2019-05-20 02:07:22,943 [salt.minion      :870 ][ERROR   ][361] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-20 02:08:12,989 [salt.minion      :870 ][ERROR   ][361] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-20 02:09:03,039 [salt.minion      :870 ][ERROR   ][361] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-20 02:09:53,076 [salt.minion      :870 ][ERROR   ][361] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-20 02:10:43,125 [salt.minion      :870 ][ERROR   ][361] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-20 02:12:52,766 [salt.utils.decorators:613 ][WARNING ][2814] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 02:12:53,196 [salt.utils.decorators:613 ][WARNING ][2814] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 02:12:55,388 [salt.loaded.int.states.file:2298][WARNING ][2955] 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-20 02:13:01,057 [salt.state       :2022][WARNING ][3064] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-20 02:13:03,717 [salt.utils.decorators:613 ][WARNING ][3064] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 02:13:17,743 [salt.utils.decorators:613 ][WARNING ][3064] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 02:13:56,626 [salt.utils.decorators:613 ][WARNING ][3064] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 02:13:57,513 [salt.utils.decorators:613 ][WARNING ][3064] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 02:14:01,336 [salt.loaded.ext.module.maasng:1008][WARNING ][3064] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-05-20 02:14:01,337 [salt.loaded.ext.module.maasng:1011][WARNING ][3064] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-05-20 02:14:02,014 [salt.loaded.ext.module.maasng:1235][WARNING ][3064] Ignoring parameter vlan:0
2019-05-20 02:14:08,609 [salt.utils.decorators:613 ][WARNING ][6150] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 02:14:08,691 [salt.loaded.ext.module.maas:412 ][WARNING ][6150] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-20 02:14:09,883 [salt.loaded.ext.module.maas:412 ][WARNING ][6150] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-20 02:14:10,961 [salt.loaded.ext.module.maas:412 ][WARNING ][6150] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-20 02:14:12,459 [salt.loaded.ext.module.maas:412 ][WARNING ][6150] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-20 02:14:13,902 [salt.loaded.ext.module.maas:412 ][WARNING ][6150] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-20 02:14:18,479 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6776] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-20 02:14:18,511 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6776] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-20 02:14:18,534 [salt.utils.parsers:1051][WARNING ][361] Minion received a SIGTERM. Exiting.
2019-05-20 02:14:19,614 [salt.cli.daemons :293 ][INFO    ][6826] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-05-20 02:14:19,713 [salt.cli.daemons :82  ][INFO    ][6826] Starting up the Salt Minion
2019-05-20 02:14:19,714 [salt.utils.event :1017][INFO    ][6826] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-05-20 02:14:20,620 [salt.minion      :976 ][INFO    ][6826] Creating minion process manager
2019-05-20 02:14:22,075 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6826] Executing command ['date', '+%z'] in directory '/root'
2019-05-20 02:14:22,096 [salt.utils.schedule:568 ][INFO    ][6826] Updating job settings for scheduled job: __mine_interval
2019-05-20 02:14:22,097 [salt.minion      :1108][INFO    ][6826] Added mine.update to scheduler
2019-05-20 02:14:22,101 [salt.minion      :1975][INFO    ][6826] Minion is starting as user 'root'
2019-05-20 02:14:22,115 [salt.minion      :2336][INFO    ][6826] Minion is ready to receive requests!
2019-05-20 02:14:46,167 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command state.apply with jid 20190520021446154323
2019-05-20 02:14:46,189 [salt.minion      :1432][INFO    ][6917] Starting a new job with PID 6917
2019-05-20 02:14:50,033 [salt.state       :915 ][INFO    ][6917] Loading fresh modules for state activity
2019-05-20 02:14:50,086 [salt.fileclient  :1219][INFO    ][6917] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-20 02:14:50,131 [salt.state       :1780][INFO    ][6917] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:50.131390
2019-05-20 02:14:50,131 [salt.state       :1813][INFO    ][6917] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 02:14:50,133 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6917] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 02:14:51,582 [salt.state       :300 ][INFO    ][6917] {'pid': 6925, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 02:14:51,582 [salt.state       :1951][INFO    ][6917] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:51.582905 duration_in_ms=1451.516
2019-05-20 02:14:51,584 [salt.state       :1780][INFO    ][6917] Running state [maas.wait_for_machine_status] at time 02:14:51.584023
2019-05-20 02:14:51,584 [salt.state       :1813][INFO    ][6917] Executing state module.run for [maas.wait_for_machine_status]
2019-05-20 02:14:51,584 [salt.utils.decorators:613 ][WARNING ][6917] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 02:14:52,498 [salt.loaded.ext.module.maas:1023][INFO    ][6917] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.090487s left)
2019-05-20 02:15:01,208 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520021501192303
2019-05-20 02:15:01,231 [salt.minion      :1432][INFO    ][6952] Starting a new job with PID 6952
2019-05-20 02:15:01,255 [salt.minion      :1711][INFO    ][6952] Returning information for job: 20190520021501192303
2019-05-20 02:15:23,820 [salt.loaded.ext.module.maas:1023][INFO    ][6917] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.76905608s left)
2019-05-20 02:15:31,250 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520021531237766
2019-05-20 02:15:31,267 [salt.minion      :1432][INFO    ][6996] Starting a new job with PID 6996
2019-05-20 02:15:31,290 [salt.minion      :1711][INFO    ][6996] Returning information for job: 20190520021531237766
2019-05-20 02:15:55,248 [salt.loaded.ext.module.maas:1023][INFO    ][6917] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.34111714s left)
2019-05-20 02:16:01,299 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520021601280911
2019-05-20 02:16:01,320 [salt.minion      :1432][INFO    ][7145] Starting a new job with PID 7145
2019-05-20 02:16:01,343 [salt.minion      :1711][INFO    ][7145] Returning information for job: 20190520021601280911
2019-05-20 02:16:26,555 [salt.loaded.ext.module.maas:1023][INFO    ][6917] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.0333581s left)
2019-05-20 02:16:31,348 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520021631335175
2019-05-20 02:16:31,370 [salt.minion      :1432][INFO    ][7281] Starting a new job with PID 7281
2019-05-20 02:16:31,393 [salt.minion      :1711][INFO    ][7281] Returning information for job: 20190520021631335175
2019-05-20 02:16:57,907 [salt.loaded.ext.module.maas:1023][INFO    ][6917] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.68165016s left)
2019-05-20 02:17:01,410 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520021701394427
2019-05-20 02:17:01,431 [salt.minion      :1432][INFO    ][7441] Starting a new job with PID 7441
2019-05-20 02:17:01,456 [salt.minion      :1711][INFO    ][7441] Returning information for job: 20190520021701394427
2019-05-20 02:17:29,821 [salt.loaded.ext.module.maas:1023][INFO    ][6917] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.76800609s left)
2019-05-20 02:17:31,473 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520021731457738
2019-05-20 02:17:31,496 [salt.minion      :1432][INFO    ][8045] Starting a new job with PID 8045
2019-05-20 02:17:31,519 [salt.minion      :1711][INFO    ][8045] Returning information for job: 20190520021731457738
2019-05-20 02:18:01,541 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520021801529087
2019-05-20 02:18:01,563 [salt.minion      :1432][INFO    ][8240] Starting a new job with PID 8240
2019-05-20 02:18:01,588 [salt.minion      :1711][INFO    ][8240] Returning information for job: 20190520021801529087
2019-05-20 02:18:03,282 [salt.loaded.ext.module.maas:1023][INFO    ][6917] Waiting status:Ready|Deployed for machines:['cmp001']
sleep for:30s Timeout:1500s (1308.30697608s left)
2019-05-20 02:18:31,681 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520021831665780
2019-05-20 02:18:31,703 [salt.minion      :1432][INFO    ][8406] Starting a new job with PID 8406
2019-05-20 02:18:31,726 [salt.minion      :1711][INFO    ][8406] Returning information for job: 20190520021831665780
2019-05-20 02:18:36,616 [salt.state       :300 ][INFO    ][6917] {'ret': True}
2019-05-20 02:18:36,616 [salt.state       :1951][INFO    ][6917] Completed state [maas.wait_for_machine_status] at time 02:18:36.616617 duration_in_ms=225032.59
2019-05-20 02:18:36,620 [salt.minion      :1711][INFO    ][6917] Returning information for job: 20190520021446154323
2019-05-20 02:18:37,280 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command state.apply with jid 20190520021837267856
2019-05-20 02:18:37,302 [salt.minion      :1432][INFO    ][8419] Starting a new job with PID 8419
2019-05-20 02:18:41,119 [salt.state       :915 ][INFO    ][8419] Loading fresh modules for state activity
2019-05-20 02:18:41,148 [salt.fileclient  :1219][INFO    ][8419] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-20 02:18:41,204 [salt.state       :1780][INFO    ][8419] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:18:41.204290
2019-05-20 02:18:41,204 [salt.state       :1813][INFO    ][8419] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 02:18:41,205 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8419] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 02:18:42,565 [salt.state       :300 ][INFO    ][8419] {'pid': 8426, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 02:18:42,565 [salt.state       :1951][INFO    ][8419] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:18:42.565430 duration_in_ms=1361.14
2019-05-20 02:18:42,566 [salt.state       :1780][INFO    ][8419] Running state [maas_machines_storage_cmp002_lvm] at time 02:18:42.566663
2019-05-20 02:18:42,566 [salt.state       :1813][INFO    ][8419] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-20 02:18:43,547 [salt.loaded.ext.module.maasng:610 ][INFO    ][8419] y8xh4k
2019-05-20 02:18:43,547 [salt.loaded.ext.module.maasng:626 ][INFO    ][8419] sda
2019-05-20 02:18:44,393 [salt.loaded.ext.module.maasng:361 ][INFO    ][8419] y8xh4k
2019-05-20 02:18:44,507 [salt.loaded.ext.module.maasng:367 ][INFO    ][8419] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'e856d5ac-3baa-4343-8eb9-1d82b0f982e0', u'resource_uri': u'/MAAS/api/2.0/nodes/y8xh4k/blockdevices/4/partition/4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'y8xh4k', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'965485d5-6b08-44b7-a901-9e056e05bdce', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 4, u'size': 2397992648704}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'y8xh4k', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/y8xh4k/blockdevices/4/'}, {u'size': 2397988454400, u'model': None, u'uuid': u'15dd4faf-51f2-428a-a576-6fe68dec12e2', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'cc9166a7-f258-4afe-91ca-2664e61b733a', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'y8xh4k', u'partition_table_type': None, u'available_size': 0, 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'resource_uri': u'/MAAS/api/2.0/nodes/y8xh4k/blockdevices/9/'}]
2019-05-20 02:18:44,507 [salt.loaded.ext.module.maasng:632 ][INFO    ][8419] vgroot
2019-05-20 02:18:44,508 [salt.loaded.ext.module.maasng:635 ][INFO    ][8419] lvroot
2019-05-20 02:18:44,508 [salt.loaded.ext.module.maasng:639 ][INFO    ][8419] 107374182400
2019-05-20 02:18:45,216 [salt.loaded.ext.module.maasng:645 ][INFO    ][8419] {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'memory_test_status': -1, u'ip_addresses': [u'192.168.11.42'], u'storage_test_status_name': u'Passed', 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'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'relay_vlan': None, u'primary_rack': u'fswc6s', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 30}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fswc6s', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fswc6s', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'y8xh4k', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/y8xh4k/interfaces/4/'}, u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/y8xh4k/blockdevices/4/', u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'f876d2ee-7177-43ff-93dc-e59a905bf251', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'y8xh4k', u'filesystem': {u'uuid': u'131d0c7a-9421-456b-bec5-279506c9cca2', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/y8xh4k/blockdevices/4/partition/6'}], u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'y8xh4k', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 4, u'size': 2397998940160}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'disable_ipv4': False, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'y8xh4k', 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'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/y8xh4k/blockdevices/4/', u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'f876d2ee-7177-43ff-93dc-e59a905bf251', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'y8xh4k', u'filesystem': {u'uuid': u'131d0c7a-9421-456b-bec5-279506c9cca2', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/y8xh4k/blockdevices/4/partition/6'}], u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'y8xh4k', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 4, u'size': 2397998940160}], u'min_hwe_kernel': u'ga-16.04', u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'y8xh4k', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'memory_test_status_name': u'Unknown', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, 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'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/y8xh4k/blockdevices/11/', u'type': u'virtual', u'partitions': [], u'tags': [], u'uuid': u'1850505d-7196-4c0d-9c34-7125ae54df33', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'y8xh4k', u'partition_table_type': None, u'filesystem': {u'uuid': u'db9ebb5e-2406-4cf0-9b03-05acab60826f', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'relay_vlan': None, u'primary_rack': u'fswc6s', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 30}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fswc6s', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fswc6s', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'y8xh4k', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/y8xh4k/interfaces/4/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 31}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'y8xh4k', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/y8xh4k/interfaces/18/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 32}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'y8xh4k', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/y8xh4k/interfaces/19/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 33}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'y8xh4k', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/y8xh4k/interfaces/20/'}], u'address_ttl': None, u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/y8xh4k/blockdevices/4/', u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'f876d2ee-7177-43ff-93dc-e59a905bf251', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'y8xh4k', u'filesystem': {u'uuid': u'131d0c7a-9421-456b-bec5-279506c9cca2', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/y8xh4k/blockdevices/4/partition/6'}], u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'y8xh4k', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 4, u'size': 2397998940160}, {u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/y8xh4k/blockdevices/11/', u'type': u'virtual', u'partitions': [], u'tags': [], u'uuid': u'1850505d-7196-4c0d-9c34-7125ae54df33', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'y8xh4k', u'partition_table_type': None, u'filesystem': {u'uuid': u'db9ebb5e-2406-4cf0-9b03-05acab60826f', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'size': 107374182400}], u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/y8xh4k/'}
2019-05-20 02:18:45,219 [salt.state       :300 ][INFO    ][8419] {'new': {'storage_layout': 'lvm'}}
2019-05-20 02:18:45,219 [salt.state       :1951][INFO    ][8419] Completed state [maas_machines_storage_cmp002_lvm] at time 02:18:45.219524 duration_in_ms=2652.858
2019-05-20 02:18:45,220 [salt.state       :1780][INFO    ][8419] Running state [maas_machines_storage_cmp001_lvm] at time 02:18:45.220095
2019-05-20 02:18:45,220 [salt.state       :1813][INFO    ][8419] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-20 02:18:46,564 [salt.loaded.ext.module.maasng:610 ][INFO    ][8419] xwdmsx
2019-05-20 02:18:46,564 [salt.loaded.ext.module.maasng:626 ][INFO    ][8419] sda
2019-05-20 02:18:47,260 [salt.loaded.ext.module.maasng:361 ][INFO    ][8419] xwdmsx
2019-05-20 02:18:47,375 [salt.loaded.ext.module.maasng:367 ][INFO    ][8419] [{u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/xwdmsx/blockdevices/2/', u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'43929551-f96c-4425-8fd4-3adac445a436', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xwdmsx', u'filesystem': {u'uuid': u'49d678fc-b1a2-4287-875a-95b67be3242b', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/xwdmsx/blockdevices/2/partition/2'}], u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'xwdmsx', 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'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/xwdmsx/blockdevices/7/', u'type': u'virtual', u'partitions': [], u'tags': [], u'uuid': u'cc3d014e-3ed0-4ed3-9109-6df69e746837', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xwdmsx', u'partition_table_type': None, u'filesystem': {u'uuid': u'c02d81bc-0170-4e1c-a8d1-0f0010bbb89b', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 7, u'size': 2397988454400}]
2019-05-20 02:18:47,376 [salt.loaded.ext.module.maasng:632 ][INFO    ][8419] vgroot
2019-05-20 02:18:47,376 [salt.loaded.ext.module.maasng:635 ][INFO    ][8419] lvroot
2019-05-20 02:18:47,377 [salt.loaded.ext.module.maasng:639 ][INFO    ][8419] 107374182400
2019-05-20 02:18:48,102 [salt.loaded.ext.module.maasng:645 ][INFO    ][8419] {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'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38'], u'storage_test_status_name': u'Passed', 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'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'relay_vlan': None, u'primary_rack': u'fswc6s', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 20}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fswc6s', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fswc6s', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'xwdmsx', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/xwdmsx/interfaces/5/'}, u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/xwdmsx/blockdevices/2/', u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'ec986acc-41ff-485b-aaf3-a18aa7a5a0a5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xwdmsx', u'filesystem': {u'uuid': u'2d7cc719-6ef1-4647-b94f-1bb54f5d3dc9', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/xwdmsx/blockdevices/2/partition/7'}], u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'xwdmsx', 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'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'disable_ipv4': False, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'xwdmsx', 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'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/xwdmsx/blockdevices/2/', u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'ec986acc-41ff-485b-aaf3-a18aa7a5a0a5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xwdmsx', u'filesystem': {u'uuid': u'2d7cc719-6ef1-4647-b94f-1bb54f5d3dc9', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/xwdmsx/blockdevices/2/partition/7'}], u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'xwdmsx', 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'min_hwe_kernel': u'ga-16.04', u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'xwdmsx', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'memory_test_status_name': u'Unknown', 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'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/xwdmsx/blockdevices/12/', u'type': u'virtual', u'partitions': [], u'tags': [], u'uuid': u'e76edf27-14c3-4c20-b6cd-de4fcc1cf290', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xwdmsx', u'partition_table_type': None, u'filesystem': {u'uuid': u'd93b7181-9a12-498a-828e-a6b28d84962f', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, 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'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'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'relay_vlan': None, u'primary_rack': u'fswc6s', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 20}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fswc6s', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fswc6s', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'xwdmsx', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/xwdmsx/interfaces/5/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'xwdmsx', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/xwdmsx/interfaces/12/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 22}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'xwdmsx', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/xwdmsx/interfaces/13/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 23}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'xwdmsx', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/xwdmsx/interfaces/14/'}], u'address_ttl': None, u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/xwdmsx/blockdevices/2/', u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'ec986acc-41ff-485b-aaf3-a18aa7a5a0a5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xwdmsx', u'filesystem': {u'uuid': u'2d7cc719-6ef1-4647-b94f-1bb54f5d3dc9', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/xwdmsx/blockdevices/2/partition/7'}], u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'xwdmsx', 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'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/xwdmsx/blockdevices/12/', u'type': u'virtual', u'partitions': [], u'tags': [], u'uuid': u'e76edf27-14c3-4c20-b6cd-de4fcc1cf290', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xwdmsx', u'partition_table_type': None, u'filesystem': {u'uuid': u'd93b7181-9a12-498a-828e-a6b28d84962f', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'size': 107374182400}], u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/xwdmsx/'}
2019-05-20 02:18:48,104 [salt.state       :300 ][INFO    ][8419] {'new': {'storage_layout': 'lvm'}}
2019-05-20 02:18:48,104 [salt.state       :1951][INFO    ][8419] Completed state [maas_machines_storage_cmp001_lvm] at time 02:18:48.104821 duration_in_ms=2884.726
2019-05-20 02:18:48,108 [salt.minion      :1711][INFO    ][8419] Returning information for job: 20190520021837267856
2019-05-20 02:18:48,733 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command state.apply with jid 20190520021848716271
2019-05-20 02:18:48,755 [salt.minion      :1432][INFO    ][8446] Starting a new job with PID 8446
2019-05-20 02:18:49,512 [salt.state       :915 ][INFO    ][8446] Loading fresh modules for state activity
2019-05-20 02:18:49,565 [salt.fileclient  :1219][INFO    ][8446] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-20 02:18:49,608 [salt.state       :1780][INFO    ][8446] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:18:49.608643
2019-05-20 02:18:49,609 [salt.state       :1813][INFO    ][8446] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 02:18:49,611 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8446] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 02:18:51,070 [salt.state       :300 ][INFO    ][8446] {'pid': 8453, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 02:18:51,071 [salt.state       :1951][INFO    ][8446] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:18:51.071228 duration_in_ms=1462.587
2019-05-20 02:18:51,072 [salt.state       :1780][INFO    ][8446] Running state [maas.deploy_machines] at time 02:18:51.072451
2019-05-20 02:18:51,072 [salt.state       :1813][INFO    ][8446] Executing state module.run for [maas.deploy_machines]
2019-05-20 02:18:51,073 [salt.utils.decorators:613 ][WARNING ][8446] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 02:18:51,790 [salt.loaded.ext.module.maas:684 ][INFO    ][8446] deploymachines hwe_kernel=ga-16.04 system_id=y8xh4k distro_series=xenial
2019-05-20 02:18:54,503 [salt.loaded.ext.module.maas:684 ][INFO    ][8446] deploymachines hwe_kernel=ga-16.04 system_id=xwdmsx distro_series=xenial
2019-05-20 02:18:57,319 [salt.loaded.ext.module.maas:684 ][INFO    ][8446] deploymachines hwe_kernel=ga-16.04 system_id=g7k7bm distro_series=xenial
2019-05-20 02:19:00,074 [salt.loaded.ext.module.maas:684 ][INFO    ][8446] deploymachines hwe_kernel=ga-16.04 system_id=m4nxm7 distro_series=xenial
2019-05-20 02:19:02,936 [salt.loaded.ext.module.maas:684 ][INFO    ][8446] deploymachines hwe_kernel=ga-16.04 system_id=qw8h4q distro_series=xenial
2019-05-20 02:19:03,790 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520021903779408
2019-05-20 02:19:03,811 [salt.minion      :1432][INFO    ][8723] Starting a new job with PID 8723
2019-05-20 02:19:03,833 [salt.minion      :1711][INFO    ][8723] Returning information for job: 20190520021903779408
2019-05-20 02:19:05,615 [salt.state       :300 ][INFO    ][8446] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-20 02:19:05,615 [salt.state       :1951][INFO    ][8446] Completed state [maas.deploy_machines] at time 02:19:05.615554 duration_in_ms=14543.101
2019-05-20 02:19:05,619 [salt.minion      :1711][INFO    ][8446] Returning information for job: 20190520021848716271
2019-05-20 02:19:06,239 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command state.apply with jid 20190520021906227462
2019-05-20 02:19:06,256 [salt.minion      :1432][INFO    ][8765] Starting a new job with PID 8765
2019-05-20 02:19:09,938 [salt.state       :915 ][INFO    ][8765] Loading fresh modules for state activity
2019-05-20 02:19:09,991 [salt.fileclient  :1219][INFO    ][8765] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-20 02:19:10,045 [salt.state       :1780][INFO    ][8765] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:19:10.045278
2019-05-20 02:19:10,045 [salt.state       :1813][INFO    ][8765] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 02:19:10,048 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8765] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 02:19:11,603 [salt.state       :300 ][INFO    ][8765] {'pid': 8779, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 02:19:11,604 [salt.state       :1951][INFO    ][8765] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:19:11.604627 duration_in_ms=1559.349
2019-05-20 02:19:11,608 [salt.state       :1780][INFO    ][8765] Running state [maas.wait_for_machine_status] at time 02:19:11.608013
2019-05-20 02:19:11,608 [salt.state       :1813][INFO    ][8765] Executing state module.run for [maas.wait_for_machine_status]
2019-05-20 02:19:11,609 [salt.utils.decorators:613 ][WARNING ][8765] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 02:19:14,439 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.18049288s left)
2019-05-20 02:19:21,335 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520021921322876
2019-05-20 02:19:21,358 [salt.minion      :1432][INFO    ][8791] Starting a new job with PID 8791
2019-05-20 02:19:21,381 [salt.minion      :1711][INFO    ][8791] Returning information for job: 20190520021921322876
2019-05-20 02:19:48,002 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.61763906s left)
2019-05-20 02:19:51,392 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520021951378647
2019-05-20 02:19:51,412 [salt.minion      :1432][INFO    ][8811] Starting a new job with PID 8811
2019-05-20 02:19:51,436 [salt.minion      :1711][INFO    ][8811] Returning information for job: 20190520021951378647
2019-05-20 02:20:21,489 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520022021476236
2019-05-20 02:20:21,512 [salt.minion      :1432][INFO    ][8867] Starting a new job with PID 8867
2019-05-20 02:20:21,534 [salt.minion      :1711][INFO    ][8867] Returning information for job: 20190520022021476236
2019-05-20 02:20:21,568 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.05211186s left)
2019-05-20 02:20:51,538 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520022051525614
2019-05-20 02:20:51,558 [salt.minion      :1432][INFO    ][8976] Starting a new job with PID 8976
2019-05-20 02:20:51,582 [salt.minion      :1711][INFO    ][8976] Returning information for job: 20190520022051525614
2019-05-20 02:20:54,839 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.78040099s left)
2019-05-20 02:21:21,590 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520022121577361
2019-05-20 02:21:21,613 [salt.minion      :1432][INFO    ][9455] Starting a new job with PID 9455
2019-05-20 02:21:21,635 [salt.minion      :1711][INFO    ][9455] Returning information for job: 20190520022121577361
2019-05-20 02:21:28,384 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.235497s left)
2019-05-20 02:21:51,643 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520022151629911
2019-05-20 02:21:51,666 [salt.minion      :1432][INFO    ][9506] Starting a new job with PID 9506
2019-05-20 02:21:51,689 [salt.minion      :1711][INFO    ][9506] Returning information for job: 20190520022151629911
2019-05-20 02:22:01,724 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.89554691s left)
2019-05-20 02:22:21,693 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520022221679069
2019-05-20 02:22:21,716 [salt.minion      :1432][INFO    ][10061] Starting a new job with PID 10061
2019-05-20 02:22:21,740 [salt.minion      :1711][INFO    ][10061] Returning information for job: 20190520022221679069
2019-05-20 02:22:34,757 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.86294889s left)
2019-05-20 02:22:51,757 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520022251744970
2019-05-20 02:22:51,779 [salt.minion      :1432][INFO    ][10082] Starting a new job with PID 10082
2019-05-20 02:22:51,801 [salt.minion      :1711][INFO    ][10082] Returning information for job: 20190520022251744970
2019-05-20 02:23:08,030 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.58934593s left)
2019-05-20 02:23:21,821 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520022321808186
2019-05-20 02:23:21,844 [salt.minion      :1432][INFO    ][10190] Starting a new job with PID 10190
2019-05-20 02:23:21,868 [salt.minion      :1711][INFO    ][10190] Returning information for job: 20190520022321808186
2019-05-20 02:23:41,523 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.09718394s left)
2019-05-20 02:23:51,891 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520022351877610
2019-05-20 02:23:51,913 [salt.minion      :1432][INFO    ][10227] Starting a new job with PID 10227
2019-05-20 02:23:51,938 [salt.minion      :1711][INFO    ][10227] Returning information for job: 20190520022351877610
2019-05-20 02:24:14,859 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.76100302s left)
2019-05-20 02:24:21,958 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520022421951948
2019-05-20 02:24:21,981 [salt.minion      :1432][INFO    ][10487] Starting a new job with PID 10487
2019-05-20 02:24:22,010 [salt.minion      :1711][INFO    ][10487] Returning information for job: 20190520022421951948
2019-05-20 02:24:48,243 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.37649989s left)
2019-05-20 02:24:52,040 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520022452028085
2019-05-20 02:24:52,062 [salt.minion      :1432][INFO    ][10508] Starting a new job with PID 10508
2019-05-20 02:24:52,088 [salt.minion      :1711][INFO    ][10508] Returning information for job: 20190520022452028085
2019-05-20 02:25:21,727 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.89235806s left)
2019-05-20 02:25:22,124 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520022522111714
2019-05-20 02:25:22,147 [salt.minion      :1432][INFO    ][10803] Starting a new job with PID 10803
2019-05-20 02:25:22,172 [salt.minion      :1711][INFO    ][10803] Returning information for job: 20190520022522111714
2019-05-20 02:25:52,213 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520022552201394
2019-05-20 02:25:52,235 [salt.minion      :1432][INFO    ][10827] Starting a new job with PID 10827
2019-05-20 02:25:52,258 [salt.minion      :1711][INFO    ][10827] Returning information for job: 20190520022552201394
2019-05-20 02:25:55,334 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.28598094s left)
2019-05-20 02:26:22,309 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520022622296289
2019-05-20 02:26:22,331 [salt.minion      :1432][INFO    ][11134] Starting a new job with PID 11134
2019-05-20 02:26:22,353 [salt.minion      :1711][INFO    ][11134] Returning information for job: 20190520022622296289
2019-05-20 02:26:28,880 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.73996305s left)
2019-05-20 02:26:52,408 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520022652395045
2019-05-20 02:26:52,430 [salt.minion      :1432][INFO    ][11152] Starting a new job with PID 11152
2019-05-20 02:26:52,455 [salt.minion      :1711][INFO    ][11152] Returning information for job: 20190520022652395045
2019-05-20 02:27:02,252 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1779.36746192s left)
2019-05-20 02:27:22,516 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520022722503409
2019-05-20 02:27:22,539 [salt.minion      :1432][INFO    ][11386] Starting a new job with PID 11386
2019-05-20 02:27:22,562 [salt.minion      :1711][INFO    ][11386] Returning information for job: 20190520022722503409
2019-05-20 02:27:35,901 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1745.71928787s left)
2019-05-20 02:27:52,636 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520022752623108
2019-05-20 02:27:52,658 [salt.minion      :1432][INFO    ][11448] Starting a new job with PID 11448
2019-05-20 02:27:52,683 [salt.minion      :1711][INFO    ][11448] Returning information for job: 20190520022752623108
2019-05-20 02:28:08,797 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1712.82307601s left)
2019-05-20 02:28:22,676 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520022822660518
2019-05-20 02:28:22,697 [salt.minion      :1432][INFO    ][11623] Starting a new job with PID 11623
2019-05-20 02:28:22,719 [salt.minion      :1711][INFO    ][11623] Returning information for job: 20190520022822660518
2019-05-20 02:28:42,017 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1679.60249591s left)
2019-05-20 02:28:52,769 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520022852755848
2019-05-20 02:28:52,791 [salt.minion      :1432][INFO    ][11704] Starting a new job with PID 11704
2019-05-20 02:28:52,816 [salt.minion      :1711][INFO    ][11704] Returning information for job: 20190520022852755848
2019-05-20 02:29:15,653 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1645.967062s left)
2019-05-20 02:29:22,904 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520022922891194
2019-05-20 02:29:22,926 [salt.minion      :1432][INFO    ][12113] Starting a new job with PID 12113
2019-05-20 02:29:22,951 [salt.minion      :1711][INFO    ][12113] Returning information for job: 20190520022922891194
2019-05-20 02:29:49,388 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1612.23176408s left)
2019-05-20 02:29:53,058 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520022953041035
2019-05-20 02:29:53,081 [salt.minion      :1432][INFO    ][12132] Starting a new job with PID 12132
2019-05-20 02:29:53,106 [salt.minion      :1711][INFO    ][12132] Returning information for job: 20190520022953041035
2019-05-20 02:30:22,553 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1579.0671699s left)
2019-05-20 02:30:23,226 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520023023210111
2019-05-20 02:30:23,249 [salt.minion      :1432][INFO    ][12187] Starting a new job with PID 12187
2019-05-20 02:30:23,273 [salt.minion      :1711][INFO    ][12187] Returning information for job: 20190520023023210111
2019-05-20 02:30:53,395 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520023053380360
2019-05-20 02:30:53,419 [salt.minion      :1432][INFO    ][12205] Starting a new job with PID 12205
2019-05-20 02:30:53,442 [salt.minion      :1711][INFO    ][12205] Returning information for job: 20190520023053380360
2019-05-20 02:30:56,021 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1545.59902501s left)
2019-05-20 02:31:23,570 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520023123557700
2019-05-20 02:31:23,593 [salt.minion      :1432][INFO    ][12262] Starting a new job with PID 12262
2019-05-20 02:31:23,617 [salt.minion      :1711][INFO    ][12262] Returning information for job: 20190520023123557700
2019-05-20 02:31:29,549 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1512.07101893s left)
2019-05-20 02:31:53,749 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520023153736741
2019-05-20 02:31:53,771 [salt.minion      :1432][INFO    ][12283] Starting a new job with PID 12283
2019-05-20 02:31:53,796 [salt.minion      :1711][INFO    ][12283] Returning information for job: 20190520023153736741
2019-05-20 02:32:03,058 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1478.56143999s left)
2019-05-20 02:32:23,939 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520023223927252
2019-05-20 02:32:23,961 [salt.minion      :1432][INFO    ][12342] Starting a new job with PID 12342
2019-05-20 02:32:23,983 [salt.minion      :1711][INFO    ][12342] Returning information for job: 20190520023223927252
2019-05-20 02:32:36,448 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1445.17156601s left)
2019-05-20 02:32:54,147 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520023254134476
2019-05-20 02:32:54,169 [salt.minion      :1432][INFO    ][12372] Starting a new job with PID 12372
2019-05-20 02:32:54,194 [salt.minion      :1711][INFO    ][12372] Returning information for job: 20190520023254134476
2019-05-20 02:33:10,163 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1411.45696902s left)
2019-05-20 02:33:24,363 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520023324350312
2019-05-20 02:33:24,386 [salt.minion      :1432][INFO    ][12577] Starting a new job with PID 12577
2019-05-20 02:33:24,410 [salt.minion      :1711][INFO    ][12577] Returning information for job: 20190520023324350312
2019-05-20 02:33:43,485 [salt.loaded.ext.module.maas:1023][INFO    ][8765] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1378.13461995s left)
2019-05-20 02:33:54,589 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520023354575697
2019-05-20 02:33:54,611 [salt.minion      :1432][INFO    ][12600] Starting a new job with PID 12600
2019-05-20 02:33:54,635 [salt.minion      :1711][INFO    ][12600] Returning information for job: 20190520023354575697
2019-05-20 02:34:15,556 [salt.loaded.ext.module.maas:993 ][INFO    ][8765] Machine g7k7bm mark broken
2019-05-20 02:34:16,338 [salt.loaded.ext.module.maas:996 ][INFO    ][8765] Machine g7k7bm mark fixed
2019-05-20 02:34:17,672 [salt.loaded.ext.module.maas:684 ][INFO    ][8765] deploymachines hwe_kernel=ga-16.04 system_id=g7k7bm distro_series=xenial
2019-05-20 02:34:20,503 [salt.loaded.ext.module.maas:160 ][ERROR   ][8765] Failed for object kvm01 reason Unable to change power state to 'cycle' for node kvm01: another action is already in progress for that node.
2019-05-20 02:34:20,505 [salt.state       :302 ][ERROR   ][8765] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm03', 'kvm02'], 'errors': {'kvm01': "Unable to change power state to 'cycle' for node kvm01: another action is already in progress for that node."}, 'success': []}
2019-05-20 02:34:20,506 [salt.state       :1951][INFO    ][8765] Completed state [maas.wait_for_machine_status] at time 02:34:20.506400 duration_in_ms=908898.381
2019-05-20 02:34:20,516 [salt.minion      :1711][INFO    ][8765] Returning information for job: 20190520021906227462
2019-05-20 02:34:31,314 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command pillar.get with jid 20190520023431301477
2019-05-20 02:34:31,336 [salt.minion      :1432][INFO    ][12738] Starting a new job with PID 12738
2019-05-20 02:34:31,345 [salt.minion      :1711][INFO    ][12738] Returning information for job: 20190520023431301477
2019-05-20 02:34:31,849 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command service.status with jid 20190520023431842395
2019-05-20 02:34:31,870 [salt.minion      :1432][INFO    ][12743] Starting a new job with PID 12743
2019-05-20 02:34:32,273 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12743] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-20 02:34:32,306 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12743] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-20 02:34:32,321 [salt.minion      :1711][INFO    ][12743] Returning information for job: 20190520023431842395
2019-05-20 02:34:32,861 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command state.apply with jid 20190520023432850152
2019-05-20 02:34:32,882 [salt.minion      :1432][INFO    ][12754] Starting a new job with PID 12754
2019-05-20 02:34:36,640 [salt.state       :915 ][INFO    ][12754] Loading fresh modules for state activity
2019-05-20 02:34:37,092 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12754] Executing command 'salt-minion --version' in directory '/root'
2019-05-20 02:34:37,448 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12754] Executing command 'salt-minion --version' in directory '/root'
2019-05-20 02:34:38,333 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12754] Executing command 'salt-minion --version' in directory '/root'
2019-05-20 02:34:38,694 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12754] Executing command 'salt-minion --version' in directory '/root'
2019-05-20 02:34:39,981 [salt.state       :1780][INFO    ][12754] Running state [salt-minion] at time 02:34:39.981881
2019-05-20 02:34:39,982 [salt.state       :1813][INFO    ][12754] Executing state pkg.installed for [salt-minion]
2019-05-20 02:34:39,983 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12754] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-20 02:34:40,094 [salt.state       :300 ][INFO    ][12754] All specified packages are already installed
2019-05-20 02:34:40,095 [salt.state       :1951][INFO    ][12754] Completed state [salt-minion] at time 02:34:40.094934 duration_in_ms=113.053
2019-05-20 02:34:40,095 [salt.state       :1780][INFO    ][12754] Running state [salt_minion_dependency_packages] at time 02:34:40.095407
2019-05-20 02:34:40,095 [salt.state       :1813][INFO    ][12754] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-20 02:34:40,104 [salt.state       :300 ][INFO    ][12754] All specified packages are already installed
2019-05-20 02:34:40,104 [salt.state       :1951][INFO    ][12754] Completed state [salt_minion_dependency_packages] at time 02:34:40.104610 duration_in_ms=9.203
2019-05-20 02:34:40,108 [salt.state       :1780][INFO    ][12754] Running state [/etc/salt/minion.d/minion.conf] at time 02:34:40.108686
2019-05-20 02:34:40,109 [salt.state       :1813][INFO    ][12754] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-20 02:34:40,315 [salt.state       :300 ][INFO    ][12754] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-20 02:34:40,315 [salt.state       :1951][INFO    ][12754] Completed state [/etc/salt/minion.d/minion.conf] at time 02:34:40.315904 duration_in_ms=207.218
2019-05-20 02:34:40,316 [salt.state       :1780][INFO    ][12754] Running state [python-netaddr] at time 02:34:40.316109
2019-05-20 02:34:40,316 [salt.state       :1813][INFO    ][12754] Executing state pkg.installed for [python-netaddr]
2019-05-20 02:34:40,320 [salt.state       :300 ][INFO    ][12754] All specified packages are already installed
2019-05-20 02:34:40,320 [salt.state       :1951][INFO    ][12754] Completed state [python-netaddr] at time 02:34:40.320801 duration_in_ms=4.693
2019-05-20 02:34:40,322 [salt.state       :1780][INFO    ][12754] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:34:40.322880
2019-05-20 02:34:40,323 [salt.state       :1813][INFO    ][12754] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-20 02:34:40,332 [salt.state       :300 ][INFO    ][12754] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-20 02:34:40,332 [salt.state       :1951][INFO    ][12754] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:34:40.332833 duration_in_ms=9.953
2019-05-20 02:34:40,333 [salt.state       :1780][INFO    ][12754] Running state [salt-minion] at time 02:34:40.333508
2019-05-20 02:34:40,333 [salt.state       :1813][INFO    ][12754] Executing state service.running for [salt-minion]
2019-05-20 02:34:40,334 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12754] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-20 02:34:40,367 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12754] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-20 02:34:40,383 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12754] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-20 02:34:40,398 [salt.state       :300 ][INFO    ][12754] The service salt-minion is already running
2019-05-20 02:34:40,399 [salt.state       :1951][INFO    ][12754] Completed state [salt-minion] at time 02:34:40.398950 duration_in_ms=65.441
2019-05-20 02:34:40,400 [salt.state       :1780][INFO    ][12754] Running state [/etc/salt/grains.d] at time 02:34:40.400005
2019-05-20 02:34:40,400 [salt.state       :1813][INFO    ][12754] Executing state file.directory for [/etc/salt/grains.d]
2019-05-20 02:34:40,400 [salt.state       :300 ][INFO    ][12754] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-20 02:34:40,401 [salt.state       :1951][INFO    ][12754] Completed state [/etc/salt/grains.d] at time 02:34:40.401082 duration_in_ms=1.077
2019-05-20 02:34:40,401 [salt.state       :1780][INFO    ][12754] Running state [/etc/salt/grains] at time 02:34:40.401560
2019-05-20 02:34:40,401 [salt.state       :1813][INFO    ][12754] Executing state file.managed for [/etc/salt/grains]
2019-05-20 02:34:40,402 [salt.state       :300 ][INFO    ][12754] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-20 02:34:40,402 [salt.state       :1951][INFO    ][12754] Completed state [/etc/salt/grains] at time 02:34:40.402325 duration_in_ms=0.765
2019-05-20 02:34:40,402 [salt.state       :1780][INFO    ][12754] Running state [/etc/salt/grains.d/placeholder] at time 02:34:40.402669
2019-05-20 02:34:40,402 [salt.state       :1813][INFO    ][12754] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-20 02:34:40,403 [salt.state       :300 ][INFO    ][12754] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-20 02:34:40,403 [salt.state       :1951][INFO    ][12754] Completed state [/etc/salt/grains.d/placeholder] at time 02:34:40.403403 duration_in_ms=0.735
2019-05-20 02:34:40,403 [salt.state       :1780][INFO    ][12754] Running state [/etc/salt/grains.d/sphinx] at time 02:34:40.403756
2019-05-20 02:34:40,403 [salt.state       :1813][INFO    ][12754] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-20 02:34:40,417 [salt.state       :300 ][INFO    ][12754] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-20 02:34:40,418 [salt.state       :1951][INFO    ][12754] Completed state [/etc/salt/grains.d/sphinx] at time 02:34:40.418155 duration_in_ms=14.398
2019-05-20 02:34:40,421 [salt.state       :1780][INFO    ][12754] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:34:40.421849
2019-05-20 02:34:40,422 [salt.state       :1813][INFO    ][12754] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-20 02:34:40,422 [salt.state       :300 ][INFO    ][12754] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-20 02:34:40,423 [salt.state       :1951][INFO    ][12754] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:34:40.423132 duration_in_ms=1.284
2019-05-20 02:34:40,423 [salt.state       :1780][INFO    ][12754] Running state [/etc/salt/grains.d/dns_records] at time 02:34:40.423871
2019-05-20 02:34:40,424 [salt.state       :1813][INFO    ][12754] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-20 02:34:40,435 [salt.state       :300 ][INFO    ][12754] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-20 02:34:40,436 [salt.state       :1951][INFO    ][12754] Completed state [/etc/salt/grains.d/dns_records] at time 02:34:40.435962 duration_in_ms=12.091
2019-05-20 02:34:40,437 [salt.state       :1780][INFO    ][12754] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:34:40.437399
2019-05-20 02:34:40,437 [salt.state       :1813][INFO    ][12754] 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-20 02:34:40,438 [salt.state       :300 ][INFO    ][12754] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-20 02:34:40,438 [salt.state       :1951][INFO    ][12754] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:34:40.438711 duration_in_ms=1.312
2019-05-20 02:34:40,439 [salt.state       :1780][INFO    ][12754] Running state [/etc/salt/grains.d/salt] at time 02:34:40.439432
2019-05-20 02:34:40,439 [salt.state       :1813][INFO    ][12754] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-20 02:34:40,453 [salt.state       :300 ][INFO    ][12754] File /etc/salt/grains.d/salt is in the correct state
2019-05-20 02:34:40,453 [salt.state       :1951][INFO    ][12754] Completed state [/etc/salt/grains.d/salt] at time 02:34:40.453877 duration_in_ms=14.444
2019-05-20 02:34:40,455 [salt.state       :1780][INFO    ][12754] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:34:40.455166
2019-05-20 02:34:40,455 [salt.state       :1813][INFO    ][12754] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-20 02:34:40,456 [salt.state       :300 ][INFO    ][12754] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-20 02:34:40,456 [salt.state       :1951][INFO    ][12754] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:34:40.456347 duration_in_ms=1.181
2019-05-20 02:34:40,459 [salt.state       :1780][INFO    ][12754] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:34:40.459130
2019-05-20 02:34:40,459 [salt.state       :1813][INFO    ][12754] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-20 02:34:40,460 [salt.state       :300 ][INFO    ][12754] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-20 02:34:40,460 [salt.state       :1951][INFO    ][12754] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:34:40.460311 duration_in_ms=1.181
2019-05-20 02:34:40,461 [salt.state       :1780][INFO    ][12754] Running state [mine.update] at time 02:34:40.461268
2019-05-20 02:34:40,461 [salt.state       :1813][INFO    ][12754] Executing state module.wait for [mine.update]
2019-05-20 02:34:40,462 [salt.state       :300 ][INFO    ][12754] No changes made for mine.update
2019-05-20 02:34:40,462 [salt.state       :1951][INFO    ][12754] Completed state [mine.update] at time 02:34:40.462350 duration_in_ms=1.081
2019-05-20 02:34:40,462 [salt.state       :1780][INFO    ][12754] Running state [ca-certificates] at time 02:34:40.462705
2019-05-20 02:34:40,463 [salt.state       :1813][INFO    ][12754] Executing state pkg.installed for [ca-certificates]
2019-05-20 02:34:40,473 [salt.state       :300 ][INFO    ][12754] All specified packages are already installed
2019-05-20 02:34:40,473 [salt.state       :1951][INFO    ][12754] Completed state [ca-certificates] at time 02:34:40.473539 duration_in_ms=10.833
2019-05-20 02:34:40,474 [salt.state       :1780][INFO    ][12754] Running state [update-ca-certificates] at time 02:34:40.474460
2019-05-20 02:34:40,474 [salt.state       :1813][INFO    ][12754] Executing state cmd.wait for [update-ca-certificates]
2019-05-20 02:34:40,475 [salt.state       :300 ][INFO    ][12754] No changes made for update-ca-certificates
2019-05-20 02:34:40,475 [salt.state       :1951][INFO    ][12754] Completed state [update-ca-certificates] at time 02:34:40.475525 duration_in_ms=1.064
2019-05-20 02:34:40,475 [salt.state       :1780][INFO    ][12754] Running state [iptables] at time 02:34:40.475849
2019-05-20 02:34:40,476 [salt.state       :1813][INFO    ][12754] Executing state pkg.installed for [iptables]
2019-05-20 02:34:40,485 [salt.state       :300 ][INFO    ][12754] All specified packages are already installed
2019-05-20 02:34:40,485 [salt.state       :1951][INFO    ][12754] Completed state [iptables] at time 02:34:40.485530 duration_in_ms=9.68
2019-05-20 02:34:40,485 [salt.state       :1780][INFO    ][12754] Running state [iptables-persistent] at time 02:34:40.485841
2019-05-20 02:34:40,486 [salt.state       :1813][INFO    ][12754] Executing state pkg.installed for [iptables-persistent]
2019-05-20 02:34:40,494 [salt.state       :300 ][INFO    ][12754] All specified packages are already installed
2019-05-20 02:34:40,495 [salt.state       :1951][INFO    ][12754] Completed state [iptables-persistent] at time 02:34:40.495207 duration_in_ms=9.366
2019-05-20 02:34:40,496 [salt.state       :1780][INFO    ][12754] Running state [iptables_modules_v4_load] at time 02:34:40.496433
2019-05-20 02:34:40,496 [salt.state       :1813][INFO    ][12754] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-20 02:34:40,497 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12754] Executing command 'lsmod' in directory '/root'
2019-05-20 02:34:40,520 [salt.state       :300 ][INFO    ][12754] Kernel modules iptable_filter, ip_tables are already present
2019-05-20 02:34:40,520 [salt.state       :1951][INFO    ][12754] Completed state [iptables_modules_v4_load] at time 02:34:40.520755 duration_in_ms=24.321
2019-05-20 02:34:40,521 [salt.state       :1780][INFO    ][12754] Running state [/etc/iptables/rules.v4] at time 02:34:40.521856
2019-05-20 02:34:40,522 [salt.state       :1813][INFO    ][12754] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-20 02:34:40,639 [salt.state       :300 ][INFO    ][12754] File /etc/iptables/rules.v4 is in the correct state
2019-05-20 02:34:40,640 [salt.state       :1951][INFO    ][12754] Completed state [/etc/iptables/rules.v4] at time 02:34:40.640320 duration_in_ms=118.463
2019-05-20 02:34:40,642 [salt.state       :1780][INFO    ][12754] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:34:40.642109
2019-05-20 02:34:40,642 [salt.state       :1813][INFO    ][12754] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-20 02:34:40,643 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12754] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-20 02:34:40,662 [salt.state       :300 ][INFO    ][12754] onlyif execution failed
2019-05-20 02:34:40,663 [salt.state       :1951][INFO    ][12754] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:34:40.663050 duration_in_ms=20.942
2019-05-20 02:34:40,664 [salt.state       :1780][INFO    ][12754] Running state [netfilter-persistent] at time 02:34:40.664366
2019-05-20 02:34:40,664 [salt.state       :1813][INFO    ][12754] Executing state service.running for [netfilter-persistent]
2019-05-20 02:34:40,665 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12754] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-20 02:34:40,684 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12754] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-20 02:34:40,701 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12754] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-20 02:34:40,717 [salt.state       :300 ][INFO    ][12754] The service netfilter-persistent is already running
2019-05-20 02:34:40,717 [salt.state       :1951][INFO    ][12754] Completed state [netfilter-persistent] at time 02:34:40.717511 duration_in_ms=53.145
2019-05-20 02:34:40,718 [salt.state       :1780][INFO    ][12754] Running state [iptables_extra.remove_stale_tables] at time 02:34:40.718836
2019-05-20 02:34:40,719 [salt.state       :1813][INFO    ][12754] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-20 02:34:40,719 [salt.state       :300 ][INFO    ][12754] No changes made for iptables_extra.remove_stale_tables
2019-05-20 02:34:40,720 [salt.state       :1951][INFO    ][12754] Completed state [iptables_extra.remove_stale_tables] at time 02:34:40.720184 duration_in_ms=1.348
2019-05-20 02:34:40,720 [salt.state       :1780][INFO    ][12754] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:34:40.720563
2019-05-20 02:34:40,720 [salt.state       :1813][INFO    ][12754] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-20 02:34:40,721 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12754] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-20 02:34:40,736 [salt.state       :300 ][INFO    ][12754] onlyif execution failed
2019-05-20 02:34:40,737 [salt.state       :1951][INFO    ][12754] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:34:40.736885 duration_in_ms=16.322
2019-05-20 02:34:40,738 [salt.state       :1780][INFO    ][12754] Running state [/etc/iptables/rules.v6] at time 02:34:40.738323
2019-05-20 02:34:40,738 [salt.state       :1813][INFO    ][12754] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-20 02:34:40,739 [salt.state       :300 ][INFO    ][12754] File /etc/iptables/rules.v6 is not present
2019-05-20 02:34:40,739 [salt.state       :1951][INFO    ][12754] Completed state [/etc/iptables/rules.v6] at time 02:34:40.739848 duration_in_ms=1.525
2019-05-20 02:34:40,740 [salt.state       :1780][INFO    ][12754] Running state [iptables_extra.flush_all] at time 02:34:40.740872
2019-05-20 02:34:40,741 [salt.state       :1813][INFO    ][12754] Executing state module.wait for [iptables_extra.flush_all]
2019-05-20 02:34:40,741 [salt.state       :300 ][INFO    ][12754] No changes made for iptables_extra.flush_all
2019-05-20 02:34:40,742 [salt.state       :1951][INFO    ][12754] Completed state [iptables_extra.flush_all] at time 02:34:40.742079 duration_in_ms=1.207
2019-05-20 02:34:40,770 [salt.minion      :1711][INFO    ][12754] Returning information for job: 20190520023432850152
2019-05-20 02:34:41,406 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command state.apply with jid 20190520023441393126
2019-05-20 02:34:41,429 [salt.minion      :1432][INFO    ][12830] Starting a new job with PID 12830
2019-05-20 02:34:42,334 [salt.state       :915 ][INFO    ][12830] Loading fresh modules for state activity
2019-05-20 02:34:43,005 [salt.state       :1780][INFO    ][12830] Running state [maas-rack-controller] at time 02:34:43.005796
2019-05-20 02:34:43,006 [salt.state       :1813][INFO    ][12830] Executing state pkg.installed for [maas-rack-controller]
2019-05-20 02:34:43,006 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12830] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-20 02:34:43,093 [salt.state       :300 ][INFO    ][12830] All specified packages are already installed
2019-05-20 02:34:43,093 [salt.state       :1951][INFO    ][12830] Completed state [maas-rack-controller] at time 02:34:43.093472 duration_in_ms=87.675
2019-05-20 02:34:43,093 [salt.state       :1780][INFO    ][12830] Running state [ipmitool] at time 02:34:43.093797
2019-05-20 02:34:43,094 [salt.state       :1813][INFO    ][12830] Executing state pkg.installed for [ipmitool]
2019-05-20 02:34:43,099 [salt.state       :300 ][INFO    ][12830] All specified packages are already installed
2019-05-20 02:34:43,100 [salt.state       :1951][INFO    ][12830] Completed state [ipmitool] at time 02:34:43.100124 duration_in_ms=6.328
2019-05-20 02:34:43,103 [salt.state       :1780][INFO    ][12830] Running state [/etc/maas/rackd.conf] at time 02:34:43.102994
2019-05-20 02:34:43,103 [salt.state       :1813][INFO    ][12830] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-20 02:34:43,104 [salt.state       :300 ][INFO    ][12830] No changes needed to be made
2019-05-20 02:34:43,104 [salt.state       :1951][INFO    ][12830] Completed state [/etc/maas/rackd.conf] at time 02:34:43.104417 duration_in_ms=1.423
2019-05-20 02:34:43,104 [salt.state       :1780][INFO    ][12830] Running state [/etc/maas/rackd.conf] at time 02:34:43.104646
2019-05-20 02:34:43,104 [salt.state       :1813][INFO    ][12830] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-20 02:34:43,105 [salt.loaded.int.states.file:2298][WARNING ][12830] 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-20 02:34:43,105 [salt.state       :300 ][INFO    ][12830] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-20 02:34:43,105 [salt.state       :1951][INFO    ][12830] Completed state [/etc/maas/rackd.conf] at time 02:34:43.105783 duration_in_ms=1.137
2019-05-20 02:34:43,106 [salt.state       :1780][INFO    ][12830] Running state [maas-rackd] at time 02:34:43.106663
2019-05-20 02:34:43,106 [salt.state       :1813][INFO    ][12830] Executing state service.running for [maas-rackd]
2019-05-20 02:34:43,107 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12830] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-20 02:34:43,142 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12830] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-20 02:34:43,159 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12830] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-20 02:34:43,176 [salt.state       :300 ][INFO    ][12830] The service maas-rackd is already running
2019-05-20 02:34:43,177 [salt.state       :1951][INFO    ][12830] Completed state [maas-rackd] at time 02:34:43.177379 duration_in_ms=70.715
2019-05-20 02:34:43,179 [salt.minion      :1711][INFO    ][12830] Returning information for job: 20190520023441393126
2019-05-20 02:34:43,737 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command state.apply with jid 20190520023443724043
2019-05-20 02:34:43,758 [salt.minion      :1432][INFO    ][12854] Starting a new job with PID 12854
2019-05-20 02:34:44,561 [salt.state       :915 ][INFO    ][12854] Loading fresh modules for state activity
2019-05-20 02:34:45,349 [salt.state       :1780][INFO    ][12854] Running state [maas-region-controller] at time 02:34:45.349407
2019-05-20 02:34:45,349 [salt.state       :1813][INFO    ][12854] Executing state pkg.installed for [maas-region-controller]
2019-05-20 02:34:45,350 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12854] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-20 02:34:45,445 [salt.state       :300 ][INFO    ][12854] All specified packages are already installed
2019-05-20 02:34:45,446 [salt.state       :1951][INFO    ][12854] Completed state [maas-region-controller] at time 02:34:45.446250 duration_in_ms=96.843
2019-05-20 02:34:45,446 [salt.state       :1780][INFO    ][12854] Running state [python-oauth] at time 02:34:45.446632
2019-05-20 02:34:45,446 [salt.state       :1813][INFO    ][12854] Executing state pkg.installed for [python-oauth]
2019-05-20 02:34:45,458 [salt.state       :300 ][INFO    ][12854] All specified packages are already installed
2019-05-20 02:34:45,458 [salt.state       :1951][INFO    ][12854] Completed state [python-oauth] at time 02:34:45.458888 duration_in_ms=12.255
2019-05-20 02:34:45,464 [salt.state       :1780][INFO    ][12854] Running state [/etc/maas/regiond.conf] at time 02:34:45.464099
2019-05-20 02:34:45,464 [salt.state       :1813][INFO    ][12854] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-20 02:34:45,490 [salt.state       :300 ][INFO    ][12854] No changes needed to be made
2019-05-20 02:34:45,490 [salt.state       :1951][INFO    ][12854] Completed state [/etc/maas/regiond.conf] at time 02:34:45.490269 duration_in_ms=26.17
2019-05-20 02:34:45,490 [salt.state       :1780][INFO    ][12854] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:34:45.490616
2019-05-20 02:34:45,490 [salt.state       :1813][INFO    ][12854] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-20 02:34:45,549 [salt.state       :300 ][INFO    ][12854] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-20 02:34:45,549 [salt.state       :1951][INFO    ][12854] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:34:45.549559 duration_in_ms=58.944
2019-05-20 02:34:45,549 [salt.state       :1780][INFO    ][12854] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:34:45.549907
2019-05-20 02:34:45,550 [salt.state       :1813][INFO    ][12854] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-20 02:34:45,562 [salt.state       :300 ][INFO    ][12854] No changes needed to be made
2019-05-20 02:34:45,562 [salt.state       :1951][INFO    ][12854] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:34:45.562445 duration_in_ms=12.538
2019-05-20 02:34:45,562 [salt.state       :1780][INFO    ][12854] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:34:45.562784
2019-05-20 02:34:45,563 [salt.state       :1813][INFO    ][12854] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-20 02:34:45,580 [salt.state       :300 ][INFO    ][12854] No changes needed to be made
2019-05-20 02:34:45,580 [salt.state       :1951][INFO    ][12854] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:34:45.580598 duration_in_ms=17.815
2019-05-20 02:34:45,580 [salt.state       :1780][INFO    ][12854] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:34:45.580942
2019-05-20 02:34:45,581 [salt.state       :1813][INFO    ][12854] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-20 02:34:45,598 [salt.state       :300 ][INFO    ][12854] No changes needed to be made
2019-05-20 02:34:45,598 [salt.state       :1951][INFO    ][12854] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:34:45.598408 duration_in_ms=17.466
2019-05-20 02:34:45,598 [salt.state       :1780][INFO    ][12854] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:34:45.598750
2019-05-20 02:34:45,598 [salt.state       :1813][INFO    ][12854] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-20 02:34:45,608 [salt.state       :300 ][INFO    ][12854] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-20 02:34:45,608 [salt.state       :1951][INFO    ][12854] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:34:45.608453 duration_in_ms=9.703
2019-05-20 02:34:45,609 [salt.state       :1780][INFO    ][12854] Running state [a2enmod headers] at time 02:34:45.609392
2019-05-20 02:34:45,609 [salt.state       :1813][INFO    ][12854] Executing state cmd.run for [a2enmod headers]
2019-05-20 02:34:45,610 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12854] Executing command 'a2enmod headers' in directory '/root'
2019-05-20 02:34:45,680 [salt.state       :300 ][INFO    ][12854] {'pid': 12873, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-20 02:34:45,680 [salt.state       :1951][INFO    ][12854] Completed state [a2enmod headers] at time 02:34:45.680613 duration_in_ms=71.221
2019-05-20 02:34:45,681 [salt.state       :1780][INFO    ][12854] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:34:45.681050
2019-05-20 02:34:45,681 [salt.state       :1813][INFO    ][12854] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-20 02:34:45,697 [salt.state       :300 ][INFO    ][12854] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-20 02:34:45,697 [salt.state       :1951][INFO    ][12854] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:34:45.697360 duration_in_ms=16.309
2019-05-20 02:34:45,698 [salt.state       :1780][INFO    ][12854] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:34:45.698076
2019-05-20 02:34:45,698 [salt.state       :1813][INFO    ][12854] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-20 02:34:45,765 [salt.state       :300 ][INFO    ][12854] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-20 02:34:45,765 [salt.state       :1951][INFO    ][12854] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:34:45.765736 duration_in_ms=67.659
2019-05-20 02:34:45,766 [salt.state       :1780][INFO    ][12854] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:34:45.766245
2019-05-20 02:34:45,766 [salt.state       :1813][INFO    ][12854] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-20 02:34:45,819 [salt.state       :300 ][INFO    ][12854] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-20 02:34:45,819 [salt.state       :1951][INFO    ][12854] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:34:45.819386 duration_in_ms=53.141
2019-05-20 02:34:45,819 [salt.state       :1780][INFO    ][12854] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:34:45.819832
2019-05-20 02:34:45,820 [salt.state       :1813][INFO    ][12854] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-20 02:34:45,873 [salt.state       :300 ][INFO    ][12854] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-20 02:34:45,873 [salt.state       :1951][INFO    ][12854] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:34:45.873272 duration_in_ms=53.439
2019-05-20 02:34:45,873 [salt.state       :1780][INFO    ][12854] Running state [/root/.pgpass] at time 02:34:45.873532
2019-05-20 02:34:45,873 [salt.state       :1813][INFO    ][12854] Executing state file.managed for [/root/.pgpass]
2019-05-20 02:34:45,915 [salt.state       :300 ][INFO    ][12854] File /root/.pgpass is in the correct state
2019-05-20 02:34:45,915 [salt.state       :1951][INFO    ][12854] Completed state [/root/.pgpass] at time 02:34:45.915207 duration_in_ms=41.675
2019-05-20 02:34:45,919 [salt.state       :1780][INFO    ][12854] Running state [maas-region syncdb --noinput] at time 02:34:45.919372
2019-05-20 02:34:45,919 [salt.state       :1813][INFO    ][12854] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-20 02:34:45,920 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12854] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-20 02:34:47,741 [salt.state       :300 ][INFO    ][12854] {'pid': 12886, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: piston3, maasserver, sessions, sites, auth, metadataserver, contenttypes\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-20 02:34:47,742 [salt.state       :1951][INFO    ][12854] Completed state [maas-region syncdb --noinput] at time 02:34:47.742475 duration_in_ms=1823.102
2019-05-20 02:34:47,742 [salt.state       :2022][WARNING ][12854] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-20 02:34:47,745 [salt.state       :1780][INFO    ][12854] Running state [maas-regiond] at time 02:34:47.745686
2019-05-20 02:34:47,746 [salt.state       :1813][INFO    ][12854] Executing state service.running for [maas-regiond]
2019-05-20 02:34:47,747 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12854] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-20 02:34:47,783 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12854] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-20 02:34:47,801 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12854] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-20 02:34:47,818 [salt.state       :300 ][INFO    ][12854] The service maas-regiond is already running
2019-05-20 02:34:47,819 [salt.state       :1951][INFO    ][12854] Completed state [maas-regiond] at time 02:34:47.819071 duration_in_ms=73.385
2019-05-20 02:34:47,821 [salt.state       :1780][INFO    ][12854] Running state [bind9] at time 02:34:47.821468
2019-05-20 02:34:47,821 [salt.state       :1813][INFO    ][12854] Executing state service.running for [bind9]
2019-05-20 02:34:47,822 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12854] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-20 02:34:47,840 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12854] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-20 02:34:47,856 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12854] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-20 02:34:47,871 [salt.state       :300 ][INFO    ][12854] The service bind9 is already running
2019-05-20 02:34:47,871 [salt.state       :1951][INFO    ][12854] Completed state [bind9] at time 02:34:47.871720 duration_in_ms=50.252
2019-05-20 02:34:47,873 [salt.state       :1780][INFO    ][12854] Running state [apache2] at time 02:34:47.873941
2019-05-20 02:34:47,874 [salt.state       :1813][INFO    ][12854] Executing state service.running for [apache2]
2019-05-20 02:34:47,875 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12854] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-20 02:34:47,892 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12854] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-20 02:34:47,907 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12854] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-20 02:34:47,926 [salt.state       :300 ][INFO    ][12854] The service apache2 is already running
2019-05-20 02:34:47,926 [salt.state       :1951][INFO    ][12854] Completed state [apache2] at time 02:34:47.926560 duration_in_ms=52.619
2019-05-20 02:34:47,928 [salt.state       :1780][INFO    ][12854] Running state [maasng.wait_for_http_code] at time 02:34:47.928344
2019-05-20 02:34:47,928 [salt.state       :1813][INFO    ][12854] Executing state module.run for [maasng.wait_for_http_code]
2019-05-20 02:34:47,929 [salt.utils.decorators:613 ][WARNING ][12854] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 02:34:48,028 [salt.state       :300 ][INFO    ][12854] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-20 02:34:48,028 [salt.state       :1951][INFO    ][12854] Completed state [maasng.wait_for_http_code] at time 02:34:48.028832 duration_in_ms=100.487
2019-05-20 02:34:48,030 [salt.state       :1780][INFO    ][12854] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:34:48.030270
2019-05-20 02:34:48,030 [salt.state       :1813][INFO    ][12854] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-20 02:34:48,031 [salt.state       :300 ][INFO    ][12854] /var/lib/maas/.setup_admin exists
2019-05-20 02:34:48,031 [salt.state       :1951][INFO    ][12854] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:34:48.031398 duration_in_ms=1.129
2019-05-20 02:34:48,032 [salt.state       :1780][INFO    ][12854] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:34:48.032185
2019-05-20 02:34:48,032 [salt.state       :1813][INFO    ][12854] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 02:34:48,033 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12854] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 02:34:49,339 [salt.state       :300 ][INFO    ][12854] {'pid': 12908, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 02:34:49,340 [salt.state       :1951][INFO    ][12854] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:34:49.340402 duration_in_ms=1308.216
2019-05-20 02:34:49,348 [salt.state       :1780][INFO    ][12854] Running state [maas_region_boot_source_resources_mirror] at time 02:34:49.348280
2019-05-20 02:34:49,348 [salt.state       :1813][INFO    ][12854] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-20 02:34:49,463 [salt.state       :300 ][INFO    ][12854] {'changes': {}}
2019-05-20 02:34:49,464 [salt.state       :1951][INFO    ][12854] Completed state [maas_region_boot_source_resources_mirror] at time 02:34:49.464535 duration_in_ms=116.253
2019-05-20 02:34:49,465 [salt.state       :1780][INFO    ][12854] Running state [maasng.boot_resources_import] at time 02:34:49.465577
2019-05-20 02:34:49,466 [salt.state       :1813][INFO    ][12854] Executing state module.run for [maasng.boot_resources_import]
2019-05-20 02:34:49,466 [salt.utils.decorators:613 ][WARNING ][12854] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 02:34:49,578 [salt.loaded.ext.module.maasng:1600][INFO    ][12854] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-20 02:34:54,637 [salt.loaded.ext.module.maasng:1600][INFO    ][12854] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-20 02:34:58,828 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520023458814759
2019-05-20 02:34:58,851 [salt.minion      :1432][INFO    ][12948] Starting a new job with PID 12948
2019-05-20 02:34:58,874 [salt.minion      :1711][INFO    ][12948] Returning information for job: 20190520023458814759
2019-05-20 02:34:59,711 [salt.loaded.ext.module.maasng:1600][INFO    ][12854] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-05-20 02:35:04,825 [salt.state       :300 ][INFO    ][12854] {'ret': True}
2019-05-20 02:35:04,826 [salt.state       :1951][INFO    ][12854] Completed state [maasng.boot_resources_import] at time 02:35:04.826168 duration_in_ms=15360.59
2019-05-20 02:35:04,827 [salt.state       :1780][INFO    ][12854] Running state [maas_region_boot_sources_selection_xenial] at time 02:35:04.827452
2019-05-20 02:35:04,828 [salt.state       :1813][INFO    ][12854] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-20 02:35:05,022 [salt.state       :300 ][INFO    ][12854] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-20 02:35:05,023 [salt.state       :1951][INFO    ][12854] Completed state [maas_region_boot_sources_selection_xenial] at time 02:35:05.023374 duration_in_ms=195.92
2019-05-20 02:35:05,025 [salt.state       :1780][INFO    ][12854] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:35:05.025052
2019-05-20 02:35:05,025 [salt.state       :1813][INFO    ][12854] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-20 02:35:05,026 [salt.utils.decorators:613 ][WARNING ][12854] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 02:35:05,027 [salt.loaded.ext.module.maasng:1771][INFO    ][12854] boot-sources sync initiated for ALL Rack's
2019-05-20 02:35:05,932 [salt.state       :300 ][INFO    ][12854] {'ret': True}
2019-05-20 02:35:05,932 [salt.state       :1951][INFO    ][12854] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:35:05.932504 duration_in_ms=907.452
2019-05-20 02:35:05,934 [salt.state       :1780][INFO    ][12854] Running state [maas.process_maas_config] at time 02:35:05.934414
2019-05-20 02:35:05,934 [salt.state       :1813][INFO    ][12854] Executing state module.run for [maas.process_maas_config]
2019-05-20 02:35:05,935 [salt.utils.decorators:613 ][WARNING ][12854] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 02:35:05,936 [salt.loaded.ext.module.maas:92  ][INFO    ][12854] maasconfig name=enable_http_proxy value=True
2019-05-20 02:35:05,990 [salt.loaded.ext.module.maas:92  ][INFO    ][12854] maasconfig name=upstream_dns value=8.8.8.8
2019-05-20 02:35:08,947 [salt.loaded.ext.module.maas:92  ][INFO    ][12854] maasconfig name=commissioning_distro_series value=xenial
2019-05-20 02:35:09,011 [salt.loaded.ext.module.maas:92  ][INFO    ][12854] maasconfig name=default_osystem value=ubuntu
2019-05-20 02:35:09,074 [salt.loaded.ext.module.maas:92  ][INFO    ][12854] maasconfig name=active_discovery_interval value=600
2019-05-20 02:35:09,161 [salt.loaded.ext.module.maas:92  ][INFO    ][12854] maasconfig name=dnssec_validation value=no
2019-05-20 02:35:09,213 [salt.loaded.ext.module.maas:92  ][INFO    ][12854] maasconfig name=maas_name value=mas01
2019-05-20 02:35:09,260 [salt.loaded.ext.module.maas:92  ][INFO    ][12854] maasconfig name=network_discovery value=enabled
2019-05-20 02:35:09,375 [salt.loaded.ext.module.maas:92  ][INFO    ][12854] maasconfig name=enable_third_party_drivers value=True
2019-05-20 02:35:09,429 [salt.loaded.ext.module.maas:92  ][INFO    ][12854] maasconfig name=default_storage_layout value=lvm
2019-05-20 02:35:09,502 [salt.loaded.ext.module.maas:92  ][INFO    ][12854] maasconfig name=ntp_external_only value=True
2019-05-20 02:35:09,568 [salt.loaded.ext.module.maas:92  ][INFO    ][12854] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-20 02:35:09,634 [salt.loaded.ext.module.maas:92  ][INFO    ][12854] maasconfig name=default_distro_series value=xenial
2019-05-20 02:35:09,706 [salt.loaded.ext.module.maas:92  ][INFO    ][12854] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-05-20 02:35:09,832 [salt.state       :300 ][INFO    ][12854] {'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-20 02:35:09,832 [salt.state       :1951][INFO    ][12854] Completed state [maas.process_maas_config] at time 02:35:09.832474 duration_in_ms=3898.059
2019-05-20 02:35:09,833 [salt.state       :1780][INFO    ][12854] Running state [pxe_admin] at time 02:35:09.833380
2019-05-20 02:35:09,833 [salt.state       :1813][INFO    ][12854] Executing state maasng.fabric_present for [pxe_admin]
2019-05-20 02:35:09,903 [salt.loaded.ext.module.maasng:945 ][INFO    ][12854] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fswc6s', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-05-20 02:35:10,019 [salt.loaded.ext.module.maasng:1008][WARNING ][12854] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-20 02:35:10,020 [salt.loaded.ext.module.maasng:1011][WARNING ][12854] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-20 02:35:10,095 [salt.state       :300 ][INFO    ][12854] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-20 02:35:10,096 [salt.state       :1951][INFO    ][12854] Completed state [pxe_admin] at time 02:35:10.096131 duration_in_ms=262.749
2019-05-20 02:35:10,096 [salt.state       :1780][INFO    ][12854] Running state [vlan 0] at time 02:35:10.096822
2019-05-20 02:35:10,097 [salt.state       :1813][INFO    ][12854] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-20 02:35:10,148 [salt.loaded.ext.module.maasng:945 ][INFO    ][12854] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fswc6s', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-05-20 02:35:10,273 [salt.loaded.ext.module.maasng:945 ][INFO    ][12854] [{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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-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'fswc6s', 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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-05-20 02:35:10,554 [salt.loaded.ext.module.maasng:945 ][INFO    ][12854] [{u'id': 0, 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'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'primary_rack': u'fswc6s', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-20 02:35:10,651 [salt.state       :300 ][INFO    ][12854] {'new': 'Vlan untagged was updated'}
2019-05-20 02:35:10,652 [salt.state       :1951][INFO    ][12854] Completed state [vlan 0] at time 02:35:10.651984 duration_in_ms=555.162
2019-05-20 02:35:10,653 [salt.state       :1780][INFO    ][12854] Running state [192.168.11.0/24] at time 02:35:10.653748
2019-05-20 02:35:10,654 [salt.state       :1813][INFO    ][12854] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-20 02:35:10,885 [salt.loaded.ext.module.maasng:945 ][INFO    ][12854] [{u'id': 0, 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'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'primary_rack': u'fswc6s', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-20 02:35:10,886 [salt.loaded.ext.module.maasng:1235][WARNING ][12854] Ignoring parameter vlan:0
2019-05-20 02:35:10,970 [salt.state       :300 ][INFO    ][12854] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-20 02:35:10,970 [salt.state       :1951][INFO    ][12854] Completed state [192.168.11.0/24] at time 02:35:10.970373 duration_in_ms=316.625
2019-05-20 02:35:10,971 [salt.state       :1780][INFO    ][12854] Running state [maas_create_iprange_1] at time 02:35:10.971438
2019-05-20 02:35:10,971 [salt.state       :1813][INFO    ][12854] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-20 02:35:11,048 [salt.state       :300 ][INFO    ][12854] Iprange maas_create_iprange_1 already exist.
2019-05-20 02:35:11,048 [salt.state       :1951][INFO    ][12854] Completed state [maas_create_iprange_1] at time 02:35:11.048860 duration_in_ms=77.422
2019-05-20 02:35:11,049 [salt.state       :1780][INFO    ][12854] Running state [vlan 0] at time 02:35:11.049297
2019-05-20 02:35:11,049 [salt.state       :1813][INFO    ][12854] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-20 02:35:11,107 [salt.loaded.ext.module.maasng:945 ][INFO    ][12854] [{u'id': 0, 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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'fswc6s', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-20 02:35:11,221 [salt.loaded.ext.module.maasng:945 ][INFO    ][12854] [{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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-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'fswc6s', 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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-05-20 02:35:11,453 [salt.loaded.ext.module.maasng:945 ][INFO    ][12854] [{u'id': 0, 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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'fswc6s', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-20 02:35:11,537 [salt.state       :300 ][INFO    ][12854] {'new': 'Vlan untagged was updated'}
2019-05-20 02:35:11,538 [salt.state       :1951][INFO    ][12854] Completed state [vlan 0] at time 02:35:11.538319 duration_in_ms=489.02
2019-05-20 02:35:11,539 [salt.state       :1780][INFO    ][12854] Running state [opnfv] at time 02:35:11.539651
2019-05-20 02:35:11,540 [salt.state       :1813][INFO    ][12854] Executing state maasng.sshkey_present for [opnfv]
2019-05-20 02:35:11,590 [salt.loaded.ext.module.maasng:1903][INFO    ][12854] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-05-20 02:35:11,591 [salt.state       :300 ][INFO    ][12854] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-05-20 02:35:11,591 [salt.state       :1951][INFO    ][12854] Completed state [opnfv] at time 02:35:11.591768 duration_in_ms=52.118
2019-05-20 02:35:11,596 [salt.minion      :1711][INFO    ][12854] Returning information for job: 20190520023443724043
2019-05-20 02:35:12,097 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command state.apply with jid 20190520023512090348
2019-05-20 02:35:12,115 [salt.minion      :1432][INFO    ][13322] Starting a new job with PID 13322
2019-05-20 02:35:15,658 [salt.state       :915 ][INFO    ][13322] Loading fresh modules for state activity
2019-05-20 02:35:15,754 [salt.state       :1780][INFO    ][13322] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:35:15.754268
2019-05-20 02:35:15,754 [salt.state       :1813][INFO    ][13322] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 02:35:15,756 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13322] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 02:35:17,311 [salt.state       :300 ][INFO    ][13322] {'pid': 13346, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 02:35:17,312 [salt.state       :1951][INFO    ][13322] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:35:17.312643 duration_in_ms=1558.374
2019-05-20 02:35:17,315 [salt.state       :1780][INFO    ][13322] Running state [maas.process_machines] at time 02:35:17.315153
2019-05-20 02:35:17,315 [salt.state       :1813][INFO    ][13322] Executing state module.run for [maas.process_machines]
2019-05-20 02:35:17,317 [salt.utils.decorators:613 ][WARNING ][13322] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 02:35:18,078 [salt.loaded.ext.module.maas:412 ][WARNING ][13322] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-20 02:35:18,079 [salt.loaded.ext.module.maas:92  ][INFO    ][13322] 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=y8xh4k architecture=amd64/generic power_parameters_power_user=admin
2019-05-20 02:35:19,289 [salt.loaded.ext.module.maas:412 ][WARNING ][13322] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-20 02:35:19,290 [salt.loaded.ext.module.maas:92  ][INFO    ][13322] 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=xwdmsx architecture=amd64/generic power_parameters_power_user=admin
2019-05-20 02:35:20,449 [salt.loaded.ext.module.maas:412 ][WARNING ][13322] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-20 02:35:20,450 [salt.loaded.ext.module.maas:92  ][INFO    ][13322] 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=g7k7bm architecture=amd64/generic power_parameters_power_user=admin
2019-05-20 02:35:21,689 [salt.loaded.ext.module.maas:412 ][WARNING ][13322] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-20 02:35:21,690 [salt.loaded.ext.module.maas:92  ][INFO    ][13322] 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=m4nxm7 architecture=amd64/generic power_parameters_power_user=admin
2019-05-20 02:35:23,036 [salt.loaded.ext.module.maas:412 ][WARNING ][13322] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-20 02:35:23,037 [salt.loaded.ext.module.maas:92  ][INFO    ][13322] 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=qw8h4q architecture=amd64/generic power_parameters_power_user=admin
2019-05-20 02:35:24,367 [salt.state       :300 ][INFO    ][13322] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-20 02:35:24,367 [salt.state       :1951][INFO    ][13322] Completed state [maas.process_machines] at time 02:35:24.367837 duration_in_ms=7052.684
2019-05-20 02:35:24,371 [salt.minion      :1711][INFO    ][13322] Returning information for job: 20190520023512090348
2019-05-20 02:35:57,596 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command state.apply with jid 20190520023557583358
2019-05-20 02:35:57,619 [salt.minion      :1432][INFO    ][13587] Starting a new job with PID 13587
2019-05-20 02:36:01,371 [salt.state       :915 ][INFO    ][13587] Loading fresh modules for state activity
2019-05-20 02:36:01,461 [salt.state       :1780][INFO    ][13587] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:36:01.461148
2019-05-20 02:36:01,461 [salt.state       :1813][INFO    ][13587] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 02:36:01,463 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13587] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 02:36:02,858 [salt.state       :300 ][INFO    ][13587] {'pid': 13594, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 02:36:02,858 [salt.state       :1951][INFO    ][13587] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:36:02.858461 duration_in_ms=1397.314
2019-05-20 02:36:02,859 [salt.state       :1780][INFO    ][13587] Running state [maas.wait_for_machine_status] at time 02:36:02.859878
2019-05-20 02:36:02,860 [salt.state       :1813][INFO    ][13587] Executing state module.run for [maas.wait_for_machine_status]
2019-05-20 02:36:02,860 [salt.utils.decorators:613 ][WARNING ][13587] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 02:36:04,796 [salt.loaded.ext.module.maas:993 ][INFO    ][13587] Machine g7k7bm mark broken
2019-05-20 02:36:05,425 [salt.loaded.ext.module.maas:996 ][INFO    ][13587] Machine g7k7bm mark fixed
2019-05-20 02:36:06,837 [salt.loaded.ext.module.maas:684 ][INFO    ][13587] deploymachines hwe_kernel=ga-16.04 system_id=g7k7bm distro_series=xenial
2019-05-20 02:36:10,872 [salt.loaded.ext.module.maas:1023][INFO    ][13587] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1491.99194694s left)
2019-05-20 02:36:12,662 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520023612648509
2019-05-20 02:36:12,685 [salt.minion      :1432][INFO    ][13693] Starting a new job with PID 13693
2019-05-20 02:36:12,708 [salt.minion      :1711][INFO    ][13693] Returning information for job: 20190520023612648509
2019-05-20 02:36:42,715 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520023642701195
2019-05-20 02:36:42,738 [salt.minion      :1432][INFO    ][13715] Starting a new job with PID 13715
2019-05-20 02:36:42,759 [salt.minion      :1711][INFO    ][13715] Returning information for job: 20190520023642701195
2019-05-20 02:36:44,208 [salt.loaded.ext.module.maas:1023][INFO    ][13587] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1458.656353s left)
2019-05-20 02:37:12,797 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520023712745343
2019-05-20 02:37:12,818 [salt.minion      :1432][INFO    ][13773] Starting a new job with PID 13773
2019-05-20 02:37:12,846 [salt.minion      :1711][INFO    ][13773] Returning information for job: 20190520023712745343
2019-05-20 02:37:17,659 [salt.loaded.ext.module.maas:1023][INFO    ][13587] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1425.20565486s left)
2019-05-20 02:37:42,849 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520023742832034
2019-05-20 02:37:42,872 [salt.minion      :1432][INFO    ][13794] Starting a new job with PID 13794
2019-05-20 02:37:42,897 [salt.minion      :1711][INFO    ][13794] Returning information for job: 20190520023742832034
2019-05-20 02:37:51,050 [salt.loaded.ext.module.maas:1023][INFO    ][13587] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1391.81445599s left)
2019-05-20 02:38:12,908 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520023812893048
2019-05-20 02:38:12,931 [salt.minion      :1432][INFO    ][13943] Starting a new job with PID 13943
2019-05-20 02:38:12,957 [salt.minion      :1711][INFO    ][13943] Returning information for job: 20190520023812893048
2019-05-20 02:38:24,779 [salt.loaded.ext.module.maas:1023][INFO    ][13587] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1358.08526683s left)
2019-05-20 02:38:42,964 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520023842951987
2019-05-20 02:38:42,986 [salt.minion      :1432][INFO    ][13969] Starting a new job with PID 13969
2019-05-20 02:38:43,011 [salt.minion      :1711][INFO    ][13969] Returning information for job: 20190520023842951987
2019-05-20 02:38:58,094 [salt.loaded.ext.module.maas:1023][INFO    ][13587] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1324.76978683s left)
2019-05-20 02:39:13,016 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520023913003422
2019-05-20 02:39:13,039 [salt.minion      :1432][INFO    ][14104] Starting a new job with PID 14104
2019-05-20 02:39:13,066 [salt.minion      :1711][INFO    ][14104] Returning information for job: 20190520023913003422
2019-05-20 02:39:31,713 [salt.loaded.ext.module.maas:1023][INFO    ][13587] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1291.15130496s left)
2019-05-20 02:39:43,077 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520023943064641
2019-05-20 02:39:43,101 [salt.minion      :1432][INFO    ][14123] Starting a new job with PID 14123
2019-05-20 02:39:43,126 [salt.minion      :1711][INFO    ][14123] Returning information for job: 20190520023943064641
2019-05-20 02:40:05,084 [salt.loaded.ext.module.maas:1023][INFO    ][13587] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1257.77971196s left)
2019-05-20 02:40:13,146 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520024013133439
2019-05-20 02:40:13,169 [salt.minion      :1432][INFO    ][14187] Starting a new job with PID 14187
2019-05-20 02:40:13,195 [salt.minion      :1711][INFO    ][14187] Returning information for job: 20190520024013133439
2019-05-20 02:40:38,539 [salt.loaded.ext.module.maas:1023][INFO    ][13587] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1224.32477498s left)
2019-05-20 02:40:43,216 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520024043203049
2019-05-20 02:40:43,239 [salt.minion      :1432][INFO    ][14210] Starting a new job with PID 14210
2019-05-20 02:40:43,264 [salt.minion      :1711][INFO    ][14210] Returning information for job: 20190520024043203049
2019-05-20 02:41:12,096 [salt.loaded.ext.module.maas:1023][INFO    ][13587] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1190.76784492s left)
2019-05-20 02:41:13,290 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520024113277818
2019-05-20 02:41:13,308 [salt.minion      :1432][INFO    ][14304] Starting a new job with PID 14304
2019-05-20 02:41:13,320 [salt.minion      :1711][INFO    ][14304] Returning information for job: 20190520024113277818
2019-05-20 02:41:43,348 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520024143334227
2019-05-20 02:41:43,370 [salt.minion      :1432][INFO    ][14324] Starting a new job with PID 14324
2019-05-20 02:41:43,396 [salt.minion      :1711][INFO    ][14324] Returning information for job: 20190520024143334227
2019-05-20 02:41:46,013 [salt.loaded.ext.module.maas:1023][INFO    ][13587] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1156.85107183s left)
2019-05-20 02:42:13,436 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520024213422616
2019-05-20 02:42:13,458 [salt.minion      :1432][INFO    ][14413] Starting a new job with PID 14413
2019-05-20 02:42:13,484 [salt.minion      :1711][INFO    ][14413] Returning information for job: 20190520024213422616
2019-05-20 02:42:18,570 [salt.loaded.ext.module.maas:1023][INFO    ][13587] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1124.2945509s left)
2019-05-20 02:42:43,525 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520024243512865
2019-05-20 02:42:43,549 [salt.minion      :1432][INFO    ][14434] Starting a new job with PID 14434
2019-05-20 02:42:43,575 [salt.minion      :1711][INFO    ][14434] Returning information for job: 20190520024243512865
2019-05-20 02:42:52,307 [salt.loaded.ext.module.maas:1023][INFO    ][13587] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1090.55743885s left)
2019-05-20 02:43:13,627 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520024313614839
2019-05-20 02:43:13,649 [salt.minion      :1432][INFO    ][14593] Starting a new job with PID 14593
2019-05-20 02:43:13,677 [salt.minion      :1711][INFO    ][14593] Returning information for job: 20190520024313614839
2019-05-20 02:43:25,499 [salt.loaded.ext.module.maas:1023][INFO    ][13587] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1057.36504078s left)
2019-05-20 02:43:43,730 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520024343717244
2019-05-20 02:43:43,752 [salt.minion      :1432][INFO    ][14621] Starting a new job with PID 14621
2019-05-20 02:43:43,778 [salt.minion      :1711][INFO    ][14621] Returning information for job: 20190520024343717244
2019-05-20 02:43:59,038 [salt.loaded.ext.module.maas:1023][INFO    ][13587] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1023.82659388s left)
2019-05-20 02:44:13,841 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520024413828796
2019-05-20 02:44:13,865 [salt.minion      :1432][INFO    ][14706] Starting a new job with PID 14706
2019-05-20 02:44:13,892 [salt.minion      :1711][INFO    ][14706] Returning information for job: 20190520024413828796
2019-05-20 02:44:32,680 [salt.loaded.ext.module.maas:1023][INFO    ][13587] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (990.18441987s left)
2019-05-20 02:44:43,962 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520024443945059
2019-05-20 02:44:43,989 [salt.minion      :1432][INFO    ][14726] Starting a new job with PID 14726
2019-05-20 02:44:44,022 [salt.minion      :1711][INFO    ][14726] Returning information for job: 20190520024443945059
2019-05-20 02:45:06,350 [salt.loaded.ext.module.maas:1023][INFO    ][13587] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (956.514578819s left)
2019-05-20 02:45:14,098 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command saltutil.find_job with jid 20190520024514084666
2019-05-20 02:45:14,122 [salt.minion      :1432][INFO    ][14818] Starting a new job with PID 14818
2019-05-20 02:45:14,158 [salt.minion      :1711][INFO    ][14818] Returning information for job: 20190520024514084666
2019-05-20 02:45:40,033 [salt.state       :300 ][INFO    ][13587] {'ret': True}
2019-05-20 02:45:40,033 [salt.state       :1951][INFO    ][13587] Completed state [maas.wait_for_machine_status] at time 02:45:40.033658 duration_in_ms=577173.778
2019-05-20 02:45:40,037 [salt.minion      :1711][INFO    ][13587] Returning information for job: 20190520023557583358
2019-05-20 02:45:40,726 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command state.apply with jid 20190520024540716499
2019-05-20 02:45:40,748 [salt.minion      :1432][INFO    ][14859] Starting a new job with PID 14859
2019-05-20 02:45:44,552 [salt.state       :915 ][INFO    ][14859] Loading fresh modules for state activity
2019-05-20 02:45:44,684 [salt.state       :1780][INFO    ][14859] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:45:44.684059
2019-05-20 02:45:44,684 [salt.state       :1813][INFO    ][14859] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 02:45:44,686 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14859] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 02:45:46,259 [salt.state       :300 ][INFO    ][14859] {'pid': 14869, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 02:45:46,260 [salt.state       :1951][INFO    ][14859] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:45:46.260548 duration_in_ms=1576.488
2019-05-20 02:45:46,263 [salt.state       :1780][INFO    ][14859] Running state [maas_machines_storage_cmp002_lvm] at time 02:45:46.263888
2019-05-20 02:45:46,264 [salt.state       :1813][INFO    ][14859] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-20 02:45:46,933 [salt.state       :300 ][INFO    ][14859] Machine cmp002 is not in Ready state.
2019-05-20 02:45:46,934 [salt.state       :1951][INFO    ][14859] Completed state [maas_machines_storage_cmp002_lvm] at time 02:45:46.934255 duration_in_ms=670.368
2019-05-20 02:45:46,934 [salt.state       :1780][INFO    ][14859] Running state [maas_machines_storage_cmp001_lvm] at time 02:45:46.934811
2019-05-20 02:45:46,935 [salt.state       :1813][INFO    ][14859] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-20 02:45:47,745 [salt.state       :300 ][INFO    ][14859] Machine cmp001 is not in Ready state.
2019-05-20 02:45:47,745 [salt.state       :1951][INFO    ][14859] Completed state [maas_machines_storage_cmp001_lvm] at time 02:45:47.745339 duration_in_ms=810.528
2019-05-20 02:45:47,749 [salt.minion      :1711][INFO    ][14859] Returning information for job: 20190520024540716499
2019-05-20 02:45:48,383 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command state.apply with jid 20190520024548369144
2019-05-20 02:45:48,406 [salt.minion      :1432][INFO    ][14880] Starting a new job with PID 14880
2019-05-20 02:45:49,221 [salt.state       :915 ][INFO    ][14880] Loading fresh modules for state activity
2019-05-20 02:45:49,310 [salt.state       :1780][INFO    ][14880] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:45:49.310289
2019-05-20 02:45:49,310 [salt.state       :1813][INFO    ][14880] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 02:45:49,312 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14880] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 02:45:50,839 [salt.state       :300 ][INFO    ][14880] {'pid': 14887, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 02:45:50,840 [salt.state       :1951][INFO    ][14880] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:45:50.840642 duration_in_ms=1530.352
2019-05-20 02:45:50,843 [salt.state       :1780][INFO    ][14880] Running state [maas.deploy_machines] at time 02:45:50.843176
2019-05-20 02:45:50,843 [salt.state       :1813][INFO    ][14880] Executing state module.run for [maas.deploy_machines]
2019-05-20 02:45:50,845 [salt.utils.decorators:613 ][WARNING ][14880] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 02:45:51,647 [salt.state       :300 ][INFO    ][14880] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-20 02:45:51,648 [salt.state       :1951][INFO    ][14880] Completed state [maas.deploy_machines] at time 02:45:51.648417 duration_in_ms=805.239
2019-05-20 02:45:51,652 [salt.minion      :1711][INFO    ][14880] Returning information for job: 20190520024548369144
2019-05-20 02:45:52,290 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command state.apply with jid 20190520024552274618
2019-05-20 02:45:52,312 [salt.minion      :1432][INFO    ][14896] Starting a new job with PID 14896
2019-05-20 02:45:53,076 [salt.state       :915 ][INFO    ][14896] Loading fresh modules for state activity
2019-05-20 02:45:53,165 [salt.state       :1780][INFO    ][14896] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:45:53.165294
2019-05-20 02:45:53,165 [salt.state       :1813][INFO    ][14896] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-20 02:45:53,167 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14896] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-20 02:45:54,640 [salt.state       :300 ][INFO    ][14896] {'pid': 14903, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-20 02:45:54,641 [salt.state       :1951][INFO    ][14896] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:45:54.641255 duration_in_ms=1475.961
2019-05-20 02:45:54,642 [salt.state       :1780][INFO    ][14896] Running state [maas.wait_for_machine_status] at time 02:45:54.642826
2019-05-20 02:45:54,643 [salt.state       :1813][INFO    ][14896] Executing state module.run for [maas.wait_for_machine_status]
2019-05-20 02:45:54,643 [salt.utils.decorators:613 ][WARNING ][14896] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-20 02:45:57,786 [salt.state       :300 ][INFO    ][14896] {'ret': True}
2019-05-20 02:45:57,787 [salt.state       :1951][INFO    ][14896] Completed state [maas.wait_for_machine_status] at time 02:45:57.786939 duration_in_ms=3144.111
2019-05-20 02:45:57,790 [salt.minion      :1711][INFO    ][14896] Returning information for job: 20190520024552274618
2019-05-20 03:14:23,117 [salt.utils.schedule:1377][INFO    ][6826] Running scheduled job: __mine_interval
2019-05-20 04:14:23,117 [salt.utils.schedule:1377][INFO    ][6826] Running scheduled job: __mine_interval
2019-05-20 04:24:31,815 [salt.minion      :1308][INFO    ][6826] User sudo_ubuntu Executing command cp.push_dir with jid 20190520042431804295
2019-05-20 04:24:31,837 [salt.minion      :1432][INFO    ][22106] Starting a new job with PID 22106
