2019-05-14 05:02:26,040 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-14 05:03:16,097 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-14 05:04:06,149 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-14 05:04:56,190 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-14 05:05:46,242 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-14 05:07:55,331 [salt.utils.decorators:613 ][WARNING ][2699] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-14 05:07:55,942 [salt.utils.decorators:613 ][WARNING ][2699] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-14 05:07:57,873 [salt.loaded.int.states.file:2298][WARNING ][2838] 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-05-14 05:08:03,707 [salt.state       :2022][WARNING ][2947] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-14 05:08:06,261 [salt.utils.decorators:613 ][WARNING ][2947] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-14 05:23:13,117 [salt.utils.decorators:613 ][WARNING ][2947] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-14 05:53:25,569 [salt.utils.decorators:613 ][WARNING ][2947] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-14 06:08:30,554 [salt.utils.decorators:613 ][WARNING ][2947] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-14 06:08:34,851 [salt.loaded.ext.module.maasng:1008][WARNING ][2947] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-05-14 06:08:34,852 [salt.loaded.ext.module.maasng:1011][WARNING ][2947] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-05-14 06:08:35,529 [salt.loaded.ext.module.maasng:1235][WARNING ][2947] Ignoring parameter vlan:0
2019-05-14 06:08:36,374 [salt.utils.decorators:613 ][WARNING ][2947] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-14 06:08:38,424 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18636] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-14 06:08:38,458 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18636] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-14 06:08:38,480 [salt.utils.parsers:1051][WARNING ][359] Minion received a SIGTERM. Exiting.
2019-05-14 06:08:39,418 [salt.cli.daemons :293 ][INFO    ][18718] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-05-14 06:08:39,500 [salt.cli.daemons :82  ][INFO    ][18718] Starting up the Salt Minion
2019-05-14 06:08:39,501 [salt.utils.event :1017][INFO    ][18718] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-05-14 06:08:40,292 [salt.minion      :976 ][INFO    ][18718] Creating minion process manager
2019-05-14 06:08:41,707 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][18718] Executing command ['date', '+%z'] in directory '/root'
2019-05-14 06:08:41,730 [salt.utils.schedule:568 ][INFO    ][18718] Updating job settings for scheduled job: __mine_interval
2019-05-14 06:08:41,732 [salt.minion      :1108][INFO    ][18718] Added mine.update to scheduler
2019-05-14 06:08:41,737 [salt.minion      :1975][INFO    ][18718] Minion is starting as user 'root'
2019-05-14 06:08:41,747 [salt.minion      :2336][INFO    ][18718] Minion is ready to receive requests!
2019-05-14 06:08:42,490 [salt.utils.decorators:613 ][WARNING ][18656] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-14 06:08:42,563 [salt.loaded.ext.module.maas:412 ][WARNING ][18656] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-14 06:08:46,194 [salt.loaded.ext.module.maas:412 ][WARNING ][18656] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-14 06:08:49,667 [salt.loaded.ext.module.maas:412 ][WARNING ][18656] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-14 06:08:52,340 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514060852327471
2019-05-14 06:08:52,362 [salt.minion      :1432][INFO    ][19043] Starting a new job with PID 19043
2019-05-14 06:08:52,385 [salt.minion      :1711][INFO    ][19043] Returning information for job: 20190514060852327471
2019-05-14 06:08:53,601 [salt.loaded.ext.module.maas:412 ][WARNING ][18656] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-14 06:08:57,665 [salt.loaded.ext.module.maas:412 ][WARNING ][18656] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-14 06:09:33,737 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command state.apply with jid 20190514060933723275
2019-05-14 06:09:33,756 [salt.minion      :1432][INFO    ][19413] Starting a new job with PID 19413
2019-05-14 06:09:37,336 [salt.state       :915 ][INFO    ][19413] Loading fresh modules for state activity
2019-05-14 06:09:37,369 [salt.fileclient  :1219][INFO    ][19413] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-14 06:09:37,399 [salt.state       :1780][INFO    ][19413] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:09:37.399435
2019-05-14 06:09:37,399 [salt.state       :1813][INFO    ][19413] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-14 06:09:37,401 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19413] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-14 06:09:38,832 [salt.state       :300 ][INFO    ][19413] {'pid': 19433, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-14 06:09:38,833 [salt.state       :1951][INFO    ][19413] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:09:38.833196 duration_in_ms=1433.761
2019-05-14 06:09:38,834 [salt.state       :1780][INFO    ][19413] Running state [maas.wait_for_machine_status] at time 06:09:38.834689
2019-05-14 06:09:38,834 [salt.state       :1813][INFO    ][19413] Executing state module.run for [maas.wait_for_machine_status]
2019-05-14 06:09:38,835 [salt.utils.decorators:613 ][WARNING ][19413] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-14 06:09:40,035 [salt.loaded.ext.module.maas:1023][INFO    ][19413] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.80375504s left)
2019-05-14 06:09:48,846 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514060948833416
2019-05-14 06:09:48,866 [salt.minion      :1432][INFO    ][19475] Starting a new job with PID 19475
2019-05-14 06:09:48,888 [salt.minion      :1711][INFO    ][19475] Returning information for job: 20190514060948833416
2019-05-14 06:10:10,892 [salt.loaded.ext.module.maas:1023][INFO    ][19413] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.94745421s left)
2019-05-14 06:10:19,052 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514061018991559
2019-05-14 06:10:19,075 [salt.minion      :1432][INFO    ][19629] Starting a new job with PID 19629
2019-05-14 06:10:19,096 [salt.minion      :1711][INFO    ][19629] Returning information for job: 20190514061018991559
2019-05-14 06:10:41,889 [salt.loaded.ext.module.maas:1023][INFO    ][19413] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.95016408s left)
2019-05-14 06:10:49,161 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514061049148062
2019-05-14 06:10:49,176 [salt.minion      :1432][INFO    ][19832] Starting a new job with PID 19832
2019-05-14 06:10:49,186 [salt.minion      :1711][INFO    ][19832] Returning information for job: 20190514061049148062
2019-05-14 06:11:15,206 [salt.loaded.ext.module.maas:1023][INFO    ][19413] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1403.63313103s left)
2019-05-14 06:11:19,191 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514061119178315
2019-05-14 06:11:19,214 [salt.minion      :1432][INFO    ][20217] Starting a new job with PID 20217
2019-05-14 06:11:19,235 [salt.minion      :1711][INFO    ][20217] Returning information for job: 20190514061119178315
2019-05-14 06:11:46,899 [salt.loaded.ext.module.maas:1023][INFO    ][19413] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1371.94009209s left)
2019-05-14 06:11:49,251 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514061149236880
2019-05-14 06:11:49,274 [salt.minion      :1432][INFO    ][20508] Starting a new job with PID 20508
2019-05-14 06:11:49,295 [salt.minion      :1711][INFO    ][20508] Returning information for job: 20190514061149236880
2019-05-14 06:12:18,537 [salt.loaded.ext.module.maas:1023][INFO    ][19413] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1340.30227613s left)
2019-05-14 06:12:19,302 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514061219290010
2019-05-14 06:12:19,325 [salt.minion      :1432][INFO    ][21179] Starting a new job with PID 21179
2019-05-14 06:12:19,347 [salt.minion      :1711][INFO    ][21179] Returning information for job: 20190514061219290010
2019-05-14 06:12:49,371 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514061249359028
2019-05-14 06:12:49,392 [salt.minion      :1432][INFO    ][21485] Starting a new job with PID 21485
2019-05-14 06:12:49,414 [salt.minion      :1711][INFO    ][21485] Returning information for job: 20190514061249359028
2019-05-14 06:12:52,078 [salt.state       :300 ][INFO    ][19413] {'ret': True}
2019-05-14 06:12:52,079 [salt.state       :1951][INFO    ][19413] Completed state [maas.wait_for_machine_status] at time 06:12:52.078906 duration_in_ms=193244.215
2019-05-14 06:12:52,083 [salt.minion      :1711][INFO    ][19413] Returning information for job: 20190514060933723275
2019-05-14 06:12:52,709 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command state.apply with jid 20190514061252697027
2019-05-14 06:12:52,732 [salt.minion      :1432][INFO    ][21520] Starting a new job with PID 21520
2019-05-14 06:12:56,468 [salt.state       :915 ][INFO    ][21520] Loading fresh modules for state activity
2019-05-14 06:12:56,522 [salt.fileclient  :1219][INFO    ][21520] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-14 06:12:56,615 [salt.state       :1780][INFO    ][21520] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:12:56.615381
2019-05-14 06:12:56,615 [salt.state       :1813][INFO    ][21520] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-14 06:12:56,617 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21520] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-14 06:12:58,055 [salt.state       :300 ][INFO    ][21520] {'pid': 21549, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-14 06:12:58,056 [salt.state       :1951][INFO    ][21520] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:12:58.056094 duration_in_ms=1440.713
2019-05-14 06:12:58,057 [salt.state       :1780][INFO    ][21520] Running state [maas_machines_storage_cmp002_lvm] at time 06:12:58.057396
2019-05-14 06:12:58,057 [salt.state       :1813][INFO    ][21520] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-14 06:12:59,554 [salt.loaded.ext.module.maasng:610 ][INFO    ][21520] adggby
2019-05-14 06:12:59,555 [salt.loaded.ext.module.maasng:626 ][INFO    ][21520] sda
2019-05-14 06:13:00,592 [salt.loaded.ext.module.maasng:361 ][INFO    ][21520] adggby
2019-05-14 06:13:00,725 [salt.loaded.ext.module.maasng:367 ][INFO    ][21520] [{u'model': u'UCSB-MRAID12G', u'available_size': 0, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/adggby/blockdevices/3/', u'type': u'physical', u'partitions': [{u'uuid': u'2a28f30a-1806-4ef7-966f-ee491014fba9', u'resource_uri': u'/MAAS/api/2.0/nodes/adggby/blockdevices/3/partition/4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'adggby', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'603f5d1e-322b-4954-9bb1-e90fb2cc65bf'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 4, u'size': 2397992648704}], u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'adggby', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 3, u'size': 2397998940160}, {u'model': None, u'available_size': 0, u'uuid': u'8f5f84e3-a575-4bc1-89ec-ee3ec5d9c7c9', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/adggby/blockdevices/9/', u'type': u'virtual', u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'adggby', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'78cbfa78-c767-4289-a63c-f7fd36f10503'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 9, u'size': 2397988454400}]
2019-05-14 06:13:00,726 [salt.loaded.ext.module.maasng:632 ][INFO    ][21520] vgroot
2019-05-14 06:13:00,727 [salt.loaded.ext.module.maasng:635 ][INFO    ][21520] lvroot
2019-05-14 06:13:00,727 [salt.loaded.ext.module.maasng:639 ][INFO    ][21520] 107374182400
2019-05-14 06:13:01,349 [salt.loaded.ext.module.maasng:645 ][INFO    ][21520] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'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'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'adggby', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"From 'Testing' to 'Ready'", u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rmfsdh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 31}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rmfsdh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rmfsdh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'adggby', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/adggby/interfaces/6/'}, u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'available_size': 0, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/adggby/blockdevices/3/', u'type': u'physical', u'partitions': [{u'uuid': u'f512746c-6eb1-4fd6-8b26-047fdfe34340', u'resource_uri': u'/MAAS/api/2.0/nodes/adggby/blockdevices/3/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'adggby', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'2c9f483c-49a7-47e5-8065-1f57641d4550'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'adggby', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 3, u'size': 2397998940160}, {u'model': None, u'available_size': 0, u'uuid': u'f57149ad-288d-4b52-acd2-670640e84584', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/adggby/blockdevices/12/', u'type': u'virtual', u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'adggby', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'b63e24f8-590c-49ea-96d3-64e1e48b3c7a'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'on', u'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/adggby/blockdevices/3/', u'type': u'physical', u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'adggby', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'f512746c-6eb1-4fd6-8b26-047fdfe34340', u'resource_uri': u'/MAAS/api/2.0/nodes/adggby/blockdevices/3/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'adggby', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'2c9f483c-49a7-47e5-8065-1f57641d4550'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 3, u'size': 2397998940160}], u'ip_addresses': [u'192.168.11.38'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'adggby', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/adggby/blockdevices/3/', u'type': u'physical', u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'adggby', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'f512746c-6eb1-4fd6-8b26-047fdfe34340', u'resource_uri': u'/MAAS/api/2.0/nodes/adggby/blockdevices/3/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'adggby', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'2c9f483c-49a7-47e5-8065-1f57641d4550'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 3, u'size': 2397998940160}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'rmfsdh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 31}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rmfsdh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rmfsdh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'adggby', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/adggby/interfaces/6/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 32}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 17, u'discovered': None, u'system_id': u'adggby', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/adggby/interfaces/17/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 33}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 18, u'discovered': None, u'system_id': u'adggby', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/adggby/interfaces/18/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 34}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 19, u'discovered': None, u'system_id': u'adggby', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/adggby/interfaces/19/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/adggby/blockdevices/12/', u'type': u'virtual', u'tags': [], u'uuid': u'f57149ad-288d-4b52-acd2-670640e84584', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'adggby', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'b63e24f8-590c-49ea-96d3-64e1e48b3c7a'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'partitions': [], u'used_size': 107374182400, u'id': 12, u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 2, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/adggby/'}
2019-05-14 06:13:01,351 [salt.state       :300 ][INFO    ][21520] {'new': {'storage_layout': 'lvm'}}
2019-05-14 06:13:01,352 [salt.state       :1951][INFO    ][21520] Completed state [maas_machines_storage_cmp002_lvm] at time 06:13:01.352095 duration_in_ms=3294.697
2019-05-14 06:13:01,352 [salt.state       :1780][INFO    ][21520] Running state [maas_machines_storage_cmp001_lvm] at time 06:13:01.352666
2019-05-14 06:13:01,353 [salt.state       :1813][INFO    ][21520] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-14 06:13:02,480 [salt.loaded.ext.module.maasng:610 ][INFO    ][21520] eesrad
2019-05-14 06:13:02,480 [salt.loaded.ext.module.maasng:626 ][INFO    ][21520] sda
2019-05-14 06:13:03,117 [salt.loaded.ext.module.maasng:361 ][INFO    ][21520] eesrad
2019-05-14 06:13:03,234 [salt.loaded.ext.module.maasng:367 ][INFO    ][21520] [{u'model': u'UCSB-MRAID12G', u'available_size': 0, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/eesrad/blockdevices/2/', u'type': u'physical', u'partitions': [{u'uuid': u'15c01e26-0f0e-4512-a6b0-e39bf86bd560', u'resource_uri': u'/MAAS/api/2.0/nodes/eesrad/blockdevices/2/partition/3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'eesrad', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'4ee4343f-9d46-4f87-ac87-25172f454622'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 3, u'size': 2397992648704}], u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'eesrad', 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'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 2, u'size': 2397998940160}, {u'model': None, u'available_size': 0, u'uuid': u'23be935b-0549-4458-99e1-d1af0c8be516', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/eesrad/blockdevices/8/', u'type': u'virtual', u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'eesrad', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'3d02466d-8164-423b-9ae4-9217570c7fab'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 8, u'size': 2397988454400}]
2019-05-14 06:13:03,234 [salt.loaded.ext.module.maasng:632 ][INFO    ][21520] vgroot
2019-05-14 06:13:03,235 [salt.loaded.ext.module.maasng:635 ][INFO    ][21520] lvroot
2019-05-14 06:13:03,235 [salt.loaded.ext.module.maasng:639 ][INFO    ][21520] 107374182400
2019-05-14 06:13:03,677 [salt.loaded.ext.module.maasng:645 ][INFO    ][21520] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'rmfsdh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40', u'id': 26, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'rmfsdh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'id': 7, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'rmfsdh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'eesrad', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/eesrad/interfaces/7/'}, u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'partition_table_type': u'GPT', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'573d7d6a-c4aa-411f-b0ad-e8c2ec87fc10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'eesrad', u'device_id': 2, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'49b2afcf-6870-4f34-9a49-44be1a6fca08', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/eesrad/blockdevices/2/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'id': 2, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'eesrad', u'resource_uri': u'/MAAS/api/2.0/nodes/eesrad/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'size': 2397998940160}, u'blockdevice_set': [{u'partition_table_type': u'GPT', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'573d7d6a-c4aa-411f-b0ad-e8c2ec87fc10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'eesrad', u'device_id': 2, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'49b2afcf-6870-4f34-9a49-44be1a6fca08', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/eesrad/blockdevices/2/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'uuid': None, u'id': 2, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'eesrad', u'resource_uri': u'/MAAS/api/2.0/nodes/eesrad/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'size': 2397998940160}, {u'partition_table_type': None, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'ab5e3bba-f38d-4124-aa53-d2526912c1e3', u'id': 13, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'eesrad', u'resource_uri': u'/MAAS/api/2.0/nodes/eesrad/blockdevices/13/', u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'5b3c6016-5db0-4e5c-b582-26ee5751bc46', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'model': None, u'size': 107374182400}], 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/eesrad/', u'current_commissioning_result_id': 4, u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'eesrad', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'partition_table_type': None, u'available_size': 0, u'uuid': u'ab5e3bba-f38d-4124-aa53-d2526912c1e3', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'id': 13, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'eesrad', u'resource_uri': u'/MAAS/api/2.0/nodes/eesrad/blockdevices/13/', u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'5b3c6016-5db0-4e5c-b582-26ee5751bc46', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'model': None, u'size': 107374182400}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'partition_table_type': u'GPT', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'573d7d6a-c4aa-411f-b0ad-e8c2ec87fc10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'eesrad', u'device_id': 2, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'49b2afcf-6870-4f34-9a49-44be1a6fca08', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/eesrad/blockdevices/2/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'id': 2, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'eesrad', u'resource_uri': u'/MAAS/api/2.0/nodes/eesrad/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'size': 2397998940160}], u'ip_addresses': [u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'eesrad', u'id': 8}], 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'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'rmfsdh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40', u'id': 26, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'rmfsdh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'id': 7, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'rmfsdh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'eesrad', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/eesrad/interfaces/7/'}, {u'name': u'enp7s0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'id': 14, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'system_id': u'eesrad', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/eesrad/interfaces/14/'}, {u'name': u'enp8s0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'id': 15, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'system_id': u'eesrad', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/eesrad/interfaces/15/'}, {u'name': u'enp9s0', u'links': [{u'id': 29, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'id': 16, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'system_id': u'eesrad', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/eesrad/interfaces/16/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-05-14 06:13:03,678 [salt.state       :300 ][INFO    ][21520] {'new': {'storage_layout': 'lvm'}}
2019-05-14 06:13:03,678 [salt.state       :1951][INFO    ][21520] Completed state [maas_machines_storage_cmp001_lvm] at time 06:13:03.678526 duration_in_ms=2325.86
2019-05-14 06:13:03,680 [salt.minion      :1711][INFO    ][21520] Returning information for job: 20190514061252697027
2019-05-14 06:13:04,201 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command state.apply with jid 20190514061304189384
2019-05-14 06:13:04,220 [salt.minion      :1432][INFO    ][21648] Starting a new job with PID 21648
2019-05-14 06:13:04,843 [salt.state       :915 ][INFO    ][21648] Loading fresh modules for state activity
2019-05-14 06:13:04,888 [salt.fileclient  :1219][INFO    ][21648] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-14 06:13:04,925 [salt.state       :1780][INFO    ][21648] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:13:04.925161
2019-05-14 06:13:04,925 [salt.state       :1813][INFO    ][21648] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-14 06:13:04,927 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21648] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-14 06:13:06,448 [salt.state       :300 ][INFO    ][21648] {'pid': 21660, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-14 06:13:06,448 [salt.state       :1951][INFO    ][21648] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:13:06.448806 duration_in_ms=1523.644
2019-05-14 06:13:06,451 [salt.state       :1780][INFO    ][21648] Running state [maas.deploy_machines] at time 06:13:06.451210
2019-05-14 06:13:06,451 [salt.state       :1813][INFO    ][21648] Executing state module.run for [maas.deploy_machines]
2019-05-14 06:13:06,452 [salt.utils.decorators:613 ][WARNING ][21648] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-14 06:13:06,967 [salt.loaded.ext.module.maas:684 ][INFO    ][21648] deploymachines hwe_kernel=hwe-16.04 system_id=adggby distro_series=xenial
2019-05-14 06:13:14,970 [salt.loaded.ext.module.maas:684 ][INFO    ][21648] deploymachines hwe_kernel=hwe-16.04 system_id=eesrad distro_series=xenial
2019-05-14 06:13:19,220 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514061319209064
2019-05-14 06:13:19,239 [salt.minion      :1432][INFO    ][21917] Starting a new job with PID 21917
2019-05-14 06:13:19,256 [salt.minion      :1711][INFO    ][21917] Returning information for job: 20190514061319209064
2019-05-14 06:13:20,094 [salt.loaded.ext.module.maas:684 ][INFO    ][21648] deploymachines hwe_kernel=hwe-16.04 system_id=d8mhkx distro_series=xenial
2019-05-14 06:13:23,664 [salt.loaded.ext.module.maas:684 ][INFO    ][21648] deploymachines hwe_kernel=hwe-16.04 system_id=qesx8p distro_series=xenial
2019-05-14 06:13:28,024 [salt.loaded.ext.module.maas:684 ][INFO    ][21648] deploymachines hwe_kernel=hwe-16.04 system_id=a8cd6d distro_series=xenial
2019-05-14 06:13:33,064 [salt.state       :300 ][INFO    ][21648] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-14 06:13:33,064 [salt.state       :1951][INFO    ][21648] Completed state [maas.deploy_machines] at time 06:13:33.064404 duration_in_ms=26613.194
2019-05-14 06:13:33,066 [salt.minion      :1711][INFO    ][21648] Returning information for job: 20190514061304189384
2019-05-14 06:13:33,585 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command state.apply with jid 20190514061333573018
2019-05-14 06:13:33,600 [salt.minion      :1432][INFO    ][22153] Starting a new job with PID 22153
2019-05-14 06:13:37,106 [salt.state       :915 ][INFO    ][22153] Loading fresh modules for state activity
2019-05-14 06:13:37,136 [salt.fileclient  :1219][INFO    ][22153] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-14 06:13:37,163 [salt.state       :1780][INFO    ][22153] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:13:37.162968
2019-05-14 06:13:37,163 [salt.state       :1813][INFO    ][22153] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-14 06:13:37,164 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22153] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-14 06:13:38,301 [salt.state       :300 ][INFO    ][22153] {'pid': 22175, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-14 06:13:38,301 [salt.state       :1951][INFO    ][22153] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:13:38.301482 duration_in_ms=1138.515
2019-05-14 06:13:38,302 [salt.state       :1780][INFO    ][22153] Running state [maas.wait_for_machine_status] at time 06:13:38.302827
2019-05-14 06:13:38,303 [salt.state       :1813][INFO    ][22153] Executing state module.run for [maas.wait_for_machine_status]
2019-05-14 06:13:38,303 [salt.utils.decorators:613 ][WARNING ][22153] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-14 06:13:40,915 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.39180303s left)
2019-05-14 06:13:48,617 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514061348604854
2019-05-14 06:13:48,639 [salt.minion      :1432][INFO    ][22240] Starting a new job with PID 22240
2019-05-14 06:13:48,660 [salt.minion      :1711][INFO    ][22240] Returning information for job: 20190514061348604854
2019-05-14 06:14:13,567 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.7394979s left)
2019-05-14 06:14:18,673 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514061418660518
2019-05-14 06:14:18,696 [salt.minion      :1432][INFO    ][22452] Starting a new job with PID 22452
2019-05-14 06:14:18,718 [salt.minion      :1711][INFO    ][22452] Returning information for job: 20190514061418660518
2019-05-14 06:14:46,127 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2182.17961192s left)
2019-05-14 06:14:48,795 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514061448783918
2019-05-14 06:14:48,815 [salt.minion      :1432][INFO    ][22613] Starting a new job with PID 22613
2019-05-14 06:14:48,836 [salt.minion      :1711][INFO    ][22613] Returning information for job: 20190514061448783918
2019-05-14 06:15:18,825 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514061518818064
2019-05-14 06:15:18,844 [salt.minion      :1432][INFO    ][22931] Starting a new job with PID 22931
2019-05-14 06:15:18,869 [salt.minion      :1711][INFO    ][22931] Returning information for job: 20190514061518818064
2019-05-14 06:15:19,744 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.56215501s left)
2019-05-14 06:15:48,887 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514061548880286
2019-05-14 06:15:48,906 [salt.minion      :1432][INFO    ][23235] Starting a new job with PID 23235
2019-05-14 06:15:48,928 [salt.minion      :1711][INFO    ][23235] Returning information for job: 20190514061548880286
2019-05-14 06:15:53,780 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2114.52648807s left)
2019-05-14 06:16:18,947 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514061618934832
2019-05-14 06:16:18,968 [salt.minion      :1432][INFO    ][24186] Starting a new job with PID 24186
2019-05-14 06:16:18,994 [salt.minion      :1711][INFO    ][24186] Returning information for job: 20190514061618934832
2019-05-14 06:16:26,956 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2081.35073996s left)
2019-05-14 06:16:49,023 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514061649010205
2019-05-14 06:16:49,043 [salt.minion      :1432][INFO    ][24363] Starting a new job with PID 24363
2019-05-14 06:16:49,061 [salt.minion      :1711][INFO    ][24363] Returning information for job: 20190514061649010205
2019-05-14 06:16:59,650 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.6570549s left)
2019-05-14 06:17:19,085 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514061719079671
2019-05-14 06:17:19,103 [salt.minion      :1432][INFO    ][24674] Starting a new job with PID 24674
2019-05-14 06:17:19,121 [salt.minion      :1711][INFO    ][24674] Returning information for job: 20190514061719079671
2019-05-14 06:17:33,250 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.05613494s left)
2019-05-14 06:17:49,141 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514061749129017
2019-05-14 06:17:49,164 [salt.minion      :1432][INFO    ][25016] Starting a new job with PID 25016
2019-05-14 06:17:49,185 [salt.minion      :1711][INFO    ][25016] Returning information for job: 20190514061749129017
2019-05-14 06:18:06,521 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1981.78589606s left)
2019-05-14 06:18:19,204 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514061819191709
2019-05-14 06:18:19,227 [salt.minion      :1432][INFO    ][25798] Starting a new job with PID 25798
2019-05-14 06:18:19,250 [salt.minion      :1711][INFO    ][25798] Returning information for job: 20190514061819191709
2019-05-14 06:18:39,805 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1948.5012939s left)
2019-05-14 06:18:49,275 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514061849262357
2019-05-14 06:18:49,298 [salt.minion      :1432][INFO    ][25984] Starting a new job with PID 25984
2019-05-14 06:18:49,318 [salt.minion      :1711][INFO    ][25984] Returning information for job: 20190514061849262357
2019-05-14 06:19:13,414 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1914.89286804s left)
2019-05-14 06:19:19,353 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514061919339275
2019-05-14 06:19:19,376 [salt.minion      :1432][INFO    ][26522] Starting a new job with PID 26522
2019-05-14 06:19:19,398 [salt.minion      :1711][INFO    ][26522] Returning information for job: 20190514061919339275
2019-05-14 06:19:47,132 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1881.17490697s left)
2019-05-14 06:19:49,434 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514061949422466
2019-05-14 06:19:49,457 [salt.minion      :1432][INFO    ][26685] Starting a new job with PID 26685
2019-05-14 06:19:49,480 [salt.minion      :1711][INFO    ][26685] Returning information for job: 20190514061949422466
2019-05-14 06:20:19,522 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514062019508715
2019-05-14 06:20:19,543 [salt.minion      :1432][INFO    ][26845] Starting a new job with PID 26845
2019-05-14 06:20:19,565 [salt.minion      :1711][INFO    ][26845] Returning information for job: 20190514062019508715
2019-05-14 06:20:20,630 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1847.67658901s left)
2019-05-14 06:20:49,615 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514062049602158
2019-05-14 06:20:49,637 [salt.minion      :1432][INFO    ][27120] Starting a new job with PID 27120
2019-05-14 06:20:49,658 [salt.minion      :1711][INFO    ][27120] Returning information for job: 20190514062049602158
2019-05-14 06:20:53,975 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1814.33123207s left)
2019-05-14 06:21:19,770 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514062119758446
2019-05-14 06:21:19,794 [salt.minion      :1432][INFO    ][27680] Starting a new job with PID 27680
2019-05-14 06:21:19,817 [salt.minion      :1711][INFO    ][27680] Returning information for job: 20190514062119758446
2019-05-14 06:21:27,534 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1780.77256489s left)
2019-05-14 06:21:49,875 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514062149862357
2019-05-14 06:21:49,897 [salt.minion      :1432][INFO    ][27824] Starting a new job with PID 27824
2019-05-14 06:21:49,919 [salt.minion      :1711][INFO    ][27824] Returning information for job: 20190514062149862357
2019-05-14 06:22:00,869 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1747.43733191s left)
2019-05-14 06:22:19,991 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514062219978352
2019-05-14 06:22:20,015 [salt.minion      :1432][INFO    ][28167] Starting a new job with PID 28167
2019-05-14 06:22:20,038 [salt.minion      :1711][INFO    ][28167] Returning information for job: 20190514062219978352
2019-05-14 06:22:34,346 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1713.96069098s left)
2019-05-14 06:22:50,110 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514062250096896
2019-05-14 06:22:50,132 [salt.minion      :1432][INFO    ][28330] Starting a new job with PID 28330
2019-05-14 06:22:50,156 [salt.minion      :1711][INFO    ][28330] Returning information for job: 20190514062250096896
2019-05-14 06:23:07,778 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1680.52886105s left)
2019-05-14 06:23:20,240 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514062320226852
2019-05-14 06:23:20,263 [salt.minion      :1432][INFO    ][28530] Starting a new job with PID 28530
2019-05-14 06:23:20,285 [salt.minion      :1711][INFO    ][28530] Returning information for job: 20190514062320226852
2019-05-14 06:23:40,917 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1647.38920307s left)
2019-05-14 06:23:50,374 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514062350361995
2019-05-14 06:23:50,397 [salt.minion      :1432][INFO    ][28692] Starting a new job with PID 28692
2019-05-14 06:23:50,418 [salt.minion      :1711][INFO    ][28692] Returning information for job: 20190514062350361995
2019-05-14 06:24:14,247 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1614.05945492s left)
2019-05-14 06:24:20,518 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514062420504917
2019-05-14 06:24:20,540 [salt.minion      :1432][INFO    ][28903] Starting a new job with PID 28903
2019-05-14 06:24:20,563 [salt.minion      :1711][INFO    ][28903] Returning information for job: 20190514062420504917
2019-05-14 06:24:47,901 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1580.40565491s left)
2019-05-14 06:24:50,664 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514062450656225
2019-05-14 06:24:50,684 [salt.minion      :1432][INFO    ][29066] Starting a new job with PID 29066
2019-05-14 06:24:50,706 [salt.minion      :1711][INFO    ][29066] Returning information for job: 20190514062450656225
2019-05-14 06:25:20,824 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514062520811901
2019-05-14 06:25:20,848 [salt.minion      :1432][INFO    ][29352] Starting a new job with PID 29352
2019-05-14 06:25:20,870 [salt.minion      :1711][INFO    ][29352] Returning information for job: 20190514062520811901
2019-05-14 06:25:21,116 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1547.19050097s left)
2019-05-14 06:25:50,995 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514062550982264
2019-05-14 06:25:51,019 [salt.minion      :1432][INFO    ][29519] Starting a new job with PID 29519
2019-05-14 06:25:51,042 [salt.minion      :1711][INFO    ][29519] Returning information for job: 20190514062550982264
2019-05-14 06:25:54,297 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1514.00977397s left)
2019-05-14 06:26:21,175 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514062621162687
2019-05-14 06:26:21,199 [salt.minion      :1432][INFO    ][29720] Starting a new job with PID 29720
2019-05-14 06:26:21,223 [salt.minion      :1711][INFO    ][29720] Returning information for job: 20190514062621162687
2019-05-14 06:26:27,725 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1480.58203101s left)
2019-05-14 06:26:51,365 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514062651352683
2019-05-14 06:26:51,388 [salt.minion      :1432][INFO    ][29887] Starting a new job with PID 29887
2019-05-14 06:26:51,409 [salt.minion      :1711][INFO    ][29887] Returning information for job: 20190514062651352683
2019-05-14 06:27:00,659 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1447.64781904s left)
2019-05-14 06:27:21,562 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514062721549259
2019-05-14 06:27:21,585 [salt.minion      :1432][INFO    ][30095] Starting a new job with PID 30095
2019-05-14 06:27:21,608 [salt.minion      :1711][INFO    ][30095] Returning information for job: 20190514062721549259
2019-05-14 06:27:34,115 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1414.19149208s left)
2019-05-14 06:27:51,780 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514062751767292
2019-05-14 06:27:51,802 [salt.minion      :1432][INFO    ][30272] Starting a new job with PID 30272
2019-05-14 06:27:51,825 [salt.minion      :1711][INFO    ][30272] Returning information for job: 20190514062751767292
2019-05-14 06:28:06,403 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1381.903795s left)
2019-05-14 06:28:22,004 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514062821992003
2019-05-14 06:28:22,026 [salt.minion      :1432][INFO    ][30497] Starting a new job with PID 30497
2019-05-14 06:28:22,042 [salt.minion      :1711][INFO    ][30497] Returning information for job: 20190514062821992003
2019-05-14 06:28:39,806 [salt.loaded.ext.module.maas:1023][INFO    ][22153] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1348.50042391s left)
2019-05-14 06:28:52,024 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514062852011565
2019-05-14 06:28:52,047 [salt.minion      :1432][INFO    ][30671] Starting a new job with PID 30671
2019-05-14 06:28:52,070 [salt.minion      :1711][INFO    ][30671] Returning information for job: 20190514062852011565
2019-05-14 06:29:12,247 [salt.loaded.ext.module.maas:993 ][INFO    ][22153] Machine qesx8p mark broken
2019-05-14 06:29:12,965 [salt.loaded.ext.module.maas:996 ][INFO    ][22153] Machine qesx8p mark fixed
2019-05-14 06:29:14,167 [salt.loaded.ext.module.maas:684 ][INFO    ][22153] deploymachines hwe_kernel=hwe-16.04 system_id=qesx8p distro_series=xenial
2019-05-14 06:29:19,572 [salt.loaded.ext.module.maas:160 ][ERROR   ][22153] Failed for object kvm03 reason Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node.
2019-05-14 06:29:19,574 [salt.state       :302 ][ERROR   ][22153] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm02'], 'errors': {'kvm03': "Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node."}, 'success': []}
2019-05-14 06:29:19,575 [salt.state       :1951][INFO    ][22153] Completed state [maas.wait_for_machine_status] at time 06:29:19.575006 duration_in_ms=941272.17
2019-05-14 06:29:19,582 [salt.minion      :1711][INFO    ][22153] Returning information for job: 20190514061333573018
2019-05-14 06:29:30,327 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command pillar.get with jid 20190514062930316345
2019-05-14 06:29:30,347 [salt.minion      :1432][INFO    ][31000] Starting a new job with PID 31000
2019-05-14 06:29:30,354 [salt.minion      :1711][INFO    ][31000] Returning information for job: 20190514062930316345
2019-05-14 06:29:30,831 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command service.status with jid 20190514062930824277
2019-05-14 06:29:30,852 [salt.minion      :1432][INFO    ][31008] Starting a new job with PID 31008
2019-05-14 06:29:31,172 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][31008] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-14 06:29:31,206 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][31008] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-14 06:29:31,221 [salt.minion      :1711][INFO    ][31008] Returning information for job: 20190514062930824277
2019-05-14 06:29:31,728 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command state.apply with jid 20190514062931714582
2019-05-14 06:29:31,749 [salt.minion      :1432][INFO    ][31022] Starting a new job with PID 31022
2019-05-14 06:29:35,340 [salt.state       :915 ][INFO    ][31022] Loading fresh modules for state activity
2019-05-14 06:29:35,676 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31022] Executing command 'salt-minion --version' in directory '/root'
2019-05-14 06:29:36,008 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31022] Executing command 'salt-minion --version' in directory '/root'
2019-05-14 06:29:36,912 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31022] Executing command 'salt-minion --version' in directory '/root'
2019-05-14 06:29:37,262 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31022] Executing command 'salt-minion --version' in directory '/root'
2019-05-14 06:29:38,701 [salt.state       :1780][INFO    ][31022] Running state [salt-minion] at time 06:29:38.701596
2019-05-14 06:29:38,701 [salt.state       :1813][INFO    ][31022] Executing state pkg.installed for [salt-minion]
2019-05-14 06:29:38,702 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31022] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-14 06:29:38,800 [salt.state       :300 ][INFO    ][31022] All specified packages are already installed
2019-05-14 06:29:38,801 [salt.state       :1951][INFO    ][31022] Completed state [salt-minion] at time 06:29:38.801337 duration_in_ms=99.74
2019-05-14 06:29:38,801 [salt.state       :1780][INFO    ][31022] Running state [salt_minion_dependency_packages] at time 06:29:38.801808
2019-05-14 06:29:38,802 [salt.state       :1813][INFO    ][31022] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-14 06:29:38,810 [salt.state       :300 ][INFO    ][31022] All specified packages are already installed
2019-05-14 06:29:38,810 [salt.state       :1951][INFO    ][31022] Completed state [salt_minion_dependency_packages] at time 06:29:38.810427 duration_in_ms=8.62
2019-05-14 06:29:38,814 [salt.state       :1780][INFO    ][31022] Running state [/etc/salt/minion.d/minion.conf] at time 06:29:38.814138
2019-05-14 06:29:38,814 [salt.state       :1813][INFO    ][31022] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-14 06:29:38,990 [salt.state       :300 ][INFO    ][31022] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-14 06:29:38,990 [salt.state       :1951][INFO    ][31022] Completed state [/etc/salt/minion.d/minion.conf] at time 06:29:38.990474 duration_in_ms=176.336
2019-05-14 06:29:38,990 [salt.state       :1780][INFO    ][31022] Running state [python-netaddr] at time 06:29:38.990680
2019-05-14 06:29:38,990 [salt.state       :1813][INFO    ][31022] Executing state pkg.installed for [python-netaddr]
2019-05-14 06:29:38,995 [salt.state       :300 ][INFO    ][31022] All specified packages are already installed
2019-05-14 06:29:38,995 [salt.state       :1951][INFO    ][31022] Completed state [python-netaddr] at time 06:29:38.995359 duration_in_ms=4.68
2019-05-14 06:29:38,997 [salt.state       :1780][INFO    ][31022] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:29:38.997392
2019-05-14 06:29:38,997 [salt.state       :1813][INFO    ][31022] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-14 06:29:39,007 [salt.state       :300 ][INFO    ][31022] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-14 06:29:39,007 [salt.state       :1951][INFO    ][31022] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:29:39.007386 duration_in_ms=9.993
2019-05-14 06:29:39,008 [salt.state       :1780][INFO    ][31022] Running state [salt-minion] at time 06:29:39.008056
2019-05-14 06:29:39,008 [salt.state       :1813][INFO    ][31022] Executing state service.running for [salt-minion]
2019-05-14 06:29:39,008 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31022] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-14 06:29:39,041 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31022] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-14 06:29:39,056 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31022] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-14 06:29:39,071 [salt.state       :300 ][INFO    ][31022] The service salt-minion is already running
2019-05-14 06:29:39,071 [salt.state       :1951][INFO    ][31022] Completed state [salt-minion] at time 06:29:39.071595 duration_in_ms=63.538
2019-05-14 06:29:39,072 [salt.state       :1780][INFO    ][31022] Running state [/etc/salt/grains.d] at time 06:29:39.072728
2019-05-14 06:29:39,073 [salt.state       :1813][INFO    ][31022] Executing state file.directory for [/etc/salt/grains.d]
2019-05-14 06:29:39,073 [salt.state       :300 ][INFO    ][31022] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-14 06:29:39,073 [salt.state       :1951][INFO    ][31022] Completed state [/etc/salt/grains.d] at time 06:29:39.073886 duration_in_ms=1.157
2019-05-14 06:29:39,074 [salt.state       :1780][INFO    ][31022] Running state [/etc/salt/grains] at time 06:29:39.074400
2019-05-14 06:29:39,074 [salt.state       :1813][INFO    ][31022] Executing state file.managed for [/etc/salt/grains]
2019-05-14 06:29:39,075 [salt.state       :300 ][INFO    ][31022] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-14 06:29:39,075 [salt.state       :1951][INFO    ][31022] Completed state [/etc/salt/grains] at time 06:29:39.075236 duration_in_ms=0.836
2019-05-14 06:29:39,075 [salt.state       :1780][INFO    ][31022] Running state [/etc/salt/grains.d/placeholder] at time 06:29:39.075602
2019-05-14 06:29:39,075 [salt.state       :1813][INFO    ][31022] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-14 06:29:39,076 [salt.state       :300 ][INFO    ][31022] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-14 06:29:39,076 [salt.state       :1951][INFO    ][31022] Completed state [/etc/salt/grains.d/placeholder] at time 06:29:39.076393 duration_in_ms=0.791
2019-05-14 06:29:39,076 [salt.state       :1780][INFO    ][31022] Running state [/etc/salt/grains.d/sphinx] at time 06:29:39.076774
2019-05-14 06:29:39,077 [salt.state       :1813][INFO    ][31022] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-14 06:29:39,077 [salt.state       :300 ][INFO    ][31022] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-14 06:29:39,078 [salt.state       :1951][INFO    ][31022] Completed state [/etc/salt/grains.d/sphinx] at time 06:29:39.078040 duration_in_ms=1.266
2019-05-14 06:29:39,079 [salt.state       :1780][INFO    ][31022] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:29:39.079685
2019-05-14 06:29:39,079 [salt.state       :1813][INFO    ][31022] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-14 06:29:39,080 [salt.state       :300 ][INFO    ][31022] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-14 06:29:39,080 [salt.state       :1951][INFO    ][31022] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:29:39.080335 duration_in_ms=0.65
2019-05-14 06:29:39,080 [salt.state       :1780][INFO    ][31022] Running state [/etc/salt/grains.d/dns_records] at time 06:29:39.080689
2019-05-14 06:29:39,080 [salt.state       :1813][INFO    ][31022] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-14 06:29:39,081 [salt.state       :300 ][INFO    ][31022] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-14 06:29:39,081 [salt.state       :1951][INFO    ][31022] Completed state [/etc/salt/grains.d/dns_records] at time 06:29:39.081784 duration_in_ms=1.095
2019-05-14 06:29:39,082 [salt.state       :1780][INFO    ][31022] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:29:39.082440
2019-05-14 06:29:39,082 [salt.state       :1813][INFO    ][31022] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-14 06:29:39,082 [salt.state       :300 ][INFO    ][31022] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-14 06:29:39,083 [salt.state       :1951][INFO    ][31022] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:29:39.083068 duration_in_ms=0.628
2019-05-14 06:29:39,083 [salt.state       :1780][INFO    ][31022] Running state [/etc/salt/grains.d/salt] at time 06:29:39.083409
2019-05-14 06:29:39,083 [salt.state       :1813][INFO    ][31022] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-14 06:29:39,084 [salt.state       :300 ][INFO    ][31022] File /etc/salt/grains.d/salt is in the correct state
2019-05-14 06:29:39,084 [salt.state       :1951][INFO    ][31022] Completed state [/etc/salt/grains.d/salt] at time 06:29:39.084419 duration_in_ms=1.01
2019-05-14 06:29:39,085 [salt.state       :1780][INFO    ][31022] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:29:39.085023
2019-05-14 06:29:39,085 [salt.state       :1813][INFO    ][31022] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-14 06:29:39,085 [salt.state       :300 ][INFO    ][31022] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-14 06:29:39,085 [salt.state       :1951][INFO    ][31022] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:29:39.085627 duration_in_ms=0.604
2019-05-14 06:29:39,086 [salt.state       :1780][INFO    ][31022] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:29:39.086863
2019-05-14 06:29:39,087 [salt.state       :1813][INFO    ][31022] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-14 06:29:39,087 [salt.state       :300 ][INFO    ][31022] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-14 06:29:39,087 [salt.state       :1951][INFO    ][31022] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:29:39.087461 duration_in_ms=0.598
2019-05-14 06:29:39,087 [salt.state       :1780][INFO    ][31022] Running state [mine.update] at time 06:29:39.087944
2019-05-14 06:29:39,088 [salt.state       :1813][INFO    ][31022] Executing state module.wait for [mine.update]
2019-05-14 06:29:39,088 [salt.state       :300 ][INFO    ][31022] No changes made for mine.update
2019-05-14 06:29:39,088 [salt.state       :1951][INFO    ][31022] Completed state [mine.update] at time 06:29:39.088524 duration_in_ms=0.58
2019-05-14 06:29:39,088 [salt.state       :1780][INFO    ][31022] Running state [ca-certificates] at time 06:29:39.088717
2019-05-14 06:29:39,088 [salt.state       :1813][INFO    ][31022] Executing state pkg.installed for [ca-certificates]
2019-05-14 06:29:39,093 [salt.state       :300 ][INFO    ][31022] All specified packages are already installed
2019-05-14 06:29:39,093 [salt.state       :1951][INFO    ][31022] Completed state [ca-certificates] at time 06:29:39.093873 duration_in_ms=5.156
2019-05-14 06:29:39,094 [salt.state       :1780][INFO    ][31022] Running state [update-ca-certificates] at time 06:29:39.094341
2019-05-14 06:29:39,094 [salt.state       :1813][INFO    ][31022] Executing state cmd.wait for [update-ca-certificates]
2019-05-14 06:29:39,094 [salt.state       :300 ][INFO    ][31022] No changes made for update-ca-certificates
2019-05-14 06:29:39,094 [salt.state       :1951][INFO    ][31022] Completed state [update-ca-certificates] at time 06:29:39.094922 duration_in_ms=0.581
2019-05-14 06:29:39,095 [salt.state       :1780][INFO    ][31022] Running state [iptables] at time 06:29:39.095096
2019-05-14 06:29:39,095 [salt.state       :1813][INFO    ][31022] Executing state pkg.installed for [iptables]
2019-05-14 06:29:39,099 [salt.state       :300 ][INFO    ][31022] All specified packages are already installed
2019-05-14 06:29:39,099 [salt.state       :1951][INFO    ][31022] Completed state [iptables] at time 06:29:39.099899 duration_in_ms=4.803
2019-05-14 06:29:39,100 [salt.state       :1780][INFO    ][31022] Running state [iptables-persistent] at time 06:29:39.100084
2019-05-14 06:29:39,100 [salt.state       :1813][INFO    ][31022] Executing state pkg.installed for [iptables-persistent]
2019-05-14 06:29:39,104 [salt.state       :300 ][INFO    ][31022] All specified packages are already installed
2019-05-14 06:29:39,104 [salt.state       :1951][INFO    ][31022] Completed state [iptables-persistent] at time 06:29:39.104889 duration_in_ms=4.806
2019-05-14 06:29:39,105 [salt.state       :1780][INFO    ][31022] Running state [iptables_modules_v4_load] at time 06:29:39.105597
2019-05-14 06:29:39,105 [salt.state       :1813][INFO    ][31022] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-14 06:29:39,106 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31022] Executing command 'lsmod' in directory '/root'
2019-05-14 06:29:39,127 [salt.state       :300 ][INFO    ][31022] Kernel modules iptable_filter, ip_tables are already present
2019-05-14 06:29:39,128 [salt.state       :1951][INFO    ][31022] Completed state [iptables_modules_v4_load] at time 06:29:39.128011 duration_in_ms=22.414
2019-05-14 06:29:39,128 [salt.state       :1780][INFO    ][31022] Running state [/etc/iptables/rules.v4] at time 06:29:39.128501
2019-05-14 06:29:39,128 [salt.state       :1813][INFO    ][31022] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-14 06:29:39,188 [salt.state       :300 ][INFO    ][31022] File /etc/iptables/rules.v4 is in the correct state
2019-05-14 06:29:39,188 [salt.state       :1951][INFO    ][31022] Completed state [/etc/iptables/rules.v4] at time 06:29:39.188738 duration_in_ms=60.237
2019-05-14 06:29:39,189 [salt.state       :1780][INFO    ][31022] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:29:39.189470
2019-05-14 06:29:39,189 [salt.state       :1813][INFO    ][31022] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-14 06:29:39,190 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31022] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-14 06:29:39,210 [salt.state       :300 ][INFO    ][31022] onlyif execution failed
2019-05-14 06:29:39,210 [salt.state       :1951][INFO    ][31022] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:29:39.210249 duration_in_ms=20.778
2019-05-14 06:29:39,211 [salt.state       :1780][INFO    ][31022] Running state [netfilter-persistent] at time 06:29:39.210963
2019-05-14 06:29:39,211 [salt.state       :1813][INFO    ][31022] Executing state service.running for [netfilter-persistent]
2019-05-14 06:29:39,211 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31022] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-14 06:29:39,231 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31022] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-14 06:29:39,248 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31022] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-14 06:29:39,265 [salt.state       :300 ][INFO    ][31022] The service netfilter-persistent is already running
2019-05-14 06:29:39,265 [salt.state       :1951][INFO    ][31022] Completed state [netfilter-persistent] at time 06:29:39.265466 duration_in_ms=54.501
2019-05-14 06:29:39,266 [salt.state       :1780][INFO    ][31022] Running state [iptables_extra.remove_stale_tables] at time 06:29:39.266198
2019-05-14 06:29:39,266 [salt.state       :1813][INFO    ][31022] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-14 06:29:39,266 [salt.state       :300 ][INFO    ][31022] No changes made for iptables_extra.remove_stale_tables
2019-05-14 06:29:39,267 [salt.state       :1951][INFO    ][31022] Completed state [iptables_extra.remove_stale_tables] at time 06:29:39.266989 duration_in_ms=0.791
2019-05-14 06:29:39,267 [salt.state       :1780][INFO    ][31022] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:29:39.267221
2019-05-14 06:29:39,267 [salt.state       :1813][INFO    ][31022] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-14 06:29:39,268 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31022] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-14 06:29:39,282 [salt.state       :300 ][INFO    ][31022] onlyif execution failed
2019-05-14 06:29:39,282 [salt.state       :1951][INFO    ][31022] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:29:39.282432 duration_in_ms=15.211
2019-05-14 06:29:39,283 [salt.state       :1780][INFO    ][31022] Running state [/etc/iptables/rules.v6] at time 06:29:39.283211
2019-05-14 06:29:39,283 [salt.state       :1813][INFO    ][31022] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-14 06:29:39,283 [salt.state       :300 ][INFO    ][31022] File /etc/iptables/rules.v6 is not present
2019-05-14 06:29:39,284 [salt.state       :1951][INFO    ][31022] Completed state [/etc/iptables/rules.v6] at time 06:29:39.284014 duration_in_ms=0.803
2019-05-14 06:29:39,284 [salt.state       :1780][INFO    ][31022] Running state [iptables_extra.flush_all] at time 06:29:39.284539
2019-05-14 06:29:39,284 [salt.state       :1813][INFO    ][31022] Executing state module.wait for [iptables_extra.flush_all]
2019-05-14 06:29:39,285 [salt.state       :300 ][INFO    ][31022] No changes made for iptables_extra.flush_all
2019-05-14 06:29:39,285 [salt.state       :1951][INFO    ][31022] Completed state [iptables_extra.flush_all] at time 06:29:39.285191 duration_in_ms=0.652
2019-05-14 06:29:39,287 [salt.minion      :1711][INFO    ][31022] Returning information for job: 20190514062931714582
2019-05-14 06:29:39,811 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command state.apply with jid 20190514062939799611
2019-05-14 06:29:39,831 [salt.minion      :1432][INFO    ][31132] Starting a new job with PID 31132
2019-05-14 06:29:40,516 [salt.state       :915 ][INFO    ][31132] Loading fresh modules for state activity
2019-05-14 06:29:41,166 [salt.state       :1780][INFO    ][31132] Running state [maas-rack-controller] at time 06:29:41.166216
2019-05-14 06:29:41,166 [salt.state       :1813][INFO    ][31132] Executing state pkg.installed for [maas-rack-controller]
2019-05-14 06:29:41,167 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31132] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-14 06:29:41,275 [salt.state       :300 ][INFO    ][31132] All specified packages are already installed
2019-05-14 06:29:41,276 [salt.state       :1951][INFO    ][31132] Completed state [maas-rack-controller] at time 06:29:41.276098 duration_in_ms=109.881
2019-05-14 06:29:41,276 [salt.state       :1780][INFO    ][31132] Running state [ipmitool] at time 06:29:41.276591
2019-05-14 06:29:41,277 [salt.state       :1813][INFO    ][31132] Executing state pkg.installed for [ipmitool]
2019-05-14 06:29:41,285 [salt.state       :300 ][INFO    ][31132] All specified packages are already installed
2019-05-14 06:29:41,285 [salt.state       :1951][INFO    ][31132] Completed state [ipmitool] at time 06:29:41.285730 duration_in_ms=9.14
2019-05-14 06:29:41,289 [salt.state       :1780][INFO    ][31132] Running state [/etc/maas/rackd.conf] at time 06:29:41.289361
2019-05-14 06:29:41,289 [salt.state       :1813][INFO    ][31132] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-14 06:29:41,291 [salt.state       :300 ][INFO    ][31132] No changes needed to be made
2019-05-14 06:29:41,291 [salt.state       :1951][INFO    ][31132] Completed state [/etc/maas/rackd.conf] at time 06:29:41.291235 duration_in_ms=1.874
2019-05-14 06:29:41,291 [salt.state       :1780][INFO    ][31132] Running state [/etc/maas/rackd.conf] at time 06:29:41.291532
2019-05-14 06:29:41,291 [salt.state       :1813][INFO    ][31132] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-14 06:29:41,292 [salt.loaded.int.states.file:2298][WARNING ][31132] 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-05-14 06:29:41,292 [salt.state       :300 ][INFO    ][31132] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-14 06:29:41,293 [salt.state       :1951][INFO    ][31132] Completed state [/etc/maas/rackd.conf] at time 06:29:41.293029 duration_in_ms=1.497
2019-05-14 06:29:41,294 [salt.state       :1780][INFO    ][31132] Running state [maas-rackd] at time 06:29:41.294122
2019-05-14 06:29:41,294 [salt.state       :1813][INFO    ][31132] Executing state service.running for [maas-rackd]
2019-05-14 06:29:41,295 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31132] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-14 06:29:41,329 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31132] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-14 06:29:41,346 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31132] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-14 06:29:41,362 [salt.state       :300 ][INFO    ][31132] The service maas-rackd is already running
2019-05-14 06:29:41,363 [salt.state       :1951][INFO    ][31132] Completed state [maas-rackd] at time 06:29:41.363399 duration_in_ms=69.277
2019-05-14 06:29:41,365 [salt.minion      :1711][INFO    ][31132] Returning information for job: 20190514062939799611
2019-05-14 06:29:41,904 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command state.apply with jid 20190514062941890278
2019-05-14 06:29:41,927 [salt.minion      :1432][INFO    ][31164] Starting a new job with PID 31164
2019-05-14 06:29:42,703 [salt.state       :915 ][INFO    ][31164] Loading fresh modules for state activity
2019-05-14 06:29:43,369 [salt.state       :1780][INFO    ][31164] Running state [maas-region-controller] at time 06:29:43.369128
2019-05-14 06:29:43,369 [salt.state       :1813][INFO    ][31164] Executing state pkg.installed for [maas-region-controller]
2019-05-14 06:29:43,369 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31164] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-14 06:29:43,467 [salt.state       :300 ][INFO    ][31164] All specified packages are already installed
2019-05-14 06:29:43,467 [salt.state       :1951][INFO    ][31164] Completed state [maas-region-controller] at time 06:29:43.467653 duration_in_ms=98.525
2019-05-14 06:29:43,468 [salt.state       :1780][INFO    ][31164] Running state [python-oauth] at time 06:29:43.468029
2019-05-14 06:29:43,468 [salt.state       :1813][INFO    ][31164] Executing state pkg.installed for [python-oauth]
2019-05-14 06:29:43,475 [salt.state       :300 ][INFO    ][31164] All specified packages are already installed
2019-05-14 06:29:43,476 [salt.state       :1951][INFO    ][31164] Completed state [python-oauth] at time 06:29:43.476013 duration_in_ms=7.984
2019-05-14 06:29:43,479 [salt.state       :1780][INFO    ][31164] Running state [/etc/maas/regiond.conf] at time 06:29:43.479334
2019-05-14 06:29:43,479 [salt.state       :1813][INFO    ][31164] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-14 06:29:43,483 [salt.state       :300 ][INFO    ][31164] No changes needed to be made
2019-05-14 06:29:43,483 [salt.state       :1951][INFO    ][31164] Completed state [/etc/maas/regiond.conf] at time 06:29:43.483781 duration_in_ms=4.447
2019-05-14 06:29:43,484 [salt.state       :1780][INFO    ][31164] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:29:43.484166
2019-05-14 06:29:43,484 [salt.state       :1813][INFO    ][31164] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-14 06:29:43,522 [salt.state       :300 ][INFO    ][31164] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-14 06:29:43,522 [salt.state       :1951][INFO    ][31164] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:29:43.522764 duration_in_ms=38.598
2019-05-14 06:29:43,523 [salt.state       :1780][INFO    ][31164] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:29:43.523099
2019-05-14 06:29:43,523 [salt.state       :1813][INFO    ][31164] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-14 06:29:43,525 [salt.state       :300 ][INFO    ][31164] No changes needed to be made
2019-05-14 06:29:43,525 [salt.state       :1951][INFO    ][31164] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:29:43.525933 duration_in_ms=2.835
2019-05-14 06:29:43,526 [salt.state       :1780][INFO    ][31164] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:29:43.526260
2019-05-14 06:29:43,526 [salt.state       :1813][INFO    ][31164] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-14 06:29:43,528 [salt.state       :300 ][INFO    ][31164] No changes needed to be made
2019-05-14 06:29:43,528 [salt.state       :1951][INFO    ][31164] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:29:43.528896 duration_in_ms=2.637
2019-05-14 06:29:43,529 [salt.state       :1780][INFO    ][31164] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:29:43.529220
2019-05-14 06:29:43,529 [salt.state       :1813][INFO    ][31164] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-14 06:29:43,545 [salt.state       :300 ][INFO    ][31164] No changes needed to be made
2019-05-14 06:29:43,545 [salt.state       :1951][INFO    ][31164] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:29:43.545364 duration_in_ms=16.144
2019-05-14 06:29:43,545 [salt.state       :1780][INFO    ][31164] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:29:43.545696
2019-05-14 06:29:43,545 [salt.state       :1813][INFO    ][31164] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-14 06:29:43,555 [salt.state       :300 ][INFO    ][31164] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-14 06:29:43,555 [salt.state       :1951][INFO    ][31164] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:29:43.555555 duration_in_ms=9.859
2019-05-14 06:29:43,556 [salt.state       :1780][INFO    ][31164] Running state [a2enmod headers] at time 06:29:43.556395
2019-05-14 06:29:43,556 [salt.state       :1813][INFO    ][31164] Executing state cmd.run for [a2enmod headers]
2019-05-14 06:29:43,557 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31164] Executing command 'a2enmod headers' in directory '/root'
2019-05-14 06:29:43,611 [salt.state       :300 ][INFO    ][31164] {'pid': 31189, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-14 06:29:43,612 [salt.state       :1951][INFO    ][31164] Completed state [a2enmod headers] at time 06:29:43.611996 duration_in_ms=55.601
2019-05-14 06:29:43,612 [salt.state       :1780][INFO    ][31164] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:29:43.612285
2019-05-14 06:29:43,612 [salt.state       :1813][INFO    ][31164] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-14 06:29:43,625 [salt.state       :300 ][INFO    ][31164] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-14 06:29:43,625 [salt.state       :1951][INFO    ][31164] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:29:43.625298 duration_in_ms=13.013
2019-05-14 06:29:43,625 [salt.state       :1780][INFO    ][31164] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:29:43.625734
2019-05-14 06:29:43,625 [salt.state       :1813][INFO    ][31164] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-14 06:29:43,660 [salt.state       :300 ][INFO    ][31164] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-14 06:29:43,660 [salt.state       :1951][INFO    ][31164] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:29:43.660474 duration_in_ms=34.74
2019-05-14 06:29:43,660 [salt.state       :1780][INFO    ][31164] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:29:43.660855
2019-05-14 06:29:43,661 [salt.state       :1813][INFO    ][31164] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-14 06:29:43,699 [salt.state       :300 ][INFO    ][31164] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-14 06:29:43,700 [salt.state       :1951][INFO    ][31164] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:29:43.700344 duration_in_ms=39.489
2019-05-14 06:29:43,700 [salt.state       :1780][INFO    ][31164] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:29:43.700900
2019-05-14 06:29:43,701 [salt.state       :1813][INFO    ][31164] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-14 06:29:43,743 [salt.state       :300 ][INFO    ][31164] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-14 06:29:43,743 [salt.state       :1951][INFO    ][31164] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:29:43.743266 duration_in_ms=42.366
2019-05-14 06:29:43,743 [salt.state       :1780][INFO    ][31164] Running state [/root/.pgpass] at time 06:29:43.743451
2019-05-14 06:29:43,743 [salt.state       :1813][INFO    ][31164] Executing state file.managed for [/root/.pgpass]
2019-05-14 06:29:43,770 [salt.state       :300 ][INFO    ][31164] File /root/.pgpass is in the correct state
2019-05-14 06:29:43,770 [salt.state       :1951][INFO    ][31164] Completed state [/root/.pgpass] at time 06:29:43.770574 duration_in_ms=27.123
2019-05-14 06:29:43,774 [salt.state       :1780][INFO    ][31164] Running state [maas-region syncdb --noinput] at time 06:29:43.774157
2019-05-14 06:29:43,774 [salt.state       :1813][INFO    ][31164] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-14 06:29:43,774 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31164] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-14 06:29:45,784 [salt.state       :300 ][INFO    ][31164] {'pid': 31203, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sessions, metadataserver, sites, contenttypes, maasserver, auth, piston3\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-14 06:29:45,784 [salt.state       :1951][INFO    ][31164] Completed state [maas-region syncdb --noinput] at time 06:29:45.784812 duration_in_ms=2010.655
2019-05-14 06:29:45,785 [salt.state       :2022][WARNING ][31164] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-14 06:29:45,786 [salt.state       :1780][INFO    ][31164] Running state [maas-regiond] at time 06:29:45.786058
2019-05-14 06:29:45,786 [salt.state       :1813][INFO    ][31164] Executing state service.running for [maas-regiond]
2019-05-14 06:29:45,786 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31164] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-14 06:29:45,824 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31164] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-14 06:29:45,842 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31164] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-14 06:29:45,862 [salt.state       :300 ][INFO    ][31164] The service maas-regiond is already running
2019-05-14 06:29:45,862 [salt.state       :1951][INFO    ][31164] Completed state [maas-regiond] at time 06:29:45.862266 duration_in_ms=76.208
2019-05-14 06:29:45,863 [salt.state       :1780][INFO    ][31164] Running state [bind9] at time 06:29:45.863346
2019-05-14 06:29:45,863 [salt.state       :1813][INFO    ][31164] Executing state service.running for [bind9]
2019-05-14 06:29:45,864 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31164] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-14 06:29:45,883 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31164] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-14 06:29:45,900 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31164] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-14 06:29:45,915 [salt.state       :300 ][INFO    ][31164] The service bind9 is already running
2019-05-14 06:29:45,915 [salt.state       :1951][INFO    ][31164] Completed state [bind9] at time 06:29:45.915568 duration_in_ms=52.222
2019-05-14 06:29:45,916 [salt.state       :1780][INFO    ][31164] Running state [apache2] at time 06:29:45.916753
2019-05-14 06:29:45,917 [salt.state       :1813][INFO    ][31164] Executing state service.running for [apache2]
2019-05-14 06:29:45,917 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31164] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-14 06:29:45,932 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31164] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-14 06:29:45,947 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31164] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-14 06:29:45,968 [salt.state       :300 ][INFO    ][31164] The service apache2 is already running
2019-05-14 06:29:45,968 [salt.state       :1951][INFO    ][31164] Completed state [apache2] at time 06:29:45.968779 duration_in_ms=52.026
2019-05-14 06:29:45,970 [salt.state       :1780][INFO    ][31164] Running state [maasng.wait_for_http_code] at time 06:29:45.970334
2019-05-14 06:29:45,970 [salt.state       :1813][INFO    ][31164] Executing state module.run for [maasng.wait_for_http_code]
2019-05-14 06:29:45,971 [salt.utils.decorators:613 ][WARNING ][31164] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-14 06:29:46,084 [salt.state       :300 ][INFO    ][31164] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-14 06:29:46,084 [salt.state       :1951][INFO    ][31164] Completed state [maasng.wait_for_http_code] at time 06:29:46.084845 duration_in_ms=114.508
2019-05-14 06:29:46,086 [salt.state       :1780][INFO    ][31164] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:29:46.086796
2019-05-14 06:29:46,087 [salt.state       :1813][INFO    ][31164] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-14 06:29:46,088 [salt.state       :300 ][INFO    ][31164] /var/lib/maas/.setup_admin exists
2019-05-14 06:29:46,088 [salt.state       :1951][INFO    ][31164] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:29:46.088535 duration_in_ms=1.74
2019-05-14 06:29:46,089 [salt.state       :1780][INFO    ][31164] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:29:46.089635
2019-05-14 06:29:46,090 [salt.state       :1813][INFO    ][31164] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-14 06:29:46,091 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31164] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-14 06:29:47,450 [salt.state       :300 ][INFO    ][31164] {'pid': 31238, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-14 06:29:47,451 [salt.state       :1951][INFO    ][31164] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:29:47.451233 duration_in_ms=1361.599
2019-05-14 06:29:47,460 [salt.state       :1780][INFO    ][31164] Running state [maas_region_boot_source_resources_mirror] at time 06:29:47.460524
2019-05-14 06:29:47,461 [salt.state       :1813][INFO    ][31164] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-14 06:29:47,562 [salt.state       :300 ][INFO    ][31164] {'changes': {}}
2019-05-14 06:29:47,563 [salt.state       :1951][INFO    ][31164] Completed state [maas_region_boot_source_resources_mirror] at time 06:29:47.563281 duration_in_ms=102.756
2019-05-14 06:29:47,564 [salt.state       :1780][INFO    ][31164] Running state [maasng.boot_resources_import] at time 06:29:47.564866
2019-05-14 06:29:47,565 [salt.state       :1813][INFO    ][31164] Executing state module.run for [maasng.boot_resources_import]
2019-05-14 06:29:47,565 [salt.utils.decorators:613 ][WARNING ][31164] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-14 06:29:47,664 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-14 06:29:52,765 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-14 06:29:56,982 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514062956970051
2019-05-14 06:29:57,003 [salt.minion      :1432][INFO    ][31302] Starting a new job with PID 31302
2019-05-14 06:29:57,025 [salt.minion      :1711][INFO    ][31302] Returning information for job: 20190514062956970051
2019-05-14 06:29:57,825 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-05-14 06:30:02,899 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-05-14 06:30:07,963 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-05-14 06:30:13,014 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-05-14 06:30:18,082 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2019-05-14 06:30:23,142 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:864.0/900s
2019-05-14 06:30:27,024 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514063027010992
2019-05-14 06:30:27,048 [salt.minion      :1432][INFO    ][31506] Starting a new job with PID 31506
2019-05-14 06:30:27,072 [salt.minion      :1711][INFO    ][31506] Returning information for job: 20190514063027010992
2019-05-14 06:30:28,219 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2019-05-14 06:30:33,276 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-05-14 06:30:38,337 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-05-14 06:30:43,405 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-05-14 06:30:48,463 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-05-14 06:30:53,529 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-05-14 06:30:57,082 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514063057076087
2019-05-14 06:30:57,103 [salt.minion      :1432][INFO    ][31676] Starting a new job with PID 31676
2019-05-14 06:30:57,115 [salt.minion      :1711][INFO    ][31676] Returning information for job: 20190514063057076087
2019-05-14 06:30:58,585 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-05-14 06:31:03,646 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-05-14 06:31:08,707 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2019-05-14 06:31:13,769 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2019-05-14 06:31:18,834 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2019-05-14 06:31:23,919 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2019-05-14 06:31:27,122 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514063127109639
2019-05-14 06:31:27,146 [salt.minion      :1432][INFO    ][31842] Starting a new job with PID 31842
2019-05-14 06:31:27,172 [salt.minion      :1711][INFO    ][31842] Returning information for job: 20190514063127109639
2019-05-14 06:31:29,167 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:798.0/900s
2019-05-14 06:31:34,225 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:793.0/900s
2019-05-14 06:31:39,500 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:788.0/900s
2019-05-14 06:31:44,569 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:783.0/900s
2019-05-14 06:31:49,630 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:778.0/900s
2019-05-14 06:31:54,703 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:773.0/900s
2019-05-14 06:31:57,177 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514063157165712
2019-05-14 06:31:57,201 [salt.minion      :1432][INFO    ][31989] Starting a new job with PID 31989
2019-05-14 06:31:57,226 [salt.minion      :1711][INFO    ][31989] Returning information for job: 20190514063157165712
2019-05-14 06:31:59,775 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:768.0/900s
2019-05-14 06:32:04,846 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:763.0/900s
2019-05-14 06:32:10,554 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:757.0/900s
2019-05-14 06:32:16,478 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:751.0/900s
2019-05-14 06:32:21,985 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:746.0/900s
2019-05-14 06:32:27,237 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514063227224330
2019-05-14 06:32:27,260 [salt.minion      :1432][INFO    ][32190] Starting a new job with PID 32190
2019-05-14 06:32:27,286 [salt.minion      :1711][INFO    ][32190] Returning information for job: 20190514063227224330
2019-05-14 06:32:28,150 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:739.0/900s
2019-05-14 06:32:33,209 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:734.0/900s
2019-05-14 06:32:38,297 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:729.0/900s
2019-05-14 06:32:43,359 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:724.0/900s
2019-05-14 06:32:48,425 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:719.0/900s
2019-05-14 06:32:53,500 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:714.0/900s
2019-05-14 06:32:57,305 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514063257291437
2019-05-14 06:32:57,328 [salt.minion      :1432][INFO    ][32374] Starting a new job with PID 32374
2019-05-14 06:32:57,351 [salt.minion      :1711][INFO    ][32374] Returning information for job: 20190514063257291437
2019-05-14 06:32:58,565 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:709.0/900s
2019-05-14 06:33:03,621 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:704.0/900s
2019-05-14 06:33:08,686 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:699.0/900s
2019-05-14 06:33:13,733 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:694.0/900s
2019-05-14 06:33:18,948 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:689.0/900s
2019-05-14 06:33:24,009 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:684.0/900s
2019-05-14 06:33:27,377 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514063327368426
2019-05-14 06:33:27,396 [salt.minion      :1432][INFO    ][32625] Starting a new job with PID 32625
2019-05-14 06:33:27,415 [salt.minion      :1711][INFO    ][32625] Returning information for job: 20190514063327368426
2019-05-14 06:33:29,082 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:679.0/900s
2019-05-14 06:33:34,134 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:673.0/900s
2019-05-14 06:33:40,122 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:667.0/900s
2019-05-14 06:33:45,181 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:662.0/900s
2019-05-14 06:33:50,243 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:657.0/900s
2019-05-14 06:33:55,320 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:652.0/900s
2019-05-14 06:33:57,448 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514063357436288
2019-05-14 06:33:57,469 [salt.minion      :1432][INFO    ][351] Starting a new job with PID 351
2019-05-14 06:33:57,486 [salt.minion      :1711][INFO    ][351] Returning information for job: 20190514063357436288
2019-05-14 06:34:00,376 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:647.0/900s
2019-05-14 06:34:05,431 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:642.0/900s
2019-05-14 06:34:10,506 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:637.0/900s
2019-05-14 06:34:15,595 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:632.0/900s
2019-05-14 06:34:21,067 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:627.0/900s
2019-05-14 06:34:26,115 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:622.0/900s
2019-05-14 06:34:27,521 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514063427515678
2019-05-14 06:34:27,539 [salt.minion      :1432][INFO    ][605] Starting a new job with PID 605
2019-05-14 06:34:27,565 [salt.minion      :1711][INFO    ][605] Returning information for job: 20190514063427515678
2019-05-14 06:34:32,165 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:615.0/900s
2019-05-14 06:34:37,222 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:610.0/900s
2019-05-14 06:34:45,064 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:603.0/900s
2019-05-14 06:34:50,112 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:598.0/900s
2019-05-14 06:34:55,185 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:592.0/900s
2019-05-14 06:34:57,610 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514063457598726
2019-05-14 06:34:57,631 [salt.minion      :1432][INFO    ][832] Starting a new job with PID 832
2019-05-14 06:34:57,655 [salt.minion      :1711][INFO    ][832] Returning information for job: 20190514063457598726
2019-05-14 06:35:00,241 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:587.0/900s
2019-05-14 06:35:05,304 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:582.0/900s
2019-05-14 06:35:10,604 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:577.0/900s
2019-05-14 06:35:15,676 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:572.0/900s
2019-05-14 06:35:20,920 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:567.0/900s
2019-05-14 06:35:25,990 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:562.0/900s
2019-05-14 06:35:27,695 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514063527688094
2019-05-14 06:35:27,711 [salt.minion      :1432][INFO    ][1080] Starting a new job with PID 1080
2019-05-14 06:35:27,725 [salt.minion      :1711][INFO    ][1080] Returning information for job: 20190514063527688094
2019-05-14 06:35:31,216 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:556.0/900s
2019-05-14 06:35:36,380 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:551.0/900s
2019-05-14 06:35:41,443 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:546.0/900s
2019-05-14 06:35:46,514 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:541.0/900s
2019-05-14 06:35:51,586 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:536.0/900s
2019-05-14 06:35:56,639 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:531.0/900s
2019-05-14 06:35:57,770 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514063557758457
2019-05-14 06:35:57,791 [salt.minion      :1432][INFO    ][1287] Starting a new job with PID 1287
2019-05-14 06:35:57,831 [salt.minion      :1711][INFO    ][1287] Returning information for job: 20190514063557758457
2019-05-14 06:36:01,694 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:526.0/900s
2019-05-14 06:36:06,785 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:521.0/900s
2019-05-14 06:36:11,858 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:516.0/900s
2019-05-14 06:36:16,920 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:511.0/900s
2019-05-14 06:36:23,098 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:505.0/900s
2019-05-14 06:36:27,877 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514063627866896
2019-05-14 06:36:27,893 [salt.minion      :1432][INFO    ][1537] Starting a new job with PID 1537
2019-05-14 06:36:27,908 [salt.minion      :1711][INFO    ][1537] Returning information for job: 20190514063627866896
2019-05-14 06:36:28,158 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:499.0/900s
2019-05-14 06:36:33,207 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:494.0/900s
2019-05-14 06:36:38,653 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:489.0/900s
2019-05-14 06:36:43,771 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:484.0/900s
2019-05-14 06:36:49,736 [salt.loaded.ext.module.maasng:1600][INFO    ][31164] Waiting boot-resources import done
sleep for:5s Left:478.0/900s
2019-05-14 06:36:54,847 [salt.state       :300 ][INFO    ][31164] {'ret': True}
2019-05-14 06:36:54,847 [salt.state       :1951][INFO    ][31164] Completed state [maasng.boot_resources_import] at time 06:36:54.847544 duration_in_ms=427282.677
2019-05-14 06:36:54,848 [salt.state       :1780][INFO    ][31164] Running state [maas_region_boot_sources_selection_xenial] at time 06:36:54.848787
2019-05-14 06:36:54,849 [salt.state       :1813][INFO    ][31164] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-14 06:36:55,044 [salt.state       :300 ][INFO    ][31164] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-14 06:36:55,045 [salt.state       :1951][INFO    ][31164] Completed state [maas_region_boot_sources_selection_xenial] at time 06:36:55.045137 duration_in_ms=196.351
2019-05-14 06:36:55,046 [salt.state       :1780][INFO    ][31164] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 06:36:55.046611
2019-05-14 06:36:55,047 [salt.state       :1813][INFO    ][31164] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-14 06:36:55,047 [salt.utils.decorators:613 ][WARNING ][31164] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-14 06:36:55,048 [salt.loaded.ext.module.maasng:1771][INFO    ][31164] boot-sources sync initiated for ALL Rack's
2019-05-14 06:36:55,735 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-05-14 06:36:57,964 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514063657950766
2019-05-14 06:36:57,987 [salt.minion      :1432][INFO    ][1728] Starting a new job with PID 1728
2019-05-14 06:36:58,022 [salt.minion      :1711][INFO    ][1728] Returning information for job: 20190514063657950766
2019-05-14 06:37:01,187 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:894.0/900s
2019-05-14 06:37:06,786 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:888.0/900s
2019-05-14 06:37:12,303 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:883.0/900s
2019-05-14 06:37:17,734 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:878.0/900s
2019-05-14 06:37:24,410 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:871.0/900s
2019-05-14 06:37:28,080 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514063728066796
2019-05-14 06:37:28,095 [salt.minion      :1432][INFO    ][1802] Starting a new job with PID 1802
2019-05-14 06:37:28,118 [salt.minion      :1711][INFO    ][1802] Returning information for job: 20190514063728066796
2019-05-14 06:37:30,021 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:865.0/900s
2019-05-14 06:37:35,386 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:860.0/900s
2019-05-14 06:37:40,955 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:854.0/900s
2019-05-14 06:37:46,512 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:849.0/900s
2019-05-14 06:37:52,105 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:843.0/900s
2019-05-14 06:37:57,698 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:838.0/900s
2019-05-14 06:37:58,171 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514063758159654
2019-05-14 06:37:58,193 [salt.minion      :1432][INFO    ][1846] Starting a new job with PID 1846
2019-05-14 06:37:58,218 [salt.minion      :1711][INFO    ][1846] Returning information for job: 20190514063758159654
2019-05-14 06:38:03,111 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:832.0/900s
2019-05-14 06:38:08,621 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:827.0/900s
2019-05-14 06:38:14,069 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:821.0/900s
2019-05-14 06:38:19,656 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:816.0/900s
2019-05-14 06:38:25,250 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:810.0/900s
2019-05-14 06:38:28,293 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514063828281918
2019-05-14 06:38:28,315 [salt.minion      :1432][INFO    ][1948] Starting a new job with PID 1948
2019-05-14 06:38:28,341 [salt.minion      :1711][INFO    ][1948] Returning information for job: 20190514063828281918
2019-05-14 06:38:30,740 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:805.0/900s
2019-05-14 06:38:36,344 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:799.0/900s
2019-05-14 06:38:41,942 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:793.0/900s
2019-05-14 06:38:47,403 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:788.0/900s
2019-05-14 06:38:53,008 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:782.0/900s
2019-05-14 06:38:58,414 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514063858401103
2019-05-14 06:38:58,439 [salt.minion      :1432][INFO    ][2151] Starting a new job with PID 2151
2019-05-14 06:38:58,471 [salt.minion      :1711][INFO    ][2151] Returning information for job: 20190514063858401103
2019-05-14 06:38:58,571 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:777.0/900s
2019-05-14 06:39:04,152 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:771.0/900s
2019-05-14 06:39:09,757 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:765.0/900s
2019-05-14 06:39:15,374 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:760.0/900s
2019-05-14 06:39:20,967 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:754.0/900s
2019-05-14 06:39:26,583 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:749.0/900s
2019-05-14 06:39:28,554 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514063928540402
2019-05-14 06:39:28,577 [salt.minion      :1432][INFO    ][2216] Starting a new job with PID 2216
2019-05-14 06:39:28,601 [salt.minion      :1711][INFO    ][2216] Returning information for job: 20190514063928540402
2019-05-14 06:39:32,152 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:743.0/900s
2019-05-14 06:39:37,751 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:738.0/900s
2019-05-14 06:39:43,356 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:732.0/900s
2019-05-14 06:39:48,931 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:726.0/900s
2019-05-14 06:39:54,535 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:721.0/900s
2019-05-14 06:39:58,690 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514063958677286
2019-05-14 06:39:58,714 [salt.minion      :1432][INFO    ][2242] Starting a new job with PID 2242
2019-05-14 06:39:58,739 [salt.minion      :1711][INFO    ][2242] Returning information for job: 20190514063958677286
2019-05-14 06:40:00,098 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:715.0/900s
2019-05-14 06:40:05,709 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:710.0/900s
2019-05-14 06:40:11,310 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:704.0/900s
2019-05-14 06:40:16,915 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:698.0/900s
2019-05-14 06:40:22,477 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:693.0/900s
2019-05-14 06:40:28,017 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:687.0/900s
2019-05-14 06:40:28,836 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514064028822810
2019-05-14 06:40:28,859 [salt.minion      :1432][INFO    ][2309] Starting a new job with PID 2309
2019-05-14 06:40:28,881 [salt.minion      :1711][INFO    ][2309] Returning information for job: 20190514064028822810
2019-05-14 06:40:33,771 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:681.0/900s
2019-05-14 06:40:39,328 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:676.0/900s
2019-05-14 06:40:44,927 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:670.0/900s
2019-05-14 06:40:50,484 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:665.0/900s
2019-05-14 06:40:56,065 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:659.0/900s
2019-05-14 06:40:58,984 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514064058971281
2019-05-14 06:40:59,008 [salt.minion      :1432][INFO    ][2339] Starting a new job with PID 2339
2019-05-14 06:40:59,033 [salt.minion      :1711][INFO    ][2339] Returning information for job: 20190514064058971281
2019-05-14 06:41:01,694 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:654.0/900s
2019-05-14 06:41:07,268 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:648.0/900s
2019-05-14 06:41:12,861 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:642.0/900s
2019-05-14 06:41:18,406 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:637.0/900s
2019-05-14 06:41:23,969 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:631.0/900s
2019-05-14 06:41:29,146 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514064129134832
2019-05-14 06:41:29,166 [salt.minion      :1432][INFO    ][2413] Starting a new job with PID 2413
2019-05-14 06:41:29,188 [salt.minion      :1711][INFO    ][2413] Returning information for job: 20190514064129134832
2019-05-14 06:41:29,592 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:626.0/900s
2019-05-14 06:41:35,191 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:620.0/900s
2019-05-14 06:41:40,796 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:614.0/900s
2019-05-14 06:41:46,394 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:609.0/900s
2019-05-14 06:41:51,993 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:603.0/900s
2019-05-14 06:41:57,598 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:598.0/900s
2019-05-14 06:41:59,306 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514064159294303
2019-05-14 06:41:59,326 [salt.minion      :1432][INFO    ][2448] Starting a new job with PID 2448
2019-05-14 06:41:59,350 [salt.minion      :1711][INFO    ][2448] Returning information for job: 20190514064159294303
2019-05-14 06:42:03,227 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:592.0/900s
2019-05-14 06:42:08,766 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:586.0/900s
2019-05-14 06:42:14,329 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:581.0/900s
2019-05-14 06:42:19,940 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:575.0/900s
2019-05-14 06:42:25,526 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:570.0/900s
2019-05-14 06:42:29,481 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514064229468156
2019-05-14 06:42:29,504 [salt.minion      :1432][INFO    ][2498] Starting a new job with PID 2498
2019-05-14 06:42:29,528 [salt.minion      :1711][INFO    ][2498] Returning information for job: 20190514064229468156
2019-05-14 06:42:31,141 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:564.0/900s
2019-05-14 06:42:36,703 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:559.0/900s
2019-05-14 06:42:42,302 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:553.0/900s
2019-05-14 06:42:47,901 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:547.0/900s
2019-05-14 06:42:53,488 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:542.0/900s
2019-05-14 06:42:59,237 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:536.0/900s
2019-05-14 06:42:59,672 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514064259659078
2019-05-14 06:42:59,694 [salt.minion      :1432][INFO    ][2534] Starting a new job with PID 2534
2019-05-14 06:42:59,719 [salt.minion      :1711][INFO    ][2534] Returning information for job: 20190514064259659078
2019-05-14 06:43:04,643 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:531.0/900s
2019-05-14 06:43:10,183 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:525.0/900s
2019-05-14 06:43:15,788 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:519.0/900s
2019-05-14 06:43:21,386 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:514.0/900s
2019-05-14 06:43:26,973 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:508.0/900s
2019-05-14 06:43:29,871 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514064329858571
2019-05-14 06:43:29,896 [salt.minion      :1432][INFO    ][2589] Starting a new job with PID 2589
2019-05-14 06:43:29,920 [salt.minion      :1711][INFO    ][2589] Returning information for job: 20190514064329858571
2019-05-14 06:43:32,542 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:503.0/900s
2019-05-14 06:43:38,141 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:497.0/900s
2019-05-14 06:43:43,746 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:492.0/900s
2019-05-14 06:43:49,356 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:486.0/900s
2019-05-14 06:43:54,943 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:480.0/900s
2019-05-14 06:44:00,083 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514064400070346
2019-05-14 06:44:00,106 [salt.minion      :1432][INFO    ][2627] Starting a new job with PID 2627
2019-05-14 06:44:00,130 [salt.minion      :1711][INFO    ][2627] Returning information for job: 20190514064400070346
2019-05-14 06:44:00,386 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:475.0/900s
2019-05-14 06:44:06,071 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:469.0/900s
2019-05-14 06:44:11,669 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:464.0/900s
2019-05-14 06:44:17,260 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:458.0/900s
2019-05-14 06:44:22,842 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:452.0/900s
2019-05-14 06:44:28,465 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:447.0/900s
2019-05-14 06:44:30,303 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514064430289806
2019-05-14 06:44:30,327 [salt.minion      :1432][INFO    ][2680] Starting a new job with PID 2680
2019-05-14 06:44:30,351 [salt.minion      :1711][INFO    ][2680] Returning information for job: 20190514064430289806
2019-05-14 06:44:34,057 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:441.0/900s
2019-05-14 06:44:39,668 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:436.0/900s
2019-05-14 06:44:45,243 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:430.0/900s
2019-05-14 06:44:50,848 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:424.0/900s
2019-05-14 06:44:56,440 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:419.0/900s
2019-05-14 06:45:00,325 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514064500311622
2019-05-14 06:45:00,348 [salt.minion      :1432][INFO    ][2716] Starting a new job with PID 2716
2019-05-14 06:45:00,371 [salt.minion      :1711][INFO    ][2716] Returning information for job: 20190514064500311622
2019-05-14 06:45:02,033 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:413.0/900s
2019-05-14 06:45:07,525 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:408.0/900s
2019-05-14 06:45:12,949 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:402.0/900s
2019-05-14 06:45:18,543 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:397.0/900s
2019-05-14 06:45:24,165 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:391.0/900s
2019-05-14 06:45:29,776 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:385.0/900s
2019-05-14 06:45:30,360 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514064530347262
2019-05-14 06:45:30,384 [salt.minion      :1432][INFO    ][2770] Starting a new job with PID 2770
2019-05-14 06:45:30,410 [salt.minion      :1711][INFO    ][2770] Returning information for job: 20190514064530347262
2019-05-14 06:45:35,393 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:380.0/900s
2019-05-14 06:45:41,028 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:374.0/900s
2019-05-14 06:45:46,590 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:369.0/900s
2019-05-14 06:45:52,111 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:363.0/900s
2019-05-14 06:45:57,710 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:358.0/900s
2019-05-14 06:46:00,406 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514064600393497
2019-05-14 06:46:00,429 [salt.minion      :1432][INFO    ][2805] Starting a new job with PID 2805
2019-05-14 06:46:00,453 [salt.minion      :1711][INFO    ][2805] Returning information for job: 20190514064600393497
2019-05-14 06:46:03,309 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:352.0/900s
2019-05-14 06:46:08,913 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:346.0/900s
2019-05-14 06:46:14,555 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:341.0/900s
2019-05-14 06:46:20,171 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:335.0/900s
2019-05-14 06:46:25,770 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:329.0/900s
2019-05-14 06:46:30,465 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514064630453151
2019-05-14 06:46:30,489 [salt.minion      :1432][INFO    ][2856] Starting a new job with PID 2856
2019-05-14 06:46:30,515 [salt.minion      :1711][INFO    ][2856] Returning information for job: 20190514064630453151
2019-05-14 06:46:31,387 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:324.0/900s
2019-05-14 06:46:36,998 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:318.0/900s
2019-05-14 06:46:42,578 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:313.0/900s
2019-05-14 06:46:48,111 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:307.0/900s
2019-05-14 06:46:53,705 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:302.0/900s
2019-05-14 06:46:59,285 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:296.0/900s
2019-05-14 06:47:00,538 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514064700525633
2019-05-14 06:47:00,561 [salt.minion      :1432][INFO    ][2893] Starting a new job with PID 2893
2019-05-14 06:47:00,586 [salt.minion      :1711][INFO    ][2893] Returning information for job: 20190514064700525633
2019-05-14 06:47:04,842 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:290.0/900s
2019-05-14 06:47:10,451 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:285.0/900s
2019-05-14 06:47:16,065 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:279.0/900s
2019-05-14 06:47:21,660 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:274.0/900s
2019-05-14 06:47:27,265 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:268.0/900s
2019-05-14 06:47:30,622 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514064730608876
2019-05-14 06:47:30,646 [salt.minion      :1432][INFO    ][2956] Starting a new job with PID 2956
2019-05-14 06:47:30,672 [salt.minion      :1711][INFO    ][2956] Returning information for job: 20190514064730608876
2019-05-14 06:47:32,864 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:262.0/900s
2019-05-14 06:47:38,475 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:257.0/900s
2019-05-14 06:47:44,067 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:251.0/900s
2019-05-14 06:47:49,661 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:246.0/900s
2019-05-14 06:47:55,259 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:240.0/900s
2019-05-14 06:48:00,714 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514064800703204
2019-05-14 06:48:00,739 [salt.minion      :1432][INFO    ][3001] Starting a new job with PID 3001
2019-05-14 06:48:00,764 [salt.minion      :1711][INFO    ][3001] Returning information for job: 20190514064800703204
2019-05-14 06:48:00,834 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:234.0/900s
2019-05-14 06:48:06,325 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:229.0/900s
2019-05-14 06:48:11,948 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:223.0/900s
2019-05-14 06:48:17,559 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:218.0/900s
2019-05-14 06:48:23,116 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:212.0/900s
2019-05-14 06:48:28,709 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:207.0/900s
2019-05-14 06:48:30,822 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514064830809433
2019-05-14 06:48:30,847 [salt.minion      :1432][INFO    ][3076] Starting a new job with PID 3076
2019-05-14 06:48:30,872 [salt.minion      :1711][INFO    ][3076] Returning information for job: 20190514064830809433
2019-05-14 06:48:34,277 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:201.0/900s
2019-05-14 06:48:39,888 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:195.0/900s
2019-05-14 06:48:45,488 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:190.0/900s
2019-05-14 06:48:51,068 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:184.0/900s
2019-05-14 06:48:56,697 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:179.0/900s
2019-05-14 06:49:00,943 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514064900930636
2019-05-14 06:49:00,966 [salt.minion      :1432][INFO    ][3112] Starting a new job with PID 3112
2019-05-14 06:49:00,991 [salt.minion      :1711][INFO    ][3112] Returning information for job: 20190514064900930636
2019-05-14 06:49:02,320 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:173.0/900s
2019-05-14 06:49:07,895 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:167.0/900s
2019-05-14 06:49:13,506 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:162.0/900s
2019-05-14 06:49:19,110 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:156.0/900s
2019-05-14 06:49:24,697 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:151.0/900s
2019-05-14 06:49:30,254 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:145.0/900s
2019-05-14 06:49:31,084 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514064931071001
2019-05-14 06:49:31,110 [salt.minion      :1432][INFO    ][3168] Starting a new job with PID 3168
2019-05-14 06:49:31,135 [salt.minion      :1711][INFO    ][3168] Returning information for job: 20190514064931071001
2019-05-14 06:49:35,823 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:139.0/900s
2019-05-14 06:49:41,440 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:134.0/900s
2019-05-14 06:49:46,907 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:128.0/900s
2019-05-14 06:49:52,476 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:123.0/900s
2019-05-14 06:49:58,087 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:117.0/900s
2019-05-14 06:50:01,234 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514065001221574
2019-05-14 06:50:01,259 [salt.minion      :1432][INFO    ][3204] Starting a new job with PID 3204
2019-05-14 06:50:01,284 [salt.minion      :1711][INFO    ][3204] Returning information for job: 20190514065001221574
2019-05-14 06:50:03,644 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:112.0/900s
2019-05-14 06:50:09,255 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:106.0/900s
2019-05-14 06:50:14,823 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:100.0/900s
2019-05-14 06:50:20,386 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:95.0/900s
2019-05-14 06:50:25,979 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:89.0/900s
2019-05-14 06:50:31,402 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514065031389639
2019-05-14 06:50:31,423 [salt.minion      :1432][INFO    ][3296] Starting a new job with PID 3296
2019-05-14 06:50:31,448 [salt.minion      :1711][INFO    ][3296] Returning information for job: 20190514065031389639
2019-05-14 06:50:31,518 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:84.0/900s
2019-05-14 06:50:37,099 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:78.0/900s
2019-05-14 06:50:42,698 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:73.0/900s
2019-05-14 06:50:48,292 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:67.0/900s
2019-05-14 06:50:53,903 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:61.0/900s
2019-05-14 06:50:59,454 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:56.0/900s
2019-05-14 06:51:01,580 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514065101568205
2019-05-14 06:51:01,605 [salt.minion      :1432][INFO    ][3388] Starting a new job with PID 3388
2019-05-14 06:51:01,630 [salt.minion      :1711][INFO    ][3388] Returning information for job: 20190514065101568205
2019-05-14 06:51:05,041 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:50.0/900s
2019-05-14 06:51:10,663 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:45.0/900s
2019-05-14 06:51:16,250 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:39.0/900s
2019-05-14 06:51:21,831 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:33.0/900s
2019-05-14 06:51:27,442 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:28.0/900s
2019-05-14 06:51:31,778 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514065131765537
2019-05-14 06:51:31,801 [salt.minion      :1432][INFO    ][3467] Starting a new job with PID 3467
2019-05-14 06:51:31,825 [salt.minion      :1711][INFO    ][3467] Returning information for job: 20190514065131765537
2019-05-14 06:51:33,082 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:22.0/900s
2019-05-14 06:51:38,567 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:17.0/900s
2019-05-14 06:51:44,166 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:11.0/900s
2019-05-14 06:51:49,735 [salt.loaded.ext.module.maasng:1878][INFO    ][31164] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:6.0/900s
2019-05-14 06:51:55,340 [salt.state       :300 ][INFO    ][31164] {'ret': True}
2019-05-14 06:51:55,341 [salt.state       :1951][INFO    ][31164] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 06:51:55.341201 duration_in_ms=900294.587
2019-05-14 06:51:55,344 [salt.state       :1780][INFO    ][31164] Running state [maas.process_maas_config] at time 06:51:55.343965
2019-05-14 06:51:55,344 [salt.state       :1813][INFO    ][31164] Executing state module.run for [maas.process_maas_config]
2019-05-14 06:51:55,345 [salt.utils.decorators:613 ][WARNING ][31164] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-14 06:51:55,346 [salt.loaded.ext.module.maas:92  ][INFO    ][31164] maasconfig name=enable_http_proxy value=True
2019-05-14 06:51:55,408 [salt.loaded.ext.module.maas:92  ][INFO    ][31164] maasconfig name=upstream_dns value=8.8.8.8
2019-05-14 06:51:55,466 [salt.loaded.ext.module.maas:92  ][INFO    ][31164] maasconfig name=commissioning_distro_series value=xenial
2019-05-14 06:51:55,551 [salt.loaded.ext.module.maas:92  ][INFO    ][31164] maasconfig name=default_osystem value=ubuntu
2019-05-14 06:51:55,616 [salt.loaded.ext.module.maas:92  ][INFO    ][31164] maasconfig name=active_discovery_interval value=600
2019-05-14 06:51:55,670 [salt.loaded.ext.module.maas:92  ][INFO    ][31164] maasconfig name=dnssec_validation value=no
2019-05-14 06:51:55,737 [salt.loaded.ext.module.maas:92  ][INFO    ][31164] maasconfig name=maas_name value=mas01
2019-05-14 06:51:55,790 [salt.loaded.ext.module.maas:92  ][INFO    ][31164] maasconfig name=network_discovery value=enabled
2019-05-14 06:51:55,892 [salt.loaded.ext.module.maas:92  ][INFO    ][31164] maasconfig name=enable_third_party_drivers value=True
2019-05-14 06:51:58,657 [salt.loaded.ext.module.maas:92  ][INFO    ][31164] maasconfig name=default_storage_layout value=lvm
2019-05-14 06:51:58,718 [salt.loaded.ext.module.maas:92  ][INFO    ][31164] maasconfig name=ntp_external_only value=True
2019-05-14 06:51:58,773 [salt.loaded.ext.module.maas:92  ][INFO    ][31164] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-14 06:51:58,840 [salt.loaded.ext.module.maas:92  ][INFO    ][31164] maasconfig name=default_distro_series value=xenial
2019-05-14 06:51:58,904 [salt.loaded.ext.module.maas:92  ][INFO    ][31164] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-14 06:51:59,197 [salt.state       :300 ][INFO    ][31164] {'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-05-14 06:51:59,198 [salt.state       :1951][INFO    ][31164] Completed state [maas.process_maas_config] at time 06:51:59.198186 duration_in_ms=3854.221
2019-05-14 06:51:59,199 [salt.state       :1780][INFO    ][31164] Running state [pxe_admin] at time 06:51:59.199034
2019-05-14 06:51:59,199 [salt.state       :1813][INFO    ][31164] Executing state maasng.fabric_present for [pxe_admin]
2019-05-14 06:51:59,250 [salt.loaded.ext.module.maasng:945 ][INFO    ][31164] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rmfsdh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-14 06:51:59,316 [salt.loaded.ext.module.maasng:1008][WARNING ][31164] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-14 06:51:59,317 [salt.loaded.ext.module.maasng:1011][WARNING ][31164] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-14 06:51:59,382 [salt.state       :300 ][INFO    ][31164] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-14 06:51:59,383 [salt.state       :1951][INFO    ][31164] Completed state [pxe_admin] at time 06:51:59.383003 duration_in_ms=183.967
2019-05-14 06:51:59,383 [salt.state       :1780][INFO    ][31164] Running state [vlan 0] at time 06:51:59.383429
2019-05-14 06:51:59,383 [salt.state       :1813][INFO    ][31164] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-14 06:51:59,449 [salt.loaded.ext.module.maasng:945 ][INFO    ][31164] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/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'primary_rack': u'rmfsdh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-14 06:51:59,575 [salt.loaded.ext.module.maasng:945 ][INFO    ][31164] [{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'name': u'untagged', 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'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'name': u'untagged', 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'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'rmfsdh', u'name': u'untagged', 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/', u'class_type': u''}]
2019-05-14 06:51:59,820 [salt.loaded.ext.module.maasng:945 ][INFO    ][31164] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'rmfsdh', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-14 06:51:59,880 [salt.state       :300 ][INFO    ][31164] {'new': 'Vlan untagged was updated'}
2019-05-14 06:51:59,881 [salt.state       :1951][INFO    ][31164] Completed state [vlan 0] at time 06:51:59.881320 duration_in_ms=497.89
2019-05-14 06:51:59,882 [salt.state       :1780][INFO    ][31164] Running state [192.168.11.0/24] at time 06:51:59.882896
2019-05-14 06:51:59,883 [salt.state       :1813][INFO    ][31164] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-14 06:52:00,107 [salt.loaded.ext.module.maasng:945 ][INFO    ][31164] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'rmfsdh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-14 06:52:00,108 [salt.loaded.ext.module.maasng:1235][WARNING ][31164] Ignoring parameter vlan:0
2019-05-14 06:52:00,198 [salt.state       :300 ][INFO    ][31164] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-14 06:52:00,199 [salt.state       :1951][INFO    ][31164] Completed state [192.168.11.0/24] at time 06:52:00.198968 duration_in_ms=316.072
2019-05-14 06:52:00,200 [salt.state       :1780][INFO    ][31164] Running state [maas_create_iprange_1] at time 06:52:00.200300
2019-05-14 06:52:00,200 [salt.state       :1813][INFO    ][31164] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-14 06:52:00,270 [salt.state       :300 ][INFO    ][31164] Iprange maas_create_iprange_1 already exist.
2019-05-14 06:52:00,271 [salt.state       :1951][INFO    ][31164] Completed state [maas_create_iprange_1] at time 06:52:00.271002 duration_in_ms=70.702
2019-05-14 06:52:00,271 [salt.state       :1780][INFO    ][31164] Running state [vlan 0] at time 06:52:00.271435
2019-05-14 06:52:00,271 [salt.state       :1813][INFO    ][31164] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-14 06:52:00,340 [salt.loaded.ext.module.maasng:945 ][INFO    ][31164] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'rmfsdh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-14 06:52:00,455 [salt.loaded.ext.module.maasng:945 ][INFO    ][31164] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rmfsdh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-14 06:52:00,689 [salt.loaded.ext.module.maasng:945 ][INFO    ][31164] [{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'name': u'untagged', 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'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'name': u'untagged', 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'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'rmfsdh', u'name': u'untagged', 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/', u'class_type': u''}]
2019-05-14 06:52:00,790 [salt.state       :300 ][INFO    ][31164] {'new': 'Vlan untagged was updated'}
2019-05-14 06:52:00,791 [salt.state       :1951][INFO    ][31164] Completed state [vlan 0] at time 06:52:00.790949 duration_in_ms=519.514
2019-05-14 06:52:00,791 [salt.state       :1780][INFO    ][31164] Running state [opnfv] at time 06:52:00.791767
2019-05-14 06:52:00,792 [salt.state       :1813][INFO    ][31164] Executing state maasng.sshkey_present for [opnfv]
2019-05-14 06:52:00,837 [salt.loaded.ext.module.maasng:1903][INFO    ][31164] [{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-05-14 06:52:00,838 [salt.state       :300 ][INFO    ][31164] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-05-14 06:52:00,838 [salt.state       :1951][INFO    ][31164] Completed state [opnfv] at time 06:52:00.838416 duration_in_ms=46.647
2019-05-14 06:52:00,839 [salt.state       :1780][INFO    ][31164] Running state [maas.process_tags] at time 06:52:00.839336
2019-05-14 06:52:00,839 [salt.state       :1813][INFO    ][31164] Executing state module.run for [maas.process_tags]
2019-05-14 06:52:00,840 [salt.utils.decorators:613 ][WARNING ][31164] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-14 06:52:00,885 [salt.loaded.ext.module.maas:92  ][INFO    ][31164] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-05-14 06:52:00,940 [salt.state       :300 ][INFO    ][31164] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-05-14 06:52:00,940 [salt.state       :1951][INFO    ][31164] Completed state [maas.process_tags] at time 06:52:00.940356 duration_in_ms=101.02
2019-05-14 06:52:00,943 [salt.minion      :1711][INFO    ][31164] Returning information for job: 20190514062941890278
2019-05-14 06:52:01,563 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command state.apply with jid 20190514065201551265
2019-05-14 06:52:01,582 [salt.minion      :1432][INFO    ][3593] Starting a new job with PID 3593
2019-05-14 06:52:05,165 [salt.state       :915 ][INFO    ][3593] Loading fresh modules for state activity
2019-05-14 06:52:05,264 [salt.state       :1780][INFO    ][3593] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:52:05.264512
2019-05-14 06:52:05,264 [salt.state       :1813][INFO    ][3593] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-14 06:52:05,267 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3593] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-14 06:52:06,718 [salt.state       :300 ][INFO    ][3593] {'pid': 3618, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-14 06:52:06,718 [salt.state       :1951][INFO    ][3593] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:52:06.718459 duration_in_ms=1453.948
2019-05-14 06:52:06,719 [salt.state       :1780][INFO    ][3593] Running state [maas.process_machines] at time 06:52:06.719655
2019-05-14 06:52:06,719 [salt.state       :1813][INFO    ][3593] Executing state module.run for [maas.process_machines]
2019-05-14 06:52:06,720 [salt.utils.decorators:613 ][WARNING ][3593] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-14 06:52:07,243 [salt.loaded.ext.module.maas:412 ][WARNING ][3593] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-14 06:52:07,243 [salt.loaded.ext.module.maas:92  ][INFO    ][3593] 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=adggby architecture=amd64/generic power_parameters_power_user=admin
2019-05-14 06:52:12,050 [salt.loaded.ext.module.maas:412 ][WARNING ][3593] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-14 06:52:12,051 [salt.loaded.ext.module.maas:92  ][INFO    ][3593] 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=eesrad architecture=amd64/generic power_parameters_power_user=admin
2019-05-14 06:52:16,671 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514065216657600
2019-05-14 06:52:16,695 [salt.minion      :1432][INFO    ][3739] Starting a new job with PID 3739
2019-05-14 06:52:16,719 [salt.minion      :1711][INFO    ][3739] Returning information for job: 20190514065216657600
2019-05-14 06:52:16,750 [salt.loaded.ext.module.maas:412 ][WARNING ][3593] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-14 06:52:16,751 [salt.loaded.ext.module.maas:92  ][INFO    ][3593] machine hostname=kvm01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=d8mhkx architecture=amd64/generic power_parameters_power_user=admin
2019-05-14 06:52:21,458 [salt.loaded.ext.module.maas:412 ][WARNING ][3593] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-14 06:52:21,459 [salt.loaded.ext.module.maas:92  ][INFO    ][3593] machine hostname=kvm03 power_type=ipmi mac_addresses=['00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=qesx8p architecture=amd64/generic power_parameters_power_user=admin
2019-05-14 06:52:26,210 [salt.loaded.ext.module.maas:412 ][WARNING ][3593] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-14 06:52:26,211 [salt.loaded.ext.module.maas:92  ][INFO    ][3593] machine hostname=kvm02 power_type=ipmi mac_addresses=['00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=a8cd6d architecture=amd64/generic power_parameters_power_user=admin
2019-05-14 06:52:29,851 [salt.state       :300 ][INFO    ][3593] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-14 06:52:29,852 [salt.state       :1951][INFO    ][3593] Completed state [maas.process_machines] at time 06:52:29.852345 duration_in_ms=23132.687
2019-05-14 06:52:29,856 [salt.minion      :1711][INFO    ][3593] Returning information for job: 20190514065201551265
2019-05-14 06:53:02,696 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command state.apply with jid 20190514065302684223
2019-05-14 06:53:02,717 [salt.minion      :1432][INFO    ][3902] Starting a new job with PID 3902
2019-05-14 06:53:06,302 [salt.state       :915 ][INFO    ][3902] Loading fresh modules for state activity
2019-05-14 06:53:06,373 [salt.state       :1780][INFO    ][3902] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:53:06.373008
2019-05-14 06:53:06,373 [salt.state       :1813][INFO    ][3902] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-14 06:53:06,375 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3902] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-14 06:53:07,847 [salt.state       :300 ][INFO    ][3902] {'pid': 3943, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-14 06:53:07,848 [salt.state       :1951][INFO    ][3902] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:53:07.848728 duration_in_ms=1475.719
2019-05-14 06:53:07,852 [salt.state       :1780][INFO    ][3902] Running state [maas.wait_for_machine_status] at time 06:53:07.852046
2019-05-14 06:53:07,852 [salt.state       :1813][INFO    ][3902] Executing state module.run for [maas.wait_for_machine_status]
2019-05-14 06:53:07,853 [salt.utils.decorators:613 ][WARNING ][3902] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-14 06:53:10,233 [salt.loaded.ext.module.maas:993 ][INFO    ][3902] Machine qesx8p mark broken
2019-05-14 06:53:10,809 [salt.loaded.ext.module.maas:996 ][INFO    ][3902] Machine qesx8p mark fixed
2019-05-14 06:53:11,812 [salt.loaded.ext.module.maas:684 ][INFO    ][3902] deploymachines hwe_kernel=hwe-16.04 system_id=qesx8p distro_series=xenial
2019-05-14 06:53:17,816 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514065317801480
2019-05-14 06:53:17,844 [salt.minion      :1432][INFO    ][4002] Starting a new job with PID 4002
2019-05-14 06:53:17,867 [salt.minion      :1711][INFO    ][4002] Returning information for job: 20190514065317801480
2019-05-14 06:53:18,503 [salt.loaded.ext.module.maas:1023][INFO    ][3902] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1489.36485887s left)
2019-05-14 06:53:47,864 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514065347853409
2019-05-14 06:53:47,886 [salt.minion      :1432][INFO    ][4049] Starting a new job with PID 4049
2019-05-14 06:53:47,909 [salt.minion      :1711][INFO    ][4049] Returning information for job: 20190514065347853409
2019-05-14 06:53:51,128 [salt.loaded.ext.module.maas:1023][INFO    ][3902] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1456.73964095s left)
2019-05-14 06:54:17,997 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514065417935534
2019-05-14 06:54:18,016 [salt.minion      :1432][INFO    ][4122] Starting a new job with PID 4122
2019-05-14 06:54:18,037 [salt.minion      :1711][INFO    ][4122] Returning information for job: 20190514065417935534
2019-05-14 06:54:24,378 [salt.loaded.ext.module.maas:1023][INFO    ][3902] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1423.48968291s left)
2019-05-14 06:54:48,036 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514065448025408
2019-05-14 06:54:48,055 [salt.minion      :1432][INFO    ][4160] Starting a new job with PID 4160
2019-05-14 06:54:48,078 [salt.minion      :1711][INFO    ][4160] Returning information for job: 20190514065448025408
2019-05-14 06:54:57,299 [salt.loaded.ext.module.maas:1023][INFO    ][3902] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1390.56857586s left)
2019-05-14 06:55:18,072 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514065518060634
2019-05-14 06:55:18,091 [salt.minion      :1432][INFO    ][4284] Starting a new job with PID 4284
2019-05-14 06:55:18,109 [salt.minion      :1711][INFO    ][4284] Returning information for job: 20190514065518060634
2019-05-14 06:55:30,479 [salt.loaded.ext.module.maas:1023][INFO    ][3902] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1357.38818383s left)
2019-05-14 06:55:48,121 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514065548103655
2019-05-14 06:55:48,139 [salt.minion      :1432][INFO    ][4319] Starting a new job with PID 4319
2019-05-14 06:55:48,157 [salt.minion      :1711][INFO    ][4319] Returning information for job: 20190514065548103655
2019-05-14 06:56:03,887 [salt.loaded.ext.module.maas:1023][INFO    ][3902] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1323.98023891s left)
2019-05-14 06:56:18,171 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514065618158791
2019-05-14 06:56:18,191 [salt.minion      :1432][INFO    ][4474] Starting a new job with PID 4474
2019-05-14 06:56:18,215 [salt.minion      :1711][INFO    ][4474] Returning information for job: 20190514065618158791
2019-05-14 06:56:37,072 [salt.loaded.ext.module.maas:1023][INFO    ][3902] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1290.79546189s left)
2019-05-14 06:56:48,233 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514065648220602
2019-05-14 06:56:48,256 [salt.minion      :1432][INFO    ][4518] Starting a new job with PID 4518
2019-05-14 06:56:48,278 [salt.minion      :1711][INFO    ][4518] Returning information for job: 20190514065648220602
2019-05-14 06:57:10,399 [salt.loaded.ext.module.maas:1023][INFO    ][3902] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1257.46878386s left)
2019-05-14 06:57:18,302 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514065718289354
2019-05-14 06:57:18,325 [salt.minion      :1432][INFO    ][4837] Starting a new job with PID 4837
2019-05-14 06:57:18,350 [salt.minion      :1711][INFO    ][4837] Returning information for job: 20190514065718289354
2019-05-14 06:57:44,163 [salt.loaded.ext.module.maas:1023][INFO    ][3902] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1223.70432997s left)
2019-05-14 06:57:48,375 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514065748359506
2019-05-14 06:57:48,399 [salt.minion      :1432][INFO    ][4857] Starting a new job with PID 4857
2019-05-14 06:57:48,423 [salt.minion      :1711][INFO    ][4857] Returning information for job: 20190514065748359506
2019-05-14 06:58:17,734 [salt.loaded.ext.module.maas:1023][INFO    ][3902] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1190.13334894s left)
2019-05-14 06:58:18,447 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514065818434736
2019-05-14 06:58:18,470 [salt.minion      :1432][INFO    ][4939] Starting a new job with PID 4939
2019-05-14 06:58:18,491 [salt.minion      :1711][INFO    ][4939] Returning information for job: 20190514065818434736
2019-05-14 06:58:48,519 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514065848506195
2019-05-14 06:58:48,543 [salt.minion      :1432][INFO    ][4964] Starting a new job with PID 4964
2019-05-14 06:58:48,568 [salt.minion      :1711][INFO    ][4964] Returning information for job: 20190514065848506195
2019-05-14 06:58:51,289 [salt.loaded.ext.module.maas:1023][INFO    ][3902] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1156.57843494s left)
2019-05-14 06:59:18,602 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514065918586940
2019-05-14 06:59:18,627 [salt.minion      :1432][INFO    ][5065] Starting a new job with PID 5065
2019-05-14 06:59:18,655 [salt.minion      :1711][INFO    ][5065] Returning information for job: 20190514065918586940
2019-05-14 06:59:24,950 [salt.loaded.ext.module.maas:1023][INFO    ][3902] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1122.91724992s left)
2019-05-14 06:59:48,695 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514065948682950
2019-05-14 06:59:48,719 [salt.minion      :1432][INFO    ][5086] Starting a new job with PID 5086
2019-05-14 06:59:48,753 [salt.minion      :1711][INFO    ][5086] Returning information for job: 20190514065948682950
2019-05-14 06:59:58,518 [salt.loaded.ext.module.maas:1023][INFO    ][3902] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1089.34964991s left)
2019-05-14 07:00:18,798 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514070018786365
2019-05-14 07:00:18,823 [salt.minion      :1432][INFO    ][5232] Starting a new job with PID 5232
2019-05-14 07:00:18,848 [salt.minion      :1711][INFO    ][5232] Returning information for job: 20190514070018786365
2019-05-14 07:00:31,890 [salt.loaded.ext.module.maas:1023][INFO    ][3902] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1055.97792482s left)
2019-05-14 07:00:48,904 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514070048891674
2019-05-14 07:00:48,928 [salt.minion      :1432][INFO    ][5261] Starting a new job with PID 5261
2019-05-14 07:00:48,953 [salt.minion      :1711][INFO    ][5261] Returning information for job: 20190514070048891674
2019-05-14 07:01:05,523 [salt.loaded.ext.module.maas:1023][INFO    ][3902] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1022.34410882s left)
2019-05-14 07:01:19,013 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514070118999857
2019-05-14 07:01:19,037 [salt.minion      :1432][INFO    ][5387] Starting a new job with PID 5387
2019-05-14 07:01:19,062 [salt.minion      :1711][INFO    ][5387] Returning information for job: 20190514070118999857
2019-05-14 07:01:39,382 [salt.loaded.ext.module.maas:1023][INFO    ][3902] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (988.485594988s left)
2019-05-14 07:01:49,133 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514070149121208
2019-05-14 07:01:49,157 [salt.minion      :1432][INFO    ][5409] Starting a new job with PID 5409
2019-05-14 07:01:49,182 [salt.minion      :1711][INFO    ][5409] Returning information for job: 20190514070149121208
2019-05-14 07:02:13,221 [salt.loaded.ext.module.maas:1023][INFO    ][3902] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (954.646639824s left)
2019-05-14 07:02:19,259 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command saltutil.find_job with jid 20190514070219246430
2019-05-14 07:02:19,281 [salt.minion      :1432][INFO    ][5507] Starting a new job with PID 5507
2019-05-14 07:02:19,305 [salt.minion      :1711][INFO    ][5507] Returning information for job: 20190514070219246430
2019-05-14 07:02:46,819 [salt.state       :300 ][INFO    ][3902] {'ret': True}
2019-05-14 07:02:46,820 [salt.state       :1951][INFO    ][3902] Completed state [maas.wait_for_machine_status] at time 07:02:46.819974 duration_in_ms=578967.925
2019-05-14 07:02:46,824 [salt.minion      :1711][INFO    ][3902] Returning information for job: 20190514065302684223
2019-05-14 07:02:47,437 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command state.apply with jid 20190514070247424381
2019-05-14 07:02:47,457 [salt.minion      :1432][INFO    ][5543] Starting a new job with PID 5543
2019-05-14 07:02:51,099 [salt.state       :915 ][INFO    ][5543] Loading fresh modules for state activity
2019-05-14 07:02:51,241 [salt.state       :1780][INFO    ][5543] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:02:51.241061
2019-05-14 07:02:51,241 [salt.state       :1813][INFO    ][5543] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-14 07:02:51,243 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5543] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-14 07:02:52,673 [salt.state       :300 ][INFO    ][5543] {'pid': 5551, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-14 07:02:52,674 [salt.state       :1951][INFO    ][5543] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:02:52.674220 duration_in_ms=1433.16
2019-05-14 07:02:52,675 [salt.state       :1780][INFO    ][5543] Running state [maas_machines_storage_cmp002_lvm] at time 07:02:52.675590
2019-05-14 07:02:52,675 [salt.state       :1813][INFO    ][5543] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-14 07:02:53,365 [salt.state       :300 ][INFO    ][5543] Machine cmp002 is not in Ready state.
2019-05-14 07:02:53,365 [salt.state       :1951][INFO    ][5543] Completed state [maas_machines_storage_cmp002_lvm] at time 07:02:53.365620 duration_in_ms=690.027
2019-05-14 07:02:53,366 [salt.state       :1780][INFO    ][5543] Running state [maas_machines_storage_cmp001_lvm] at time 07:02:53.366235
2019-05-14 07:02:53,366 [salt.state       :1813][INFO    ][5543] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-14 07:02:54,219 [salt.state       :300 ][INFO    ][5543] Machine cmp001 is not in Ready state.
2019-05-14 07:02:54,219 [salt.state       :1951][INFO    ][5543] Completed state [maas_machines_storage_cmp001_lvm] at time 07:02:54.219831 duration_in_ms=853.596
2019-05-14 07:02:54,223 [salt.minion      :1711][INFO    ][5543] Returning information for job: 20190514070247424381
2019-05-14 07:02:54,845 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command state.apply with jid 20190514070254832348
2019-05-14 07:02:54,869 [salt.minion      :1432][INFO    ][5561] Starting a new job with PID 5561
2019-05-14 07:02:55,628 [salt.state       :915 ][INFO    ][5561] Loading fresh modules for state activity
2019-05-14 07:02:55,720 [salt.state       :1780][INFO    ][5561] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:02:55.720805
2019-05-14 07:02:55,721 [salt.state       :1813][INFO    ][5561] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-14 07:02:55,723 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5561] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-14 07:02:57,241 [salt.state       :300 ][INFO    ][5561] {'pid': 5568, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-14 07:02:57,241 [salt.state       :1951][INFO    ][5561] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:02:57.241821 duration_in_ms=1521.016
2019-05-14 07:02:57,244 [salt.state       :1780][INFO    ][5561] Running state [maas.deploy_machines] at time 07:02:57.244380
2019-05-14 07:02:57,244 [salt.state       :1813][INFO    ][5561] Executing state module.run for [maas.deploy_machines]
2019-05-14 07:02:57,250 [salt.utils.decorators:613 ][WARNING ][5561] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-14 07:02:57,988 [salt.state       :300 ][INFO    ][5561] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-14 07:02:57,989 [salt.state       :1951][INFO    ][5561] Completed state [maas.deploy_machines] at time 07:02:57.989406 duration_in_ms=745.024
2019-05-14 07:02:57,993 [salt.minion      :1711][INFO    ][5561] Returning information for job: 20190514070254832348
2019-05-14 07:02:58,586 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command state.apply with jid 20190514070258572925
2019-05-14 07:02:58,610 [salt.minion      :1432][INFO    ][5577] Starting a new job with PID 5577
2019-05-14 07:02:59,348 [salt.state       :915 ][INFO    ][5577] Loading fresh modules for state activity
2019-05-14 07:02:59,441 [salt.state       :1780][INFO    ][5577] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:02:59.441187
2019-05-14 07:02:59,441 [salt.state       :1813][INFO    ][5577] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-14 07:02:59,443 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5577] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-14 07:03:01,015 [salt.state       :300 ][INFO    ][5577] {'pid': 5601, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-14 07:03:01,016 [salt.state       :1951][INFO    ][5577] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:03:01.016304 duration_in_ms=1575.118
2019-05-14 07:03:01,020 [salt.state       :1780][INFO    ][5577] Running state [maas.wait_for_machine_status] at time 07:03:01.020204
2019-05-14 07:03:01,020 [salt.state       :1813][INFO    ][5577] Executing state module.run for [maas.wait_for_machine_status]
2019-05-14 07:03:01,020 [salt.utils.decorators:613 ][WARNING ][5577] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-14 07:03:04,405 [salt.state       :300 ][INFO    ][5577] {'ret': True}
2019-05-14 07:03:04,406 [salt.state       :1951][INFO    ][5577] Completed state [maas.wait_for_machine_status] at time 07:03:04.406322 duration_in_ms=3386.116
2019-05-14 07:03:04,410 [salt.minion      :1711][INFO    ][5577] Returning information for job: 20190514070258572925
2019-05-14 07:08:42,749 [salt.utils.schedule:1377][INFO    ][18718] Running scheduled job: __mine_interval
2019-05-14 08:08:42,749 [salt.utils.schedule:1377][INFO    ][18718] Running scheduled job: __mine_interval
2019-05-14 08:29:28,171 [salt.minion      :1308][INFO    ][18718] User sudo_ubuntu Executing command cp.push_dir with jid 20190514082928158991
2019-05-14 08:29:28,194 [salt.minion      :1432][INFO    ][11687] Starting a new job with PID 11687
