2019-04-18 00:38:50,938 [salt.minion      :870 ][ERROR   ][365] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-18 00:39:40,989 [salt.minion      :870 ][ERROR   ][365] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-18 00:40:31,038 [salt.minion      :870 ][ERROR   ][365] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-18 00:41:21,082 [salt.minion      :870 ][ERROR   ][365] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-18 00:42:11,129 [salt.minion      :870 ][ERROR   ][365] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-18 00:44:19,102 [salt.utils.decorators:613 ][WARNING ][2741] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-18 00:44:19,720 [salt.utils.decorators:613 ][WARNING ][2741] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-18 00:44:21,842 [salt.loaded.int.states.file:2298][WARNING ][2878] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-18 00:44:27,338 [salt.state       :2022][WARNING ][2991] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-18 00:44:29,985 [salt.utils.decorators:613 ][WARNING ][2991] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-18 00:44:43,620 [salt.utils.decorators:613 ][WARNING ][2991] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-18 00:45:17,195 [salt.utils.decorators:613 ][WARNING ][2991] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-18 00:45:18,158 [salt.utils.decorators:613 ][WARNING ][2991] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-18 00:45:21,736 [salt.loaded.ext.module.maasng:1008][WARNING ][2991] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-04-18 00:45:21,736 [salt.loaded.ext.module.maasng:1011][WARNING ][2991] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-04-18 00:45:22,486 [salt.loaded.ext.module.maasng:1235][WARNING ][2991] Ignoring parameter vlan:0
2019-04-18 00:45:23,627 [salt.utils.decorators:613 ][WARNING ][2991] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-18 00:45:25,379 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5929] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-18 00:45:25,409 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5929] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-18 00:45:25,431 [salt.utils.parsers:1051][WARNING ][365] Minion received a SIGTERM. Exiting.
2019-04-18 00:45:26,441 [salt.cli.daemons :293 ][INFO    ][5998] Setting up the Salt Minion "mas01.mcp-fdio-noha.local"
2019-04-18 00:45:26,543 [salt.cli.daemons :82  ][INFO    ][5998] Starting up the Salt Minion
2019-04-18 00:45:26,543 [salt.utils.event :1017][INFO    ][5998] Starting pull socket on /var/run/salt/minion/minion_event_38d774b16c_pull.ipc
2019-04-18 00:45:27,279 [salt.minion      :976 ][INFO    ][5998] Creating minion process manager
2019-04-18 00:45:28,623 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5998] Executing command ['date', '+%z'] in directory '/root'
2019-04-18 00:45:28,642 [salt.utils.schedule:568 ][INFO    ][5998] Updating job settings for scheduled job: __mine_interval
2019-04-18 00:45:28,643 [salt.minion      :1108][INFO    ][5998] Added mine.update to scheduler
2019-04-18 00:45:28,647 [salt.minion      :1975][INFO    ][5998] Minion is starting as user 'root'
2019-04-18 00:45:28,658 [salt.minion      :2336][INFO    ][5998] Minion is ready to receive requests!
2019-04-18 00:45:29,351 [salt.utils.decorators:613 ][WARNING ][5936] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-18 00:45:29,421 [salt.loaded.ext.module.maas:412 ][WARNING ][5936] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-18 00:45:30,978 [salt.loaded.ext.module.maas:412 ][WARNING ][5936] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-18 00:45:32,445 [salt.loaded.ext.module.maas:412 ][WARNING ][5936] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-18 00:45:33,894 [salt.loaded.ext.module.maas:412 ][WARNING ][5936] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-18 00:46:06,197 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command state.apply with jid 20190418004606184305
2019-04-18 00:46:06,224 [salt.minion      :1432][INFO    ][6353] Starting a new job with PID 6353
2019-04-18 00:46:09,916 [salt.state       :915 ][INFO    ][6353] Loading fresh modules for state activity
2019-04-18 00:46:09,973 [salt.fileclient  :1219][INFO    ][6353] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-18 00:46:10,016 [salt.state       :1780][INFO    ][6353] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:46:10.016053
2019-04-18 00:46:10,016 [salt.state       :1813][INFO    ][6353] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-18 00:46:10,018 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6353] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-18 00:46:11,566 [salt.state       :300 ][INFO    ][6353] {'pid': 6360, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-18 00:46:11,567 [salt.state       :1951][INFO    ][6353] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:46:11.566982 duration_in_ms=1550.929
2019-04-18 00:46:11,569 [salt.state       :1780][INFO    ][6353] Running state [maas.wait_for_machine_status] at time 00:46:11.569571
2019-04-18 00:46:11,570 [salt.state       :1813][INFO    ][6353] Executing state module.run for [maas.wait_for_machine_status]
2019-04-18 00:46:11,570 [salt.utils.decorators:613 ][WARNING ][6353] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-18 00:46:12,274 [salt.loaded.ext.module.maas:1023][INFO    ][6353] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.30684996s left)
2019-04-18 00:46:21,317 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418004621300875
2019-04-18 00:46:21,344 [salt.minion      :1432][INFO    ][6371] Starting a new job with PID 6371
2019-04-18 00:46:21,365 [salt.minion      :1711][INFO    ][6371] Returning information for job: 20190418004621300875
2019-04-18 00:46:43,019 [salt.loaded.ext.module.maas:1023][INFO    ][6353] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.56150293s left)
2019-04-18 00:46:51,362 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418004651349753
2019-04-18 00:46:51,388 [salt.minion      :1432][INFO    ][6425] Starting a new job with PID 6425
2019-04-18 00:46:51,409 [salt.minion      :1711][INFO    ][6425] Returning information for job: 20190418004651349753
2019-04-18 00:47:13,974 [salt.loaded.ext.module.maas:1023][INFO    ][6353] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1437.60695601s left)
2019-04-18 00:47:21,431 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418004721419115
2019-04-18 00:47:21,454 [salt.minion      :1432][INFO    ][6548] Starting a new job with PID 6548
2019-04-18 00:47:21,465 [salt.minion      :1711][INFO    ][6548] Returning information for job: 20190418004721419115
2019-04-18 00:47:44,953 [salt.loaded.ext.module.maas:1023][INFO    ][6353] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1406.62810683s left)
2019-04-18 00:47:51,464 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418004751451825
2019-04-18 00:47:51,490 [salt.minion      :1432][INFO    ][6776] Starting a new job with PID 6776
2019-04-18 00:47:51,511 [salt.minion      :1711][INFO    ][6776] Returning information for job: 20190418004751451825
2019-04-18 00:48:16,256 [salt.loaded.ext.module.maas:1023][INFO    ][6353] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1375.32466292s left)
2019-04-18 00:48:21,537 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418004821521390
2019-04-18 00:48:21,563 [salt.minion      :1432][INFO    ][6896] Starting a new job with PID 6896
2019-04-18 00:48:21,585 [salt.minion      :1711][INFO    ][6896] Returning information for job: 20190418004821521390
2019-04-18 00:48:47,539 [salt.loaded.ext.module.maas:1023][INFO    ][6353] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1344.04151702s left)
2019-04-18 00:48:51,593 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418004851578569
2019-04-18 00:48:51,620 [salt.minion      :1432][INFO    ][7301] Starting a new job with PID 7301
2019-04-18 00:48:51,640 [salt.minion      :1711][INFO    ][7301] Returning information for job: 20190418004851578569
2019-04-18 00:49:19,977 [salt.state       :300 ][INFO    ][6353] {'ret': True}
2019-04-18 00:49:19,977 [salt.state       :1951][INFO    ][6353] Completed state [maas.wait_for_machine_status] at time 00:49:19.977897 duration_in_ms=188408.325
2019-04-18 00:49:19,981 [salt.minion      :1711][INFO    ][6353] Returning information for job: 20190418004606184305
2019-04-18 00:49:20,620 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command state.apply with jid 20190418004920606633
2019-04-18 00:49:20,646 [salt.minion      :1432][INFO    ][7439] Starting a new job with PID 7439
2019-04-18 00:49:24,344 [salt.state       :915 ][INFO    ][7439] Loading fresh modules for state activity
2019-04-18 00:49:24,398 [salt.fileclient  :1219][INFO    ][7439] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-18 00:49:24,482 [salt.state       :1780][INFO    ][7439] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:49:24.482672
2019-04-18 00:49:24,483 [salt.state       :1813][INFO    ][7439] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-18 00:49:24,484 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7439] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-18 00:49:25,875 [salt.state       :300 ][INFO    ][7439] {'pid': 7463, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-18 00:49:25,875 [salt.state       :1951][INFO    ][7439] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:49:25.875611 duration_in_ms=1392.939
2019-04-18 00:49:25,877 [salt.state       :1780][INFO    ][7439] Running state [maas_machines_storage_cmp002_lvm] at time 00:49:25.877000
2019-04-18 00:49:25,877 [salt.state       :1813][INFO    ][7439] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-18 00:49:27,106 [salt.loaded.ext.module.maasng:610 ][INFO    ][7439] sy7rkf
2019-04-18 00:49:27,106 [salt.loaded.ext.module.maasng:626 ][INFO    ][7439] sda
2019-04-18 00:49:27,730 [salt.loaded.ext.module.maasng:361 ][INFO    ][7439] sy7rkf
2019-04-18 00:49:27,871 [salt.loaded.ext.module.maasng:367 ][INFO    ][7439] [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'resource_uri': u'/MAAS/api/2.0/nodes/sy7rkf/blockdevices/4/', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'system_id': u'sy7rkf', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'dd787137-2420-4c8f-bef3-70f77c352971', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sy7rkf', u'device_id': 4, u'filesystem': {u'uuid': u'2f6522d4-5746-4c90-a675-bf3dc5b26c4e', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/sy7rkf/blockdevices/4/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sy7rkf/blockdevices/10/', u'uuid': u'ae7ac621-f851-4ead-baf2-4751feb3d0e0', u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'system_id': u'sy7rkf', u'partition_table_type': None, u'filesystem': {u'uuid': u'c963b060-d3bc-430d-94e5-db128ec7f4c6', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'partitions': [], u'used_size': 2397988454400, u'id': 10, u'serial': None, u'size': 2397988454400}]
2019-04-18 00:49:27,871 [salt.loaded.ext.module.maasng:632 ][INFO    ][7439] vgroot
2019-04-18 00:49:27,872 [salt.loaded.ext.module.maasng:635 ][INFO    ][7439] lvroot
2019-04-18 00:49:27,872 [salt.loaded.ext.module.maasng:639 ][INFO    ][7439] 107374182400
2019-04-18 00:49:28,567 [salt.loaded.ext.module.maasng:645 ][INFO    ][7439] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4kk4tq', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41', u'mode': u'dhcp', u'id': 36}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4kk4tq', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4kk4tq', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41'}], u'children': [], u'system_id': u'sy7rkf', u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/sy7rkf/interfaces/5/'}, u'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'3b087dab-0ea0-435a-84a9-8f5a2b84bf28', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sy7rkf', u'device_id': 4, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'aca8efaa-8076-47b0-98ef-6243225c0a38', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/sy7rkf/blockdevices/4/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'sy7rkf', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/sy7rkf/blockdevices/4/'}, u'fqdn': u'cmp002.maas', u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'sy7rkf', u'power_state': u'on', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"'cloudinit' running modules for final", u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'6f933bf9-4fcd-4043-b849-4a14f35f152a', u'fstype': u'ext4'}, u'uuid': u'ce0bc20b-1a24-4572-b88e-292e67e7f029', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sy7rkf', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/sy7rkf/blockdevices/11/'}], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'3b087dab-0ea0-435a-84a9-8f5a2b84bf28', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sy7rkf', u'device_id': 4, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'aca8efaa-8076-47b0-98ef-6243225c0a38', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/sy7rkf/blockdevices/4/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'sy7rkf', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/sy7rkf/blockdevices/4/'}, {u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'6f933bf9-4fcd-4043-b849-4a14f35f152a', u'fstype': u'ext4'}, u'uuid': u'ce0bc20b-1a24-4572-b88e-292e67e7f029', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sy7rkf', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/sy7rkf/blockdevices/11/'}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'raids': [], u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'3b087dab-0ea0-435a-84a9-8f5a2b84bf28', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sy7rkf', u'device_id': 4, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'aca8efaa-8076-47b0-98ef-6243225c0a38', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/sy7rkf/blockdevices/4/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'sy7rkf', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/sy7rkf/blockdevices/4/'}], u'ip_addresses': [u'192.168.11.41'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'sy7rkf', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', 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'status_action': u'modules-final', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4kk4tq', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41', u'mode': u'dhcp', u'id': 36}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4kk4tq', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4kk4tq', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41'}], u'children': [], u'system_id': u'sy7rkf', u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/sy7rkf/interfaces/5/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 37}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'sy7rkf', u'mac_address': u'00:25:b5:a0:00:6b', u'params': u'', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/sy7rkf/interfaces/17/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 38}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'sy7rkf', u'mac_address': u'00:25:b5:a0:00:6c', u'params': u'', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/sy7rkf/interfaces/18/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 39}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'sy7rkf', u'mac_address': u'00:25:b5:a0:00:6d', u'params': u'', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/sy7rkf/interfaces/19/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/sy7rkf/'}
2019-04-18 00:49:28,568 [salt.state       :300 ][INFO    ][7439] {'new': {'storage_layout': 'lvm'}}
2019-04-18 00:49:28,569 [salt.state       :1951][INFO    ][7439] Completed state [maas_machines_storage_cmp002_lvm] at time 00:49:28.569024 duration_in_ms=2692.022
2019-04-18 00:49:28,569 [salt.state       :1780][INFO    ][7439] Running state [maas_machines_storage_cmp001_lvm] at time 00:49:28.569399
2019-04-18 00:49:28,569 [salt.state       :1813][INFO    ][7439] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-18 00:49:29,544 [salt.loaded.ext.module.maasng:610 ][INFO    ][7439] xc4d8k
2019-04-18 00:49:29,544 [salt.loaded.ext.module.maasng:626 ][INFO    ][7439] sda
2019-04-18 00:49:29,928 [salt.loaded.ext.module.maasng:361 ][INFO    ][7439] xc4d8k
2019-04-18 00:49:30,009 [salt.loaded.ext.module.maasng:367 ][INFO    ][7439] [{u'size': 2397998940160, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/xc4d8k/blockdevices/1/', u'used_size': 2397998940160, u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'xc4d8k', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'1902c240-9e1f-4d3c-b167-1d415eb62728', u'resource_uri': u'/MAAS/api/2.0/nodes/xc4d8k/blockdevices/1/partition/4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xc4d8k', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'46497849-47b4-4f4f-be47-140e8c963c91', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 4, u'size': 2397992648704}]}, {u'size': 2397988454400, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/xc4d8k/blockdevices/8/', u'used_size': 2397988454400, u'tags': [], u'uuid': u'a9071b94-aa25-43aa-91f9-c8728c9a4a6b', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'xc4d8k', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'be852a79-fd3d-4c5a-b962-26857d3d65ab', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 8, u'serial': None, u'partitions': []}]
2019-04-18 00:49:30,010 [salt.loaded.ext.module.maasng:632 ][INFO    ][7439] vgroot
2019-04-18 00:49:30,011 [salt.loaded.ext.module.maasng:635 ][INFO    ][7439] lvroot
2019-04-18 00:49:30,011 [salt.loaded.ext.module.maasng:639 ][INFO    ][7439] 107374182400
2019-04-18 00:49:30,568 [salt.loaded.ext.module.maasng:645 ][INFO    ][7439] {u'hwe_kernel': u'', u'swap_size': None, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4kk4tq', 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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4kk4tq', 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.39', u'mode': u'dhcp', u'id': 27}], u'tags': [], u'effective_mtu': 1500, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4kk4tq', 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.39'}], u'parents': [], u'system_id': u'xc4d8k', u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/xc4d8k/interfaces/6/'}, u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'resource_uri': u'/MAAS/api/2.0/nodes/xc4d8k/blockdevices/1/', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'system_id': u'xc4d8k', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'33cdb465-4fd9-4fbf-902c-e0bb5cfa5630', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xc4d8k', u'device_id': 1, u'filesystem': {u'uuid': u'ab869ab1-b010-4707-84a9-4bab98f4008a', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/xc4d8k/blockdevices/1/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/xc4d8k/', u'current_commissioning_result_id': 6, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'xc4d8k', 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'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xc4d8k/blockdevices/12/', u'uuid': u'15983f48-c649-441a-9703-baaca148dfd4', u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'system_id': u'xc4d8k', u'partition_table_type': None, u'filesystem': {u'uuid': u'49efb734-6df6-457d-b4b1-f82c7d60e2aa', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'partitions': [], u'used_size': 107374182400, u'id': 12, u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'resource_uri': u'/MAAS/api/2.0/nodes/xc4d8k/blockdevices/1/', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'system_id': u'xc4d8k', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'33cdb465-4fd9-4fbf-902c-e0bb5cfa5630', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xc4d8k', u'device_id': 1, u'filesystem': {u'uuid': u'ab869ab1-b010-4707-84a9-4bab98f4008a', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/xc4d8k/blockdevices/1/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xc4d8k/blockdevices/12/', u'uuid': u'15983f48-c649-441a-9703-baaca148dfd4', u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'system_id': u'xc4d8k', u'partition_table_type': None, u'filesystem': {u'uuid': u'49efb734-6df6-457d-b4b1-f82c7d60e2aa', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'partitions': [], u'used_size': 107374182400, u'id': 12, u'serial': None, u'size': 107374182400}], 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'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'resource_uri': u'/MAAS/api/2.0/nodes/xc4d8k/blockdevices/1/', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'system_id': u'xc4d8k', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'33cdb465-4fd9-4fbf-902c-e0bb5cfa5630', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xc4d8k', u'device_id': 1, u'filesystem': {u'uuid': u'ab869ab1-b010-4707-84a9-4bab98f4008a', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/xc4d8k/blockdevices/1/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}], u'ip_addresses': [u'192.168.11.39'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'xc4d8k', u'id': 8}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', 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'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4kk4tq', 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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4kk4tq', 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.39', u'mode': u'dhcp', u'id': 27}], u'tags': [], u'effective_mtu': 1500, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4kk4tq', 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.39'}], u'parents': [], u'system_id': u'xc4d8k', u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/xc4d8k/interfaces/6/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 28}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'xc4d8k', u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'type': u'physical', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/xc4d8k/interfaces/9/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 29}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'xc4d8k', u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/xc4d8k/interfaces/11/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 30}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'xc4d8k', u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/xc4d8k/interfaces/13/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-04-18 00:49:30,570 [salt.state       :300 ][INFO    ][7439] {'new': {'storage_layout': 'lvm'}}
2019-04-18 00:49:30,571 [salt.state       :1951][INFO    ][7439] Completed state [maas_machines_storage_cmp001_lvm] at time 00:49:30.570957 duration_in_ms=2001.556
2019-04-18 00:49:30,574 [salt.minion      :1711][INFO    ][7439] Returning information for job: 20190418004920606633
2019-04-18 00:49:31,213 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command state.apply with jid 20190418004931200916
2019-04-18 00:49:31,238 [salt.minion      :1432][INFO    ][7597] Starting a new job with PID 7597
2019-04-18 00:49:31,965 [salt.state       :915 ][INFO    ][7597] Loading fresh modules for state activity
2019-04-18 00:49:32,016 [salt.fileclient  :1219][INFO    ][7597] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-18 00:49:32,057 [salt.state       :1780][INFO    ][7597] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:49:32.057914
2019-04-18 00:49:32,058 [salt.state       :1813][INFO    ][7597] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-18 00:49:32,060 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7597] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-18 00:49:33,394 [salt.state       :300 ][INFO    ][7597] {'pid': 7604, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-18 00:49:33,394 [salt.state       :1951][INFO    ][7597] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:49:33.394529 duration_in_ms=1336.616
2019-04-18 00:49:33,395 [salt.state       :1780][INFO    ][7597] Running state [maas.deploy_machines] at time 00:49:33.395587
2019-04-18 00:49:33,395 [salt.state       :1813][INFO    ][7597] Executing state module.run for [maas.deploy_machines]
2019-04-18 00:49:33,396 [salt.utils.decorators:613 ][WARNING ][7597] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-18 00:49:33,980 [salt.loaded.ext.module.maas:684 ][INFO    ][7597] deploymachines hwe_kernel=hwe-16.04 system_id=x7d7hp distro_series=xenial
2019-04-18 00:49:36,557 [salt.loaded.ext.module.maas:684 ][INFO    ][7597] deploymachines hwe_kernel=hwe-16.04 system_id=sy7rkf distro_series=xenial
2019-04-18 00:49:40,078 [salt.loaded.ext.module.maas:684 ][INFO    ][7597] deploymachines hwe_kernel=hwe-16.04 system_id=xc4d8k distro_series=xenial
2019-04-18 00:49:42,542 [salt.loaded.ext.module.maas:684 ][INFO    ][7597] deploymachines hwe_kernel=hwe-16.04 system_id=fxhpns distro_series=xenial
2019-04-18 00:49:44,657 [salt.state       :300 ][INFO    ][7597] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2019-04-18 00:49:44,658 [salt.state       :1951][INFO    ][7597] Completed state [maas.deploy_machines] at time 00:49:44.658133 duration_in_ms=11262.544
2019-04-18 00:49:44,662 [salt.minion      :1711][INFO    ][7597] Returning information for job: 20190418004931200916
2019-04-18 00:49:45,239 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command state.apply with jid 20190418004945225987
2019-04-18 00:49:45,265 [salt.minion      :1432][INFO    ][7866] Starting a new job with PID 7866
2019-04-18 00:49:48,971 [salt.state       :915 ][INFO    ][7866] Loading fresh modules for state activity
2019-04-18 00:49:48,999 [salt.fileclient  :1219][INFO    ][7866] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-18 00:49:49,026 [salt.state       :1780][INFO    ][7866] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:49:49.026224
2019-04-18 00:49:49,026 [salt.state       :1813][INFO    ][7866] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-18 00:49:49,027 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7866] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-18 00:49:50,337 [salt.state       :300 ][INFO    ][7866] {'pid': 7880, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-18 00:49:50,338 [salt.state       :1951][INFO    ][7866] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:49:50.338127 duration_in_ms=1311.902
2019-04-18 00:49:50,340 [salt.state       :1780][INFO    ][7866] Running state [maas.wait_for_machine_status] at time 00:49:50.340738
2019-04-18 00:49:50,341 [salt.state       :1813][INFO    ][7866] Executing state module.run for [maas.wait_for_machine_status]
2019-04-18 00:49:50,342 [salt.utils.decorators:613 ][WARNING ][7866] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-18 00:49:52,530 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2247.82152009s left)
2019-04-18 00:50:00,268 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418005000252283
2019-04-18 00:50:00,295 [salt.minion      :1432][INFO    ][7898] Starting a new job with PID 7898
2019-04-18 00:50:00,315 [salt.minion      :1711][INFO    ][7898] Returning information for job: 20190418005000252283
2019-04-18 00:50:24,965 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2215.38712811s left)
2019-04-18 00:50:30,323 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418005030305243
2019-04-18 00:50:30,350 [salt.minion      :1432][INFO    ][7953] Starting a new job with PID 7953
2019-04-18 00:50:30,371 [salt.minion      :1711][INFO    ][7953] Returning information for job: 20190418005030305243
2019-04-18 00:50:57,527 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2182.82485914s left)
2019-04-18 00:51:00,435 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418005100418715
2019-04-18 00:51:00,462 [salt.minion      :1432][INFO    ][7972] Starting a new job with PID 7972
2019-04-18 00:51:00,484 [salt.minion      :1711][INFO    ][7972] Returning information for job: 20190418005100418715
2019-04-18 00:51:29,492 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2150.85995007s left)
2019-04-18 00:51:30,484 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418005130471354
2019-04-18 00:51:30,510 [salt.minion      :1432][INFO    ][8182] Starting a new job with PID 8182
2019-04-18 00:51:30,531 [salt.minion      :1711][INFO    ][8182] Returning information for job: 20190418005130471354
2019-04-18 00:52:00,540 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418005200527254
2019-04-18 00:52:00,565 [salt.minion      :1432][INFO    ][8293] Starting a new job with PID 8293
2019-04-18 00:52:00,584 [salt.minion      :1711][INFO    ][8293] Returning information for job: 20190418005200527254
2019-04-18 00:52:02,281 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2118.07086515s left)
2019-04-18 00:52:30,579 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418005230572455
2019-04-18 00:52:30,596 [salt.minion      :1432][INFO    ][8545] Starting a new job with PID 8545
2019-04-18 00:52:30,607 [salt.minion      :1711][INFO    ][8545] Returning information for job: 20190418005230572455
2019-04-18 00:52:33,884 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2086.46810412s left)
2019-04-18 00:53:00,611 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418005300596703
2019-04-18 00:53:00,638 [salt.minion      :1432][INFO    ][8944] Starting a new job with PID 8944
2019-04-18 00:53:00,659 [salt.minion      :1711][INFO    ][8944] Returning information for job: 20190418005300596703
2019-04-18 00:53:06,284 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2054.06807303s left)
2019-04-18 00:53:30,670 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418005330657306
2019-04-18 00:53:30,695 [salt.minion      :1432][INFO    ][9137] Starting a new job with PID 9137
2019-04-18 00:53:30,715 [salt.minion      :1711][INFO    ][9137] Returning information for job: 20190418005330657306
2019-04-18 00:53:38,326 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2022.02564001s left)
2019-04-18 00:54:00,735 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418005400722691
2019-04-18 00:54:00,761 [salt.minion      :1432][INFO    ][9160] Starting a new job with PID 9160
2019-04-18 00:54:00,782 [salt.minion      :1711][INFO    ][9160] Returning information for job: 20190418005400722691
2019-04-18 00:54:10,831 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1989.5208261s left)
2019-04-18 00:54:30,801 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418005430792200
2019-04-18 00:54:30,820 [salt.minion      :1432][INFO    ][9473] Starting a new job with PID 9473
2019-04-18 00:54:30,833 [salt.minion      :1711][INFO    ][9473] Returning information for job: 20190418005430792200
2019-04-18 00:54:43,041 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1957.31061006s left)
2019-04-18 00:55:00,858 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418005500844739
2019-04-18 00:55:00,884 [salt.minion      :1432][INFO    ][9688] Starting a new job with PID 9688
2019-04-18 00:55:00,904 [salt.minion      :1711][INFO    ][9688] Returning information for job: 20190418005500844739
2019-04-18 00:55:15,525 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1924.82636309s left)
2019-04-18 00:55:30,930 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418005530917907
2019-04-18 00:55:30,954 [salt.minion      :1432][INFO    ][9934] Starting a new job with PID 9934
2019-04-18 00:55:30,974 [salt.minion      :1711][INFO    ][9934] Returning information for job: 20190418005530917907
2019-04-18 00:55:48,071 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1892.28117204s left)
2019-04-18 00:56:01,007 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418005600993974
2019-04-18 00:56:01,033 [salt.minion      :1432][INFO    ][9958] Starting a new job with PID 9958
2019-04-18 00:56:01,055 [salt.minion      :1711][INFO    ][9958] Returning information for job: 20190418005600993974
2019-04-18 00:56:20,579 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1859.77240109s left)
2019-04-18 00:56:31,097 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418005631084059
2019-04-18 00:56:31,123 [salt.minion      :1432][INFO    ][10072] Starting a new job with PID 10072
2019-04-18 00:56:31,144 [salt.minion      :1711][INFO    ][10072] Returning information for job: 20190418005631084059
2019-04-18 00:56:53,194 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1827.15761805s left)
2019-04-18 00:57:01,194 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418005701181207
2019-04-18 00:57:01,220 [salt.minion      :1432][INFO    ][10184] Starting a new job with PID 10184
2019-04-18 00:57:01,242 [salt.minion      :1711][INFO    ][10184] Returning information for job: 20190418005701181207
2019-04-18 00:57:25,272 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1795.07989907s left)
2019-04-18 00:57:31,287 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418005731279187
2019-04-18 00:57:31,305 [salt.minion      :1432][INFO    ][10538] Starting a new job with PID 10538
2019-04-18 00:57:31,322 [salt.minion      :1711][INFO    ][10538] Returning information for job: 20190418005731279187
2019-04-18 00:57:57,768 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1762.58401418s left)
2019-04-18 00:58:01,380 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418005801367800
2019-04-18 00:58:01,407 [salt.minion      :1432][INFO    ][10595] Starting a new job with PID 10595
2019-04-18 00:58:01,429 [salt.minion      :1711][INFO    ][10595] Returning information for job: 20190418005801367800
2019-04-18 00:58:30,116 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1730.236058s left)
2019-04-18 00:58:31,492 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418005831479725
2019-04-18 00:58:31,519 [salt.minion      :1432][INFO    ][10648] Starting a new job with PID 10648
2019-04-18 00:58:31,543 [salt.minion      :1711][INFO    ][10648] Returning information for job: 20190418005831479725
2019-04-18 00:59:01,613 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418005901600610
2019-04-18 00:59:01,639 [salt.minion      :1432][INFO    ][10666] Starting a new job with PID 10666
2019-04-18 00:59:01,661 [salt.minion      :1711][INFO    ][10666] Returning information for job: 20190418005901600610
2019-04-18 00:59:02,312 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1698.03998113s left)
2019-04-18 00:59:31,740 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418005931728059
2019-04-18 00:59:31,767 [salt.minion      :1432][INFO    ][10716] Starting a new job with PID 10716
2019-04-18 00:59:31,788 [salt.minion      :1711][INFO    ][10716] Returning information for job: 20190418005931728059
2019-04-18 00:59:34,845 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1665.50698805s left)
2019-04-18 01:00:01,872 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418010001859353
2019-04-18 01:00:01,899 [salt.minion      :1432][INFO    ][10734] Starting a new job with PID 10734
2019-04-18 01:00:01,920 [salt.minion      :1711][INFO    ][10734] Returning information for job: 20190418010001859353
2019-04-18 01:00:07,221 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1633.13049817s left)
2019-04-18 01:00:32,021 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418010032005887
2019-04-18 01:00:32,047 [salt.minion      :1432][INFO    ][10782] Starting a new job with PID 10782
2019-04-18 01:00:32,069 [salt.minion      :1711][INFO    ][10782] Returning information for job: 20190418010032005887
2019-04-18 01:00:39,777 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1600.574404s left)
2019-04-18 01:01:02,178 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418010102163031
2019-04-18 01:01:02,205 [salt.minion      :1432][INFO    ][10803] Starting a new job with PID 10803
2019-04-18 01:01:02,227 [salt.minion      :1711][INFO    ][10803] Returning information for job: 20190418010102163031
2019-04-18 01:01:12,149 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1568.2027061s left)
2019-04-18 01:01:32,340 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418010132327409
2019-04-18 01:01:32,369 [salt.minion      :1432][INFO    ][10853] Starting a new job with PID 10853
2019-04-18 01:01:32,389 [salt.minion      :1711][INFO    ][10853] Returning information for job: 20190418010132327409
2019-04-18 01:01:44,363 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1535.98858118s left)
2019-04-18 01:02:02,516 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418010202500629
2019-04-18 01:02:02,543 [salt.minion      :1432][INFO    ][10880] Starting a new job with PID 10880
2019-04-18 01:02:02,564 [salt.minion      :1711][INFO    ][10880] Returning information for job: 20190418010202500629
2019-04-18 01:02:16,590 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1503.7615881s left)
2019-04-18 01:02:32,694 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418010232681837
2019-04-18 01:02:32,721 [salt.minion      :1432][INFO    ][10933] Starting a new job with PID 10933
2019-04-18 01:02:32,742 [salt.minion      :1711][INFO    ][10933] Returning information for job: 20190418010232681837
2019-04-18 01:02:49,036 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1471.31547523s left)
2019-04-18 01:03:02,890 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418010302874938
2019-04-18 01:03:02,916 [salt.minion      :1432][INFO    ][10951] Starting a new job with PID 10951
2019-04-18 01:03:02,937 [salt.minion      :1711][INFO    ][10951] Returning information for job: 20190418010302874938
2019-04-18 01:03:21,577 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1438.7747252s left)
2019-04-18 01:03:33,087 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418010333074734
2019-04-18 01:03:33,114 [salt.minion      :1432][INFO    ][11005] Starting a new job with PID 11005
2019-04-18 01:03:33,135 [salt.minion      :1711][INFO    ][11005] Returning information for job: 20190418010333074734
2019-04-18 01:03:53,843 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1406.50875521s left)
2019-04-18 01:04:03,299 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418010403283740
2019-04-18 01:04:03,322 [salt.minion      :1432][INFO    ][11025] Starting a new job with PID 11025
2019-04-18 01:04:03,342 [salt.minion      :1711][INFO    ][11025] Returning information for job: 20190418010403283740
2019-04-18 01:04:26,149 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1374.20301819s left)
2019-04-18 01:04:33,519 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418010433507391
2019-04-18 01:04:33,544 [salt.minion      :1432][INFO    ][11214] Starting a new job with PID 11214
2019-04-18 01:04:33,564 [salt.minion      :1711][INFO    ][11214] Returning information for job: 20190418010433507391
2019-04-18 01:04:58,507 [salt.loaded.ext.module.maas:1023][INFO    ][7866] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1341.84425616s left)
2019-04-18 01:05:03,746 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418010503733644
2019-04-18 01:05:03,772 [salt.minion      :1432][INFO    ][11245] Starting a new job with PID 11245
2019-04-18 01:05:03,793 [salt.minion      :1711][INFO    ][11245] Returning information for job: 20190418010503733644
2019-04-18 01:05:30,651 [salt.loaded.ext.module.maas:993 ][INFO    ][7866] Machine fxhpns mark broken
2019-04-18 01:05:31,396 [salt.loaded.ext.module.maas:996 ][INFO    ][7866] Machine fxhpns mark fixed
2019-04-18 01:05:32,700 [salt.loaded.ext.module.maas:684 ][INFO    ][7866] deploymachines hwe_kernel=hwe-16.04 system_id=fxhpns distro_series=xenial
2019-04-18 01:05:33,776 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418010533764000
2019-04-18 01:05:33,802 [salt.minion      :1432][INFO    ][11346] Starting a new job with PID 11346
2019-04-18 01:05:33,823 [salt.minion      :1711][INFO    ][11346] Returning information for job: 20190418010533764000
2019-04-18 01:05:35,126 [salt.loaded.ext.module.maas:160 ][ERROR   ][7866] Failed for object ctl01 reason Unable to change power state to 'cycle' for node ctl01: another action is already in progress for that node.
2019-04-18 01:05:35,127 [salt.state       :302 ][ERROR   ][7866] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['gtw01', 'cmp002', 'cmp001'], 'errors': {'ctl01': "Unable to change power state to 'cycle' for node ctl01: another action is already in progress for that node."}, 'success': []}
2019-04-18 01:05:35,128 [salt.state       :1951][INFO    ][7866] Completed state [maas.wait_for_machine_status] at time 01:05:35.127934 duration_in_ms=944787.195
2019-04-18 01:05:35,145 [salt.minion      :1711][INFO    ][7866] Returning information for job: 20190418004945225987
2019-04-18 01:05:45,957 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command pillar.get with jid 20190418010545942932
2019-04-18 01:05:45,982 [salt.minion      :1432][INFO    ][11375] Starting a new job with PID 11375
2019-04-18 01:05:45,990 [salt.minion      :1711][INFO    ][11375] Returning information for job: 20190418010545942932
2019-04-18 01:05:46,479 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command service.status with jid 20190418010546467364
2019-04-18 01:05:46,504 [salt.minion      :1432][INFO    ][11380] Starting a new job with PID 11380
2019-04-18 01:05:46,931 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][11380] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-18 01:05:46,965 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][11380] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-18 01:05:46,981 [salt.minion      :1711][INFO    ][11380] Returning information for job: 20190418010546467364
2019-04-18 01:05:47,481 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command state.apply with jid 20190418010547469441
2019-04-18 01:05:47,506 [salt.minion      :1432][INFO    ][11391] Starting a new job with PID 11391
2019-04-18 01:05:51,152 [salt.state       :915 ][INFO    ][11391] Loading fresh modules for state activity
2019-04-18 01:05:51,596 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11391] Executing command 'salt-minion --version' in directory '/root'
2019-04-18 01:05:51,891 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11391] Executing command 'salt-minion --version' in directory '/root'
2019-04-18 01:05:52,721 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11391] Executing command 'salt-minion --version' in directory '/root'
2019-04-18 01:05:53,045 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11391] Executing command 'salt-minion --version' in directory '/root'
2019-04-18 01:05:54,387 [salt.state       :1780][INFO    ][11391] Running state [salt-minion] at time 01:05:54.387455
2019-04-18 01:05:54,387 [salt.state       :1813][INFO    ][11391] Executing state pkg.installed for [salt-minion]
2019-04-18 01:05:54,388 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11391] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-18 01:05:54,474 [salt.state       :300 ][INFO    ][11391] All specified packages are already installed
2019-04-18 01:05:54,474 [salt.state       :1951][INFO    ][11391] Completed state [salt-minion] at time 01:05:54.474634 duration_in_ms=87.179
2019-04-18 01:05:54,474 [salt.state       :1780][INFO    ][11391] Running state [salt_minion_dependency_packages] at time 01:05:54.474938
2019-04-18 01:05:54,475 [salt.state       :1813][INFO    ][11391] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-18 01:05:54,481 [salt.state       :300 ][INFO    ][11391] All specified packages are already installed
2019-04-18 01:05:54,481 [salt.state       :1951][INFO    ][11391] Completed state [salt_minion_dependency_packages] at time 01:05:54.481228 duration_in_ms=6.29
2019-04-18 01:05:54,484 [salt.state       :1780][INFO    ][11391] Running state [/etc/salt/minion.d/minion.conf] at time 01:05:54.484145
2019-04-18 01:05:54,484 [salt.state       :1813][INFO    ][11391] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-18 01:05:54,688 [salt.state       :300 ][INFO    ][11391] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-18 01:05:54,688 [salt.state       :1951][INFO    ][11391] Completed state [/etc/salt/minion.d/minion.conf] at time 01:05:54.688420 duration_in_ms=204.274
2019-04-18 01:05:54,692 [salt.state       :1780][INFO    ][11391] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 01:05:54.692471
2019-04-18 01:05:54,692 [salt.state       :1813][INFO    ][11391] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-18 01:05:54,704 [salt.state       :300 ][INFO    ][11391] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-18 01:05:54,704 [salt.state       :1951][INFO    ][11391] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 01:05:54.704383 duration_in_ms=11.911
2019-04-18 01:05:54,705 [salt.state       :1780][INFO    ][11391] Running state [salt-minion] at time 01:05:54.705614
2019-04-18 01:05:54,705 [salt.state       :1813][INFO    ][11391] Executing state service.running for [salt-minion]
2019-04-18 01:05:54,706 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11391] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-18 01:05:54,747 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11391] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-18 01:05:54,765 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11391] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-18 01:05:54,783 [salt.state       :300 ][INFO    ][11391] The service salt-minion is already running
2019-04-18 01:05:54,783 [salt.state       :1951][INFO    ][11391] Completed state [salt-minion] at time 01:05:54.783881 duration_in_ms=78.267
2019-04-18 01:05:54,786 [salt.state       :1780][INFO    ][11391] Running state [/etc/salt/grains.d] at time 01:05:54.786030
2019-04-18 01:05:54,786 [salt.state       :1813][INFO    ][11391] Executing state file.directory for [/etc/salt/grains.d]
2019-04-18 01:05:54,787 [salt.state       :300 ][INFO    ][11391] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-18 01:05:54,788 [salt.state       :1951][INFO    ][11391] Completed state [/etc/salt/grains.d] at time 01:05:54.788108 duration_in_ms=2.077
2019-04-18 01:05:54,789 [salt.state       :1780][INFO    ][11391] Running state [/etc/salt/grains] at time 01:05:54.789066
2019-04-18 01:05:54,789 [salt.state       :1813][INFO    ][11391] Executing state file.managed for [/etc/salt/grains]
2019-04-18 01:05:54,790 [salt.state       :300 ][INFO    ][11391] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-18 01:05:54,790 [salt.state       :1951][INFO    ][11391] Completed state [/etc/salt/grains] at time 01:05:54.790468 duration_in_ms=1.401
2019-04-18 01:05:54,791 [salt.state       :1780][INFO    ][11391] Running state [/etc/salt/grains.d/placeholder] at time 01:05:54.791082
2019-04-18 01:05:54,791 [salt.state       :1813][INFO    ][11391] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-18 01:05:54,792 [salt.state       :300 ][INFO    ][11391] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-18 01:05:54,792 [salt.state       :1951][INFO    ][11391] Completed state [/etc/salt/grains.d/placeholder] at time 01:05:54.792424 duration_in_ms=1.341
2019-04-18 01:05:54,793 [salt.state       :1780][INFO    ][11391] Running state [/etc/salt/grains.d/sphinx] at time 01:05:54.793045
2019-04-18 01:05:54,793 [salt.state       :1813][INFO    ][11391] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-18 01:05:54,807 [salt.state       :300 ][INFO    ][11391] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-18 01:05:54,808 [salt.state       :1951][INFO    ][11391] Completed state [/etc/salt/grains.d/sphinx] at time 01:05:54.807950 duration_in_ms=14.904
2019-04-18 01:05:54,810 [salt.state       :1780][INFO    ][11391] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 01:05:54.810823
2019-04-18 01:05:54,811 [salt.state       :1813][INFO    ][11391] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-18 01:05:54,811 [salt.state       :300 ][INFO    ][11391] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-18 01:05:54,812 [salt.state       :1951][INFO    ][11391] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 01:05:54.811946 duration_in_ms=1.124
2019-04-18 01:05:54,812 [salt.state       :1780][INFO    ][11391] Running state [/etc/salt/grains.d/dns_records] at time 01:05:54.812577
2019-04-18 01:05:54,812 [salt.state       :1813][INFO    ][11391] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-18 01:05:54,825 [salt.state       :300 ][INFO    ][11391] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-18 01:05:54,825 [salt.state       :1951][INFO    ][11391] Completed state [/etc/salt/grains.d/dns_records] at time 01:05:54.825800 duration_in_ms=13.223
2019-04-18 01:05:54,827 [salt.state       :1780][INFO    ][11391] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 01:05:54.827007
2019-04-18 01:05:54,827 [salt.state       :1813][INFO    ][11391] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-04-18 01:05:54,827 [salt.state       :300 ][INFO    ][11391] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-18 01:05:54,828 [salt.state       :1951][INFO    ][11391] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 01:05:54.828118 duration_in_ms=1.111
2019-04-18 01:05:54,828 [salt.state       :1780][INFO    ][11391] Running state [/etc/salt/grains.d/salt] at time 01:05:54.828742
2019-04-18 01:05:54,829 [salt.state       :1813][INFO    ][11391] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-18 01:05:54,843 [salt.state       :300 ][INFO    ][11391] File /etc/salt/grains.d/salt is in the correct state
2019-04-18 01:05:54,843 [salt.state       :1951][INFO    ][11391] Completed state [/etc/salt/grains.d/salt] at time 01:05:54.843824 duration_in_ms=15.082
2019-04-18 01:05:54,845 [salt.state       :1780][INFO    ][11391] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 01:05:54.844972
2019-04-18 01:05:54,845 [salt.state       :1813][INFO    ][11391] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-18 01:05:54,845 [salt.state       :300 ][INFO    ][11391] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-18 01:05:54,846 [salt.state       :1951][INFO    ][11391] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 01:05:54.846065 duration_in_ms=1.093
2019-04-18 01:05:54,848 [salt.state       :1780][INFO    ][11391] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 01:05:54.848570
2019-04-18 01:05:54,849 [salt.state       :1813][INFO    ][11391] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-18 01:05:54,849 [salt.state       :300 ][INFO    ][11391] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-18 01:05:54,849 [salt.state       :1951][INFO    ][11391] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 01:05:54.849674 duration_in_ms=1.104
2019-04-18 01:05:54,850 [salt.state       :1780][INFO    ][11391] Running state [mine.update] at time 01:05:54.850556
2019-04-18 01:05:54,850 [salt.state       :1813][INFO    ][11391] Executing state module.wait for [mine.update]
2019-04-18 01:05:54,851 [salt.state       :300 ][INFO    ][11391] No changes made for mine.update
2019-04-18 01:05:54,851 [salt.state       :1951][INFO    ][11391] Completed state [mine.update] at time 01:05:54.851603 duration_in_ms=1.046
2019-04-18 01:05:54,851 [salt.state       :1780][INFO    ][11391] Running state [ca-certificates] at time 01:05:54.851923
2019-04-18 01:05:54,852 [salt.state       :1813][INFO    ][11391] Executing state pkg.installed for [ca-certificates]
2019-04-18 01:05:54,862 [salt.state       :300 ][INFO    ][11391] All specified packages are already installed
2019-04-18 01:05:54,862 [salt.state       :1951][INFO    ][11391] Completed state [ca-certificates] at time 01:05:54.862319 duration_in_ms=10.396
2019-04-18 01:05:54,863 [salt.state       :1780][INFO    ][11391] Running state [update-ca-certificates] at time 01:05:54.863598
2019-04-18 01:05:54,863 [salt.state       :1813][INFO    ][11391] Executing state cmd.wait for [update-ca-certificates]
2019-04-18 01:05:54,864 [salt.state       :300 ][INFO    ][11391] No changes made for update-ca-certificates
2019-04-18 01:05:54,864 [salt.state       :1951][INFO    ][11391] Completed state [update-ca-certificates] at time 01:05:54.864628 duration_in_ms=1.03
2019-04-18 01:05:54,864 [salt.state       :1780][INFO    ][11391] Running state [iptables] at time 01:05:54.864930
2019-04-18 01:05:54,865 [salt.state       :1813][INFO    ][11391] Executing state pkg.installed for [iptables]
2019-04-18 01:05:54,873 [salt.state       :300 ][INFO    ][11391] All specified packages are already installed
2019-04-18 01:05:54,874 [salt.state       :1951][INFO    ][11391] Completed state [iptables] at time 01:05:54.873983 duration_in_ms=9.053
2019-04-18 01:05:54,874 [salt.state       :1780][INFO    ][11391] Running state [iptables-persistent] at time 01:05:54.874275
2019-04-18 01:05:54,874 [salt.state       :1813][INFO    ][11391] Executing state pkg.installed for [iptables-persistent]
2019-04-18 01:05:54,882 [salt.state       :300 ][INFO    ][11391] All specified packages are already installed
2019-04-18 01:05:54,883 [salt.state       :1951][INFO    ][11391] Completed state [iptables-persistent] at time 01:05:54.883167 duration_in_ms=8.891
2019-04-18 01:05:54,884 [salt.state       :1780][INFO    ][11391] Running state [iptables_modules_v4_load] at time 01:05:54.884326
2019-04-18 01:05:54,884 [salt.state       :1813][INFO    ][11391] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-18 01:05:54,885 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11391] Executing command 'lsmod' in directory '/root'
2019-04-18 01:05:54,910 [salt.state       :300 ][INFO    ][11391] Kernel modules iptable_filter, ip_tables are already present
2019-04-18 01:05:54,910 [salt.state       :1951][INFO    ][11391] Completed state [iptables_modules_v4_load] at time 01:05:54.910866 duration_in_ms=26.54
2019-04-18 01:05:54,911 [salt.state       :1780][INFO    ][11391] Running state [/etc/iptables/rules.v4] at time 01:05:54.911919
2019-04-18 01:05:54,912 [salt.state       :1813][INFO    ][11391] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-18 01:05:55,029 [salt.state       :300 ][INFO    ][11391] File /etc/iptables/rules.v4 is in the correct state
2019-04-18 01:05:55,029 [salt.state       :1951][INFO    ][11391] Completed state [/etc/iptables/rules.v4] at time 01:05:55.029700 duration_in_ms=117.781
2019-04-18 01:05:55,030 [salt.state       :1780][INFO    ][11391] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 01:05:55.030919
2019-04-18 01:05:55,031 [salt.state       :1813][INFO    ][11391] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-18 01:05:55,031 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11391] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-18 01:05:55,054 [salt.state       :300 ][INFO    ][11391] onlyif execution failed
2019-04-18 01:05:55,055 [salt.state       :1951][INFO    ][11391] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 01:05:55.054896 duration_in_ms=23.977
2019-04-18 01:05:55,056 [salt.state       :1780][INFO    ][11391] Running state [netfilter-persistent] at time 01:05:55.056429
2019-04-18 01:05:55,056 [salt.state       :1813][INFO    ][11391] Executing state service.running for [netfilter-persistent]
2019-04-18 01:05:55,058 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11391] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-18 01:05:55,079 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11391] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-18 01:05:55,099 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11391] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-18 01:05:55,117 [salt.state       :300 ][INFO    ][11391] The service netfilter-persistent is already running
2019-04-18 01:05:55,117 [salt.state       :1951][INFO    ][11391] Completed state [netfilter-persistent] at time 01:05:55.117590 duration_in_ms=61.161
2019-04-18 01:05:55,118 [salt.state       :1780][INFO    ][11391] Running state [iptables_extra.remove_stale_tables] at time 01:05:55.118866
2019-04-18 01:05:55,119 [salt.state       :1813][INFO    ][11391] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-18 01:05:55,119 [salt.state       :300 ][INFO    ][11391] No changes made for iptables_extra.remove_stale_tables
2019-04-18 01:05:55,120 [salt.state       :1951][INFO    ][11391] Completed state [iptables_extra.remove_stale_tables] at time 01:05:55.120204 duration_in_ms=1.338
2019-04-18 01:05:55,120 [salt.state       :1780][INFO    ][11391] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 01:05:55.120575
2019-04-18 01:05:55,121 [salt.state       :1813][INFO    ][11391] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-18 01:05:55,121 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11391] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-18 01:05:55,138 [salt.state       :300 ][INFO    ][11391] onlyif execution failed
2019-04-18 01:05:55,138 [salt.state       :1951][INFO    ][11391] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 01:05:55.138793 duration_in_ms=18.216
2019-04-18 01:05:55,140 [salt.state       :1780][INFO    ][11391] Running state [/etc/iptables/rules.v6] at time 01:05:55.140205
2019-04-18 01:05:55,140 [salt.state       :1813][INFO    ][11391] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-18 01:05:55,141 [salt.state       :300 ][INFO    ][11391] File /etc/iptables/rules.v6 is not present
2019-04-18 01:05:55,141 [salt.state       :1951][INFO    ][11391] Completed state [/etc/iptables/rules.v6] at time 01:05:55.141669 duration_in_ms=1.464
2019-04-18 01:05:55,142 [salt.state       :1780][INFO    ][11391] Running state [iptables_extra.flush_all] at time 01:05:55.142649
2019-04-18 01:05:55,143 [salt.state       :1813][INFO    ][11391] Executing state module.wait for [iptables_extra.flush_all]
2019-04-18 01:05:55,143 [salt.state       :300 ][INFO    ][11391] No changes made for iptables_extra.flush_all
2019-04-18 01:05:55,143 [salt.state       :1951][INFO    ][11391] Completed state [iptables_extra.flush_all] at time 01:05:55.143766 duration_in_ms=1.116
2019-04-18 01:05:55,147 [salt.minion      :1711][INFO    ][11391] Returning information for job: 20190418010547469441
2019-04-18 01:05:55,781 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command state.apply with jid 20190418010555769252
2019-04-18 01:05:55,808 [salt.minion      :1432][INFO    ][11483] Starting a new job with PID 11483
2019-04-18 01:05:56,463 [salt.state       :915 ][INFO    ][11483] Loading fresh modules for state activity
2019-04-18 01:05:57,117 [salt.state       :1780][INFO    ][11483] Running state [maas-rack-controller] at time 01:05:57.117285
2019-04-18 01:05:57,117 [salt.state       :1813][INFO    ][11483] Executing state pkg.installed for [maas-rack-controller]
2019-04-18 01:05:57,118 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11483] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-18 01:05:57,204 [salt.state       :300 ][INFO    ][11483] All specified packages are already installed
2019-04-18 01:05:57,204 [salt.state       :1951][INFO    ][11483] Completed state [maas-rack-controller] at time 01:05:57.204872 duration_in_ms=87.588
2019-04-18 01:05:57,205 [salt.state       :1780][INFO    ][11483] Running state [ipmitool] at time 01:05:57.205186
2019-04-18 01:05:57,205 [salt.state       :1813][INFO    ][11483] Executing state pkg.installed for [ipmitool]
2019-04-18 01:05:57,211 [salt.state       :300 ][INFO    ][11483] All specified packages are already installed
2019-04-18 01:05:57,211 [salt.state       :1951][INFO    ][11483] Completed state [ipmitool] at time 01:05:57.211632 duration_in_ms=6.446
2019-04-18 01:05:57,214 [salt.state       :1780][INFO    ][11483] Running state [/etc/maas/rackd.conf] at time 01:05:57.214375
2019-04-18 01:05:57,214 [salt.state       :1813][INFO    ][11483] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-18 01:05:57,215 [salt.state       :300 ][INFO    ][11483] No changes needed to be made
2019-04-18 01:05:57,215 [salt.state       :1951][INFO    ][11483] Completed state [/etc/maas/rackd.conf] at time 01:05:57.215801 duration_in_ms=1.426
2019-04-18 01:05:57,216 [salt.state       :1780][INFO    ][11483] Running state [/etc/maas/rackd.conf] at time 01:05:57.216010
2019-04-18 01:05:57,216 [salt.state       :1813][INFO    ][11483] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-18 01:05:57,216 [salt.loaded.int.states.file:2298][WARNING ][11483] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-18 01:05:57,216 [salt.state       :300 ][INFO    ][11483] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-18 01:05:57,217 [salt.state       :1951][INFO    ][11483] Completed state [/etc/maas/rackd.conf] at time 01:05:57.217141 duration_in_ms=1.132
2019-04-18 01:05:57,218 [salt.state       :1780][INFO    ][11483] Running state [maas-rackd] at time 01:05:57.218014
2019-04-18 01:05:57,218 [salt.state       :1813][INFO    ][11483] Executing state service.running for [maas-rackd]
2019-04-18 01:05:57,218 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11483] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-18 01:05:57,251 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11483] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-18 01:05:57,266 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11483] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-18 01:05:57,281 [salt.state       :300 ][INFO    ][11483] The service maas-rackd is already running
2019-04-18 01:05:57,281 [salt.state       :1951][INFO    ][11483] Completed state [maas-rackd] at time 01:05:57.281391 duration_in_ms=63.377
2019-04-18 01:05:57,282 [salt.minion      :1711][INFO    ][11483] Returning information for job: 20190418010555769252
2019-04-18 01:05:57,874 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command state.apply with jid 20190418010557860616
2019-04-18 01:05:57,901 [salt.minion      :1432][INFO    ][11506] Starting a new job with PID 11506
2019-04-18 01:05:58,568 [salt.state       :915 ][INFO    ][11506] Loading fresh modules for state activity
2019-04-18 01:05:59,242 [salt.state       :1780][INFO    ][11506] Running state [maas-region-controller] at time 01:05:59.242186
2019-04-18 01:05:59,242 [salt.state       :1813][INFO    ][11506] Executing state pkg.installed for [maas-region-controller]
2019-04-18 01:05:59,243 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11506] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-18 01:05:59,316 [salt.state       :300 ][INFO    ][11506] All specified packages are already installed
2019-04-18 01:05:59,317 [salt.state       :1951][INFO    ][11506] Completed state [maas-region-controller] at time 01:05:59.316997 duration_in_ms=74.81
2019-04-18 01:05:59,317 [salt.state       :1780][INFO    ][11506] Running state [python-oauth] at time 01:05:59.317229
2019-04-18 01:05:59,317 [salt.state       :1813][INFO    ][11506] Executing state pkg.installed for [python-oauth]
2019-04-18 01:05:59,321 [salt.state       :300 ][INFO    ][11506] All specified packages are already installed
2019-04-18 01:05:59,322 [salt.state       :1951][INFO    ][11506] Completed state [python-oauth] at time 01:05:59.322083 duration_in_ms=4.853
2019-04-18 01:05:59,324 [salt.state       :1780][INFO    ][11506] Running state [/etc/maas/regiond.conf] at time 01:05:59.324256
2019-04-18 01:05:59,324 [salt.state       :1813][INFO    ][11506] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-18 01:05:59,377 [salt.state       :300 ][INFO    ][11506] No changes needed to be made
2019-04-18 01:05:59,377 [salt.state       :1951][INFO    ][11506] Completed state [/etc/maas/regiond.conf] at time 01:05:59.377264 duration_in_ms=53.007
2019-04-18 01:05:59,377 [salt.state       :1780][INFO    ][11506] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 01:05:59.377667
2019-04-18 01:05:59,377 [salt.state       :1813][INFO    ][11506] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-18 01:05:59,436 [salt.state       :300 ][INFO    ][11506] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-18 01:05:59,436 [salt.state       :1951][INFO    ][11506] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 01:05:59.436808 duration_in_ms=59.141
2019-04-18 01:05:59,437 [salt.state       :1780][INFO    ][11506] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 01:05:59.437206
2019-04-18 01:05:59,437 [salt.state       :1813][INFO    ][11506] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-18 01:05:59,449 [salt.state       :300 ][INFO    ][11506] No changes needed to be made
2019-04-18 01:05:59,449 [salt.state       :1951][INFO    ][11506] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 01:05:59.449749 duration_in_ms=12.543
2019-04-18 01:05:59,450 [salt.state       :1780][INFO    ][11506] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 01:05:59.450169
2019-04-18 01:05:59,450 [salt.state       :1813][INFO    ][11506] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-18 01:05:59,473 [salt.state       :300 ][INFO    ][11506] No changes needed to be made
2019-04-18 01:05:59,473 [salt.state       :1951][INFO    ][11506] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 01:05:59.473846 duration_in_ms=23.677
2019-04-18 01:05:59,474 [salt.state       :1780][INFO    ][11506] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 01:05:59.474277
2019-04-18 01:05:59,474 [salt.state       :1813][INFO    ][11506] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-18 01:05:59,495 [salt.state       :300 ][INFO    ][11506] No changes needed to be made
2019-04-18 01:05:59,495 [salt.state       :1951][INFO    ][11506] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 01:05:59.495448 duration_in_ms=21.171
2019-04-18 01:05:59,495 [salt.state       :1780][INFO    ][11506] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 01:05:59.495868
2019-04-18 01:05:59,496 [salt.state       :1813][INFO    ][11506] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-18 01:05:59,506 [salt.state       :300 ][INFO    ][11506] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-18 01:05:59,507 [salt.state       :1951][INFO    ][11506] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 01:05:59.506975 duration_in_ms=11.107
2019-04-18 01:05:59,508 [salt.state       :1780][INFO    ][11506] Running state [a2enmod headers] at time 01:05:59.507997
2019-04-18 01:05:59,508 [salt.state       :1813][INFO    ][11506] Executing state cmd.run for [a2enmod headers]
2019-04-18 01:05:59,508 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11506] Executing command 'a2enmod headers' in directory '/root'
2019-04-18 01:05:59,580 [salt.state       :300 ][INFO    ][11506] {'pid': 11526, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-18 01:05:59,581 [salt.state       :1951][INFO    ][11506] Completed state [a2enmod headers] at time 01:05:59.581153 duration_in_ms=73.154
2019-04-18 01:05:59,581 [salt.state       :1780][INFO    ][11506] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 01:05:59.581758
2019-04-18 01:05:59,582 [salt.state       :1813][INFO    ][11506] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-18 01:05:59,602 [salt.state       :300 ][INFO    ][11506] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-18 01:05:59,602 [salt.state       :1951][INFO    ][11506] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 01:05:59.602451 duration_in_ms=20.693
2019-04-18 01:05:59,603 [salt.state       :1780][INFO    ][11506] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 01:05:59.603338
2019-04-18 01:05:59,603 [salt.state       :1813][INFO    ][11506] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-18 01:05:59,682 [salt.state       :300 ][INFO    ][11506] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-18 01:05:59,682 [salt.state       :1951][INFO    ][11506] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 01:05:59.682783 duration_in_ms=79.445
2019-04-18 01:05:59,683 [salt.state       :1780][INFO    ][11506] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 01:05:59.683394
2019-04-18 01:05:59,683 [salt.state       :1813][INFO    ][11506] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-18 01:05:59,754 [salt.state       :300 ][INFO    ][11506] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-18 01:05:59,754 [salt.state       :1951][INFO    ][11506] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 01:05:59.754687 duration_in_ms=71.294
2019-04-18 01:05:59,755 [salt.state       :1780][INFO    ][11506] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 01:05:59.755305
2019-04-18 01:05:59,755 [salt.state       :1813][INFO    ][11506] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-18 01:05:59,820 [salt.state       :300 ][INFO    ][11506] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-18 01:05:59,820 [salt.state       :1951][INFO    ][11506] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 01:05:59.820314 duration_in_ms=65.008
2019-04-18 01:05:59,820 [salt.state       :1780][INFO    ][11506] Running state [/root/.pgpass] at time 01:05:59.820609
2019-04-18 01:05:59,820 [salt.state       :1813][INFO    ][11506] Executing state file.managed for [/root/.pgpass]
2019-04-18 01:05:59,867 [salt.state       :300 ][INFO    ][11506] File /root/.pgpass is in the correct state
2019-04-18 01:05:59,868 [salt.state       :1951][INFO    ][11506] Completed state [/root/.pgpass] at time 01:05:59.868218 duration_in_ms=47.609
2019-04-18 01:05:59,873 [salt.state       :1780][INFO    ][11506] Running state [maas-region syncdb --noinput] at time 01:05:59.873245
2019-04-18 01:05:59,873 [salt.state       :1813][INFO    ][11506] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-18 01:05:59,874 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11506] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-18 01:06:01,803 [salt.state       :300 ][INFO    ][11506] {'pid': 11539, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: contenttypes, metadataserver, piston3, sessions, maasserver, auth, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-18 01:06:01,803 [salt.state       :1951][INFO    ][11506] Completed state [maas-region syncdb --noinput] at time 01:06:01.803783 duration_in_ms=1930.537
2019-04-18 01:06:01,804 [salt.state       :2022][WARNING ][11506] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-18 01:06:01,806 [salt.state       :1780][INFO    ][11506] Running state [maas-regiond] at time 01:06:01.806827
2019-04-18 01:06:01,807 [salt.state       :1813][INFO    ][11506] Executing state service.running for [maas-regiond]
2019-04-18 01:06:01,808 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11506] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-18 01:06:01,849 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11506] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-18 01:06:01,868 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11506] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-18 01:06:01,887 [salt.state       :300 ][INFO    ][11506] The service maas-regiond is already running
2019-04-18 01:06:01,888 [salt.state       :1951][INFO    ][11506] Completed state [maas-regiond] at time 01:06:01.888092 duration_in_ms=81.265
2019-04-18 01:06:01,890 [salt.state       :1780][INFO    ][11506] Running state [bind9] at time 01:06:01.890730
2019-04-18 01:06:01,891 [salt.state       :1813][INFO    ][11506] Executing state service.running for [bind9]
2019-04-18 01:06:01,892 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11506] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-18 01:06:01,913 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11506] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-18 01:06:01,931 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11506] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-18 01:06:01,949 [salt.state       :300 ][INFO    ][11506] The service bind9 is already running
2019-04-18 01:06:01,950 [salt.state       :1951][INFO    ][11506] Completed state [bind9] at time 01:06:01.950421 duration_in_ms=59.692
2019-04-18 01:06:01,953 [salt.state       :1780][INFO    ][11506] Running state [apache2] at time 01:06:01.953055
2019-04-18 01:06:01,953 [salt.state       :1813][INFO    ][11506] Executing state service.running for [apache2]
2019-04-18 01:06:01,954 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11506] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-18 01:06:01,975 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11506] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-18 01:06:01,994 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11506] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-18 01:06:02,018 [salt.state       :300 ][INFO    ][11506] The service apache2 is already running
2019-04-18 01:06:02,018 [salt.state       :1951][INFO    ][11506] Completed state [apache2] at time 01:06:02.018658 duration_in_ms=65.602
2019-04-18 01:06:02,021 [salt.state       :1780][INFO    ][11506] Running state [maasng.wait_for_http_code] at time 01:06:02.021381
2019-04-18 01:06:02,021 [salt.state       :1813][INFO    ][11506] Executing state module.run for [maasng.wait_for_http_code]
2019-04-18 01:06:02,022 [salt.utils.decorators:613 ][WARNING ][11506] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-18 01:06:02,191 [salt.state       :300 ][INFO    ][11506] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-18 01:06:02,192 [salt.state       :1951][INFO    ][11506] Completed state [maasng.wait_for_http_code] at time 01:06:02.192367 duration_in_ms=170.986
2019-04-18 01:06:02,193 [salt.state       :1780][INFO    ][11506] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 01:06:02.193692
2019-04-18 01:06:02,194 [salt.state       :1813][INFO    ][11506] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-18 01:06:02,194 [salt.state       :300 ][INFO    ][11506] /var/lib/maas/.setup_admin exists
2019-04-18 01:06:02,195 [salt.state       :1951][INFO    ][11506] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 01:06:02.195211 duration_in_ms=1.519
2019-04-18 01:06:02,196 [salt.state       :1780][INFO    ][11506] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:06:02.196310
2019-04-18 01:06:02,196 [salt.state       :1813][INFO    ][11506] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-18 01:06:02,197 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11506] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-18 01:06:03,622 [salt.state       :300 ][INFO    ][11506] {'pid': 11560, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-18 01:06:03,623 [salt.state       :1951][INFO    ][11506] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:06:03.623255 duration_in_ms=1426.944
2019-04-18 01:06:03,634 [salt.state       :1780][INFO    ][11506] Running state [maas_region_boot_source_resources_mirror] at time 01:06:03.634250
2019-04-18 01:06:03,634 [salt.state       :1813][INFO    ][11506] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-18 01:06:03,743 [salt.state       :300 ][INFO    ][11506] {'changes': {}}
2019-04-18 01:06:03,743 [salt.state       :1951][INFO    ][11506] Completed state [maas_region_boot_source_resources_mirror] at time 01:06:03.743886 duration_in_ms=109.636
2019-04-18 01:06:03,745 [salt.state       :1780][INFO    ][11506] Running state [maasng.boot_resources_import] at time 01:06:03.744974
2019-04-18 01:06:03,745 [salt.state       :1813][INFO    ][11506] Executing state module.run for [maasng.boot_resources_import]
2019-04-18 01:06:03,746 [salt.utils.decorators:613 ][WARNING ][11506] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-18 01:06:03,858 [salt.loaded.ext.module.maasng:1600][INFO    ][11506] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-18 01:06:08,929 [salt.loaded.ext.module.maasng:1600][INFO    ][11506] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-18 01:06:12,956 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418010612943967
2019-04-18 01:06:12,974 [salt.minion      :1432][INFO    ][11586] Starting a new job with PID 11586
2019-04-18 01:06:12,995 [salt.minion      :1711][INFO    ][11586] Returning information for job: 20190418010612943967
2019-04-18 01:06:13,985 [salt.loaded.ext.module.maasng:1600][INFO    ][11506] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-04-18 01:06:19,107 [salt.state       :300 ][INFO    ][11506] {'ret': True}
2019-04-18 01:06:19,107 [salt.state       :1951][INFO    ][11506] Completed state [maasng.boot_resources_import] at time 01:06:19.107638 duration_in_ms=15362.663
2019-04-18 01:06:19,108 [salt.state       :1780][INFO    ][11506] Running state [maas_region_boot_sources_selection_xenial] at time 01:06:19.108758
2019-04-18 01:06:19,109 [salt.state       :1813][INFO    ][11506] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-18 01:06:19,309 [salt.state       :300 ][INFO    ][11506] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-18 01:06:19,309 [salt.state       :1951][INFO    ][11506] Completed state [maas_region_boot_sources_selection_xenial] at time 01:06:19.309686 duration_in_ms=200.928
2019-04-18 01:06:19,311 [salt.state       :1780][INFO    ][11506] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 01:06:19.311057
2019-04-18 01:06:19,311 [salt.state       :1813][INFO    ][11506] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-18 01:06:19,312 [salt.utils.decorators:613 ][WARNING ][11506] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-18 01:06:19,312 [salt.loaded.ext.module.maasng:1771][INFO    ][11506] boot-sources sync initiated for ALL Rack's
2019-04-18 01:06:20,303 [salt.state       :300 ][INFO    ][11506] {'ret': True}
2019-04-18 01:06:20,303 [salt.state       :1951][INFO    ][11506] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 01:06:20.303536 duration_in_ms=992.478
2019-04-18 01:06:20,305 [salt.state       :1780][INFO    ][11506] Running state [maas.process_maas_config] at time 01:06:20.305681
2019-04-18 01:06:20,306 [salt.state       :1813][INFO    ][11506] Executing state module.run for [maas.process_maas_config]
2019-04-18 01:06:20,306 [salt.utils.decorators:613 ][WARNING ][11506] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-18 01:06:20,307 [salt.loaded.ext.module.maas:92  ][INFO    ][11506] maasconfig name=enable_http_proxy value=True
2019-04-18 01:06:20,359 [salt.loaded.ext.module.maas:92  ][INFO    ][11506] maasconfig name=upstream_dns value=8.8.8.8
2019-04-18 01:06:20,417 [salt.loaded.ext.module.maas:92  ][INFO    ][11506] maasconfig name=commissioning_distro_series value=xenial
2019-04-18 01:06:23,498 [salt.loaded.ext.module.maas:92  ][INFO    ][11506] maasconfig name=default_osystem value=ubuntu
2019-04-18 01:06:23,559 [salt.loaded.ext.module.maas:92  ][INFO    ][11506] maasconfig name=active_discovery_interval value=600
2019-04-18 01:06:23,607 [salt.loaded.ext.module.maas:92  ][INFO    ][11506] maasconfig name=dnssec_validation value=no
2019-04-18 01:06:23,661 [salt.loaded.ext.module.maas:92  ][INFO    ][11506] maasconfig name=maas_name value=mas01
2019-04-18 01:06:23,730 [salt.loaded.ext.module.maas:92  ][INFO    ][11506] maasconfig name=network_discovery value=enabled
2019-04-18 01:06:23,863 [salt.loaded.ext.module.maas:92  ][INFO    ][11506] maasconfig name=enable_third_party_drivers value=True
2019-04-18 01:06:23,925 [salt.loaded.ext.module.maas:92  ][INFO    ][11506] maasconfig name=default_storage_layout value=lvm
2019-04-18 01:06:23,986 [salt.loaded.ext.module.maas:92  ][INFO    ][11506] maasconfig name=ntp_external_only value=True
2019-04-18 01:06:24,040 [salt.loaded.ext.module.maas:92  ][INFO    ][11506] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-18 01:06:24,094 [salt.loaded.ext.module.maas:92  ][INFO    ][11506] maasconfig name=default_distro_series value=xenial
2019-04-18 01:06:24,181 [salt.loaded.ext.module.maas:92  ][INFO    ][11506] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-04-18 01:06:24,347 [salt.state       :300 ][INFO    ][11506] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-04-18 01:06:24,348 [salt.state       :1951][INFO    ][11506] Completed state [maas.process_maas_config] at time 01:06:24.348321 duration_in_ms=4042.639
2019-04-18 01:06:24,349 [salt.state       :1780][INFO    ][11506] Running state [pxe_admin] at time 01:06:24.349638
2019-04-18 01:06:24,350 [salt.state       :1813][INFO    ][11506] Executing state maasng.fabric_present for [pxe_admin]
2019-04-18 01:06:24,430 [salt.loaded.ext.module.maasng:945 ][INFO    ][11506] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4kk4tq', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin', u'class_type': u''}]
2019-04-18 01:06:24,511 [salt.loaded.ext.module.maasng:1008][WARNING ][11506] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-18 01:06:24,511 [salt.loaded.ext.module.maasng:1011][WARNING ][11506] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-18 01:06:24,571 [salt.state       :300 ][INFO    ][11506] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-18 01:06:24,571 [salt.state       :1951][INFO    ][11506] Completed state [pxe_admin] at time 01:06:24.571429 duration_in_ms=221.79
2019-04-18 01:06:24,571 [salt.state       :1780][INFO    ][11506] Running state [vlan 0] at time 01:06:24.571928
2019-04-18 01:06:24,572 [salt.state       :1813][INFO    ][11506] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-18 01:06:24,618 [salt.loaded.ext.module.maasng:945 ][INFO    ][11506] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4kk4tq', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-04-18 01:06:24,746 [salt.loaded.ext.module.maasng:945 ][INFO    ][11506] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4kk4tq', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin', u'class_type': u''}]
2019-04-18 01:06:25,004 [salt.loaded.ext.module.maasng:945 ][INFO    ][11506] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'4kk4tq', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-18 01:06:25,089 [salt.state       :300 ][INFO    ][11506] {'new': 'Vlan untagged was updated'}
2019-04-18 01:06:25,089 [salt.state       :1951][INFO    ][11506] Completed state [vlan 0] at time 01:06:25.089647 duration_in_ms=517.718
2019-04-18 01:06:25,091 [salt.state       :1780][INFO    ][11506] Running state [192.168.11.0/24] at time 01:06:25.091185
2019-04-18 01:06:25,091 [salt.state       :1813][INFO    ][11506] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-18 01:06:25,334 [salt.loaded.ext.module.maasng:945 ][INFO    ][11506] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'4kk4tq', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-18 01:06:25,335 [salt.loaded.ext.module.maasng:1235][WARNING ][11506] Ignoring parameter vlan:0
2019-04-18 01:06:25,428 [salt.state       :300 ][INFO    ][11506] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-18 01:06:25,429 [salt.state       :1951][INFO    ][11506] Completed state [192.168.11.0/24] at time 01:06:25.428927 duration_in_ms=337.743
2019-04-18 01:06:25,430 [salt.state       :1780][INFO    ][11506] Running state [maas_create_iprange_1] at time 01:06:25.430099
2019-04-18 01:06:25,430 [salt.state       :1813][INFO    ][11506] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-18 01:06:25,493 [salt.state       :300 ][INFO    ][11506] Iprange maas_create_iprange_1 already exist.
2019-04-18 01:06:25,493 [salt.state       :1951][INFO    ][11506] Completed state [maas_create_iprange_1] at time 01:06:25.493726 duration_in_ms=63.626
2019-04-18 01:06:25,493 [salt.state       :1780][INFO    ][11506] Running state [vlan 0] at time 01:06:25.493946
2019-04-18 01:06:25,494 [salt.state       :1813][INFO    ][11506] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-18 01:06:25,560 [salt.loaded.ext.module.maasng:945 ][INFO    ][11506] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4kk4tq', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin', u'class_type': u''}]
2019-04-18 01:06:25,679 [salt.loaded.ext.module.maasng:945 ][INFO    ][11506] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'4kk4tq', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-18 01:06:25,951 [salt.loaded.ext.module.maasng:945 ][INFO    ][11506] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4kk4tq', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-18 01:06:26,035 [salt.state       :300 ][INFO    ][11506] {'new': 'Vlan untagged was updated'}
2019-04-18 01:06:26,035 [salt.state       :1951][INFO    ][11506] Completed state [vlan 0] at time 01:06:26.035674 duration_in_ms=541.725
2019-04-18 01:06:26,036 [salt.state       :1780][INFO    ][11506] Running state [opnfv] at time 01:06:26.036831
2019-04-18 01:06:26,037 [salt.state       :1813][INFO    ][11506] Executing state maasng.sshkey_present for [opnfv]
2019-04-18 01:06:26,088 [salt.loaded.ext.module.maasng:1903][INFO    ][11506] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'keysource': u''}]
2019-04-18 01:06:26,089 [salt.state       :300 ][INFO    ][11506] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-04-18 01:06:26,089 [salt.state       :1951][INFO    ][11506] Completed state [opnfv] at time 01:06:26.089533 duration_in_ms=52.703
2019-04-18 01:06:26,090 [salt.state       :1780][INFO    ][11506] Running state [maas.process_tags] at time 01:06:26.090393
2019-04-18 01:06:26,090 [salt.state       :1813][INFO    ][11506] Executing state module.run for [maas.process_tags]
2019-04-18 01:06:26,091 [salt.utils.decorators:613 ][WARNING ][11506] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-18 01:06:26,142 [salt.loaded.ext.module.maas:92  ][INFO    ][11506] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-04-18 01:06:26,238 [salt.state       :300 ][INFO    ][11506] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-04-18 01:06:26,238 [salt.state       :1951][INFO    ][11506] Completed state [maas.process_tags] at time 01:06:26.238466 duration_in_ms=148.072
2019-04-18 01:06:26,243 [salt.minion      :1711][INFO    ][11506] Returning information for job: 20190418010557860616
2019-04-18 01:06:26,799 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command state.apply with jid 20190418010626785706
2019-04-18 01:06:26,821 [salt.minion      :1432][INFO    ][11962] Starting a new job with PID 11962
2019-04-18 01:06:30,565 [salt.state       :915 ][INFO    ][11962] Loading fresh modules for state activity
2019-04-18 01:06:30,657 [salt.state       :1780][INFO    ][11962] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:06:30.657216
2019-04-18 01:06:30,657 [salt.state       :1813][INFO    ][11962] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-18 01:06:30,659 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11962] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-18 01:06:32,177 [salt.state       :300 ][INFO    ][11962] {'pid': 12014, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-18 01:06:32,178 [salt.state       :1951][INFO    ][11962] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:06:32.178830 duration_in_ms=1521.613
2019-04-18 01:06:32,181 [salt.state       :1780][INFO    ][11962] Running state [maas.process_machines] at time 01:06:32.181447
2019-04-18 01:06:32,182 [salt.state       :1813][INFO    ][11962] Executing state module.run for [maas.process_machines]
2019-04-18 01:06:32,183 [salt.utils.decorators:613 ][WARNING ][11962] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-18 01:06:32,818 [salt.loaded.ext.module.maas:412 ][WARNING ][11962] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-18 01:06:32,819 [salt.loaded.ext.module.maas:92  ][INFO    ][11962] 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=x7d7hp architecture=amd64/generic power_parameters_power_user=admin
2019-04-18 01:06:34,107 [salt.loaded.ext.module.maas:412 ][WARNING ][11962] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-18 01:06:34,108 [salt.loaded.ext.module.maas:92  ][INFO    ][11962] 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=sy7rkf architecture=amd64/generic power_parameters_power_user=admin
2019-04-18 01:06:35,415 [salt.loaded.ext.module.maas:412 ][WARNING ][11962] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-18 01:06:35,416 [salt.loaded.ext.module.maas:92  ][INFO    ][11962] 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=xc4d8k architecture=amd64/generic power_parameters_power_user=admin
2019-04-18 01:06:36,684 [salt.loaded.ext.module.maas:412 ][WARNING ][11962] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-18 01:06:36,684 [salt.loaded.ext.module.maas:92  ][INFO    ][11962] 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=fxhpns architecture=amd64/generic power_parameters_power_user=admin
2019-04-18 01:06:37,955 [salt.state       :300 ][INFO    ][11962] {'ret': {'updated': ['gtw01', 'cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2019-04-18 01:06:37,956 [salt.state       :1951][INFO    ][11962] Completed state [maas.process_machines] at time 01:06:37.956337 duration_in_ms=5774.889
2019-04-18 01:06:37,960 [salt.minion      :1711][INFO    ][11962] Returning information for job: 20190418010626785706
2019-04-18 01:07:10,730 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command state.apply with jid 20190418010710717642
2019-04-18 01:07:10,757 [salt.minion      :1432][INFO    ][12199] Starting a new job with PID 12199
2019-04-18 01:07:14,323 [salt.state       :915 ][INFO    ][12199] Loading fresh modules for state activity
2019-04-18 01:07:14,411 [salt.state       :1780][INFO    ][12199] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:07:14.411023
2019-04-18 01:07:14,411 [salt.state       :1813][INFO    ][12199] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-18 01:07:14,413 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12199] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-18 01:07:15,844 [salt.state       :300 ][INFO    ][12199] {'pid': 12207, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-18 01:07:15,845 [salt.state       :1951][INFO    ][12199] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:07:15.845581 duration_in_ms=1434.559
2019-04-18 01:07:15,848 [salt.state       :1780][INFO    ][12199] Running state [maas.wait_for_machine_status] at time 01:07:15.848676
2019-04-18 01:07:15,849 [salt.state       :1813][INFO    ][12199] Executing state module.run for [maas.wait_for_machine_status]
2019-04-18 01:07:15,849 [salt.utils.decorators:613 ][WARNING ][12199] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-18 01:07:17,685 [salt.loaded.ext.module.maas:993 ][INFO    ][12199] Machine fxhpns mark broken
2019-04-18 01:07:18,281 [salt.loaded.ext.module.maas:996 ][INFO    ][12199] Machine fxhpns mark fixed
2019-04-18 01:07:19,063 [salt.loaded.ext.module.maas:684 ][INFO    ][12199] deploymachines hwe_kernel=hwe-16.04 system_id=fxhpns distro_series=xenial
2019-04-18 01:07:21,892 [salt.loaded.ext.module.maas:1023][INFO    ][12199] Waiting status:Ready|Deployed for machines:['ctl01']
sleep for:30s Timeout:1500s (1493.96590495s left)
2019-04-18 01:07:25,837 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418010725785877
2019-04-18 01:07:25,863 [salt.minion      :1432][INFO    ][12289] Starting a new job with PID 12289
2019-04-18 01:07:25,886 [salt.minion      :1711][INFO    ][12289] Returning information for job: 20190418010725785877
2019-04-18 01:07:54,152 [salt.loaded.ext.module.maas:1023][INFO    ][12199] Waiting status:Ready|Deployed for machines:['ctl01']
sleep for:30s Timeout:1500s (1461.70672584s left)
2019-04-18 01:07:55,888 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418010755875652
2019-04-18 01:07:55,914 [salt.minion      :1432][INFO    ][12339] Starting a new job with PID 12339
2019-04-18 01:07:55,939 [salt.minion      :1711][INFO    ][12339] Returning information for job: 20190418010755875652
2019-04-18 01:08:25,937 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418010825923888
2019-04-18 01:08:25,964 [salt.minion      :1432][INFO    ][12372] Starting a new job with PID 12372
2019-04-18 01:08:25,987 [salt.minion      :1711][INFO    ][12372] Returning information for job: 20190418010825923888
2019-04-18 01:08:26,465 [salt.loaded.ext.module.maas:1023][INFO    ][12199] Waiting status:Ready|Deployed for machines:['ctl01']
sleep for:30s Timeout:1500s (1429.39275098s left)
2019-04-18 01:08:55,987 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418010855974804
2019-04-18 01:08:56,014 [salt.minion      :1432][INFO    ][12418] Starting a new job with PID 12418
2019-04-18 01:08:56,037 [salt.minion      :1711][INFO    ][12418] Returning information for job: 20190418010855974804
2019-04-18 01:08:58,709 [salt.loaded.ext.module.maas:1023][INFO    ][12199] Waiting status:Ready|Deployed for machines:['ctl01']
sleep for:30s Timeout:1500s (1397.14969397s left)
2019-04-18 01:09:26,035 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418010926029893
2019-04-18 01:09:26,056 [salt.minion      :1432][INFO    ][12497] Starting a new job with PID 12497
2019-04-18 01:09:26,080 [salt.minion      :1711][INFO    ][12497] Returning information for job: 20190418010926029893
2019-04-18 01:09:30,691 [salt.loaded.ext.module.maas:1023][INFO    ][12199] Waiting status:Ready|Deployed for machines:['ctl01']
sleep for:30s Timeout:1500s (1365.16716194s left)
2019-04-18 01:09:56,087 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418010956074288
2019-04-18 01:09:56,113 [salt.minion      :1432][INFO    ][12622] Starting a new job with PID 12622
2019-04-18 01:09:56,137 [salt.minion      :1711][INFO    ][12622] Returning information for job: 20190418010956074288
2019-04-18 01:10:03,183 [salt.loaded.ext.module.maas:1023][INFO    ][12199] Waiting status:Ready|Deployed for machines:['ctl01']
sleep for:30s Timeout:1500s (1332.67524099s left)
2019-04-18 01:10:26,150 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418011026137421
2019-04-18 01:10:26,177 [salt.minion      :1432][INFO    ][12650] Starting a new job with PID 12650
2019-04-18 01:10:26,203 [salt.minion      :1711][INFO    ][12650] Returning information for job: 20190418011026137421
2019-04-18 01:10:35,579 [salt.loaded.ext.module.maas:1023][INFO    ][12199] Waiting status:Ready|Deployed for machines:['ctl01']
sleep for:30s Timeout:1500s (1300.27889705s left)
2019-04-18 01:10:56,218 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418011056205742
2019-04-18 01:10:56,246 [salt.minion      :1432][INFO    ][12772] Starting a new job with PID 12772
2019-04-18 01:10:56,269 [salt.minion      :1711][INFO    ][12772] Returning information for job: 20190418011056205742
2019-04-18 01:11:08,281 [salt.loaded.ext.module.maas:1023][INFO    ][12199] Waiting status:Ready|Deployed for machines:['ctl01']
sleep for:30s Timeout:1500s (1267.57762194s left)
2019-04-18 01:11:26,285 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418011126272318
2019-04-18 01:11:26,311 [salt.minion      :1432][INFO    ][12802] Starting a new job with PID 12802
2019-04-18 01:11:26,334 [salt.minion      :1711][INFO    ][12802] Returning information for job: 20190418011126272318
2019-04-18 01:11:40,854 [salt.loaded.ext.module.maas:1023][INFO    ][12199] Waiting status:Ready|Deployed for machines:['ctl01']
sleep for:30s Timeout:1500s (1235.00402498s left)
2019-04-18 01:11:56,361 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418011156348688
2019-04-18 01:11:56,388 [salt.minion      :1432][INFO    ][12891] Starting a new job with PID 12891
2019-04-18 01:11:56,413 [salt.minion      :1711][INFO    ][12891] Returning information for job: 20190418011156348688
2019-04-18 01:12:13,400 [salt.loaded.ext.module.maas:1023][INFO    ][12199] Waiting status:Ready|Deployed for machines:['ctl01']
sleep for:30s Timeout:1500s (1202.45861888s left)
2019-04-18 01:12:26,438 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418011226425916
2019-04-18 01:12:26,465 [salt.minion      :1432][INFO    ][12919] Starting a new job with PID 12919
2019-04-18 01:12:26,488 [salt.minion      :1711][INFO    ][12919] Returning information for job: 20190418011226425916
2019-04-18 01:12:45,677 [salt.loaded.ext.module.maas:1023][INFO    ][12199] Waiting status:Ready|Deployed for machines:['ctl01']
sleep for:30s Timeout:1500s (1170.18151498s left)
2019-04-18 01:12:56,521 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418011256508500
2019-04-18 01:12:56,548 [salt.minion      :1432][INFO    ][13053] Starting a new job with PID 13053
2019-04-18 01:12:56,570 [salt.minion      :1711][INFO    ][13053] Returning information for job: 20190418011256508500
2019-04-18 01:13:18,156 [salt.loaded.ext.module.maas:1023][INFO    ][12199] Waiting status:Ready|Deployed for machines:['ctl01']
sleep for:30s Timeout:1500s (1137.70212197s left)
2019-04-18 01:13:26,607 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418011326594296
2019-04-18 01:13:26,634 [salt.minion      :1432][INFO    ][13082] Starting a new job with PID 13082
2019-04-18 01:13:26,657 [salt.minion      :1711][INFO    ][13082] Returning information for job: 20190418011326594296
2019-04-18 01:13:50,706 [salt.loaded.ext.module.maas:1023][INFO    ][12199] Waiting status:Ready|Deployed for machines:['ctl01']
sleep for:30s Timeout:1500s (1105.15245891s left)
2019-04-18 01:13:56,700 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418011356688309
2019-04-18 01:13:56,728 [salt.minion      :1432][INFO    ][13197] Starting a new job with PID 13197
2019-04-18 01:13:56,751 [salt.minion      :1711][INFO    ][13197] Returning information for job: 20190418011356688309
2019-04-18 01:14:23,137 [salt.loaded.ext.module.maas:1023][INFO    ][12199] Waiting status:Ready|Deployed for machines:['ctl01']
sleep for:30s Timeout:1500s (1072.72147703s left)
2019-04-18 01:14:26,802 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command saltutil.find_job with jid 20190418011426788641
2019-04-18 01:14:26,829 [salt.minion      :1432][INFO    ][13252] Starting a new job with PID 13252
2019-04-18 01:14:26,852 [salt.minion      :1711][INFO    ][13252] Returning information for job: 20190418011426788641
2019-04-18 01:14:55,091 [salt.state       :300 ][INFO    ][12199] {'ret': True}
2019-04-18 01:14:55,092 [salt.state       :1951][INFO    ][12199] Completed state [maas.wait_for_machine_status] at time 01:14:55.092004 duration_in_ms=459243.327
2019-04-18 01:14:55,095 [salt.minion      :1711][INFO    ][12199] Returning information for job: 20190418010710717642
2019-04-18 01:14:55,751 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command state.apply with jid 20190418011455738848
2019-04-18 01:14:55,778 [salt.minion      :1432][INFO    ][13341] Starting a new job with PID 13341
2019-04-18 01:14:59,532 [salt.state       :915 ][INFO    ][13341] Loading fresh modules for state activity
2019-04-18 01:14:59,669 [salt.state       :1780][INFO    ][13341] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:14:59.669211
2019-04-18 01:14:59,669 [salt.state       :1813][INFO    ][13341] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-18 01:14:59,671 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13341] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-18 01:15:01,156 [salt.state       :300 ][INFO    ][13341] {'pid': 13349, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-18 01:15:01,157 [salt.state       :1951][INFO    ][13341] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:15:01.157317 duration_in_ms=1488.105
2019-04-18 01:15:01,160 [salt.state       :1780][INFO    ][13341] Running state [maas_machines_storage_cmp002_lvm] at time 01:15:01.160513
2019-04-18 01:15:01,161 [salt.state       :1813][INFO    ][13341] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-18 01:15:01,696 [salt.state       :300 ][INFO    ][13341] Machine cmp002 is not in Ready state.
2019-04-18 01:15:01,696 [salt.state       :1951][INFO    ][13341] Completed state [maas_machines_storage_cmp002_lvm] at time 01:15:01.696504 duration_in_ms=535.99
2019-04-18 01:15:01,697 [salt.state       :1780][INFO    ][13341] Running state [maas_machines_storage_cmp001_lvm] at time 01:15:01.697066
2019-04-18 01:15:01,697 [salt.state       :1813][INFO    ][13341] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-18 01:15:02,158 [salt.state       :300 ][INFO    ][13341] Machine cmp001 is not in Ready state.
2019-04-18 01:15:02,158 [salt.state       :1951][INFO    ][13341] Completed state [maas_machines_storage_cmp001_lvm] at time 01:15:02.158618 duration_in_ms=461.552
2019-04-18 01:15:02,162 [salt.minion      :1711][INFO    ][13341] Returning information for job: 20190418011455738848
2019-04-18 01:15:02,778 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command state.apply with jid 20190418011502767056
2019-04-18 01:15:02,806 [salt.minion      :1432][INFO    ][13370] Starting a new job with PID 13370
2019-04-18 01:15:03,498 [salt.state       :915 ][INFO    ][13370] Loading fresh modules for state activity
2019-04-18 01:15:03,545 [salt.state       :1780][INFO    ][13370] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:15:03.545946
2019-04-18 01:15:03,546 [salt.state       :1813][INFO    ][13370] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-18 01:15:03,547 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13370] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-18 01:15:04,970 [salt.state       :300 ][INFO    ][13370] {'pid': 13377, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-18 01:15:04,971 [salt.state       :1951][INFO    ][13370] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:15:04.971333 duration_in_ms=1425.386
2019-04-18 01:15:04,973 [salt.state       :1780][INFO    ][13370] Running state [maas.deploy_machines] at time 01:15:04.973884
2019-04-18 01:15:04,974 [salt.state       :1813][INFO    ][13370] Executing state module.run for [maas.deploy_machines]
2019-04-18 01:15:04,975 [salt.utils.decorators:613 ][WARNING ][13370] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-18 01:15:05,588 [salt.state       :300 ][INFO    ][13370] {'ret': {'updated': ['gtw01', 'cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2019-04-18 01:15:05,589 [salt.state       :1951][INFO    ][13370] Completed state [maas.deploy_machines] at time 01:15:05.589159 duration_in_ms=615.273
2019-04-18 01:15:05,592 [salt.minion      :1711][INFO    ][13370] Returning information for job: 20190418011502767056
2019-04-18 01:15:06,215 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command state.apply with jid 20190418011506200842
2019-04-18 01:15:06,242 [salt.minion      :1432][INFO    ][13386] Starting a new job with PID 13386
2019-04-18 01:15:06,931 [salt.state       :915 ][INFO    ][13386] Loading fresh modules for state activity
2019-04-18 01:15:07,009 [salt.state       :1780][INFO    ][13386] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:15:07.008930
2019-04-18 01:15:07,009 [salt.state       :1813][INFO    ][13386] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-18 01:15:07,011 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13386] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-18 01:15:08,399 [salt.state       :300 ][INFO    ][13386] {'pid': 13393, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-18 01:15:08,400 [salt.state       :1951][INFO    ][13386] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:15:08.400282 duration_in_ms=1391.353
2019-04-18 01:15:08,401 [salt.state       :1780][INFO    ][13386] Running state [maas.wait_for_machine_status] at time 01:15:08.401450
2019-04-18 01:15:08,401 [salt.state       :1813][INFO    ][13386] Executing state module.run for [maas.wait_for_machine_status]
2019-04-18 01:15:08,401 [salt.utils.decorators:613 ][WARNING ][13386] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-18 01:15:10,905 [salt.state       :300 ][INFO    ][13386] {'ret': True}
2019-04-18 01:15:10,906 [salt.state       :1951][INFO    ][13386] Completed state [maas.wait_for_machine_status] at time 01:15:10.906216 duration_in_ms=2504.763
2019-04-18 01:15:10,910 [salt.minion      :1711][INFO    ][13386] Returning information for job: 20190418011506200842
2019-04-18 01:45:29,661 [salt.utils.schedule:1377][INFO    ][5998] Running scheduled job: __mine_interval
2019-04-18 02:05:31,544 [salt.minion      :1308][INFO    ][5998] User sudo_ubuntu Executing command cp.push_dir with jid 20190418020531531459
2019-04-18 02:05:31,571 [salt.minion      :1432][INFO    ][17341] Starting a new job with PID 17341
