2019-11-05 02:12:32,249 [salt.utils.decorators:613 ][WARNING ][2194] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-05 02:12:32,789 [salt.utils.decorators:613 ][WARNING ][2194] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-05 02:12:34,732 [salt.loaded.int.states.file:2298][WARNING ][2424] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-11-05 02:12:59,696 [salt.state       :2022][WARNING ][3018] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-11-05 02:13:02,147 [salt.utils.decorators:613 ][WARNING ][3018] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-05 02:13:50,761 [salt.utils.decorators:613 ][WARNING ][3018] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-05 02:15:11,689 [salt.utils.decorators:613 ][WARNING ][3018] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-05 02:15:28,163 [salt.utils.decorators:613 ][WARNING ][3018] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-05 02:15:31,756 [salt.loaded.ext.module.maasng:1008][WARNING ][3018] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-11-05 02:15:31,756 [salt.loaded.ext.module.maasng:1011][WARNING ][3018] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-11-05 02:15:32,492 [salt.loaded.ext.module.maasng:1235][WARNING ][3018] Ignoring parameter vlan:0
2019-11-05 02:15:35,289 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8355] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-11-05 02:15:35,318 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8355] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-11-05 02:15:35,339 [salt.utils.parsers:1051][WARNING ][322] Minion received a SIGTERM. Exiting.
2019-11-05 02:15:36,323 [salt.cli.daemons :293 ][INFO    ][8423] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-11-05 02:15:36,438 [salt.cli.daemons :82  ][INFO    ][8423] Starting up the Salt Minion
2019-11-05 02:15:36,439 [salt.utils.event :1017][INFO    ][8423] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-11-05 02:15:37,247 [salt.minion      :976 ][INFO    ][8423] Creating minion process manager
2019-11-05 02:15:38,675 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][8423] Executing command ['date', '+%z'] in directory '/root'
2019-11-05 02:15:38,693 [salt.utils.schedule:568 ][INFO    ][8423] Updating job settings for scheduled job: __mine_interval
2019-11-05 02:15:38,695 [salt.minion      :1108][INFO    ][8423] Added mine.update to scheduler
2019-11-05 02:15:38,698 [salt.minion      :1975][INFO    ][8423] Minion is starting as user 'root'
2019-11-05 02:15:38,710 [salt.minion      :2336][INFO    ][8423] Minion is ready to receive requests!
2019-11-05 02:15:39,088 [salt.utils.decorators:613 ][WARNING ][8360] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-05 02:15:39,151 [salt.loaded.ext.module.maas:412 ][WARNING ][8360] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-05 02:15:42,973 [salt.loaded.ext.module.maas:412 ][WARNING ][8360] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-05 02:15:44,255 [salt.loaded.ext.module.maas:412 ][WARNING ][8360] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-05 02:15:45,668 [salt.loaded.ext.module.maas:412 ][WARNING ][8360] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-05 02:15:47,010 [salt.loaded.ext.module.maas:412 ][WARNING ][8360] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-05 02:16:19,520 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command state.apply with jid 20191105021619508118
2019-11-05 02:16:19,541 [salt.minion      :1432][INFO    ][8820] Starting a new job with PID 8820
2019-11-05 02:16:23,166 [salt.state       :915 ][INFO    ][8820] Loading fresh modules for state activity
2019-11-05 02:16:23,205 [salt.fileclient  :1219][INFO    ][8820] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-11-05 02:16:23,238 [salt.state       :1780][INFO    ][8820] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:16:23.237985
2019-11-05 02:16:23,238 [salt.state       :1813][INFO    ][8820] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-05 02:16:23,239 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8820] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-05 02:16:24,621 [salt.state       :300 ][INFO    ][8820] {'pid': 8848, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-05 02:16:24,622 [salt.state       :1951][INFO    ][8820] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:16:24.622168 duration_in_ms=1384.183
2019-11-05 02:16:24,624 [salt.state       :1780][INFO    ][8820] Running state [maas.wait_for_machine_status] at time 02:16:24.624719
2019-11-05 02:16:24,625 [salt.state       :1813][INFO    ][8820] Executing state module.run for [maas.wait_for_machine_status]
2019-11-05 02:16:24,625 [salt.utils.decorators:613 ][WARNING ][8820] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-05 02:16:25,571 [salt.loaded.ext.module.maas:1023][INFO    ][8820] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.06119609s left)
2019-11-05 02:16:34,563 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105021634550587
2019-11-05 02:16:34,586 [salt.minion      :1432][INFO    ][8866] Starting a new job with PID 8866
2019-11-05 02:16:34,610 [salt.minion      :1711][INFO    ][8866] Returning information for job: 20191105021634550587
2019-11-05 02:16:56,527 [salt.loaded.ext.module.maas:1023][INFO    ][8820] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.10456204s left)
2019-11-05 02:17:04,610 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105021704598650
2019-11-05 02:17:04,632 [salt.minion      :1432][INFO    ][8905] Starting a new job with PID 8905
2019-11-05 02:17:04,657 [salt.minion      :1711][INFO    ][8905] Returning information for job: 20191105021704598650
2019-11-05 02:17:27,854 [salt.loaded.ext.module.maas:1023][INFO    ][8820] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.77829909s left)
2019-11-05 02:17:34,692 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105021734682560
2019-11-05 02:17:34,711 [salt.minion      :1432][INFO    ][9059] Starting a new job with PID 9059
2019-11-05 02:17:34,722 [salt.minion      :1711][INFO    ][9059] Returning information for job: 20191105021734682560
2019-11-05 02:17:59,324 [salt.loaded.ext.module.maas:1023][INFO    ][8820] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.3080771s left)
2019-11-05 02:18:04,729 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105021804715189
2019-11-05 02:18:04,748 [salt.minion      :1432][INFO    ][9408] Starting a new job with PID 9408
2019-11-05 02:18:04,770 [salt.minion      :1711][INFO    ][9408] Returning information for job: 20191105021804715189
2019-11-05 02:18:31,127 [salt.loaded.ext.module.maas:1023][INFO    ][8820] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.50531912s left)
2019-11-05 02:18:34,791 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105021834776516
2019-11-05 02:18:34,813 [salt.minion      :1432][INFO    ][9925] Starting a new job with PID 9925
2019-11-05 02:18:34,834 [salt.minion      :1711][INFO    ][9925] Returning information for job: 20191105021834776516
2019-11-05 02:19:02,991 [salt.loaded.ext.module.maas:1023][INFO    ][8820] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.64112902s left)
2019-11-05 02:19:04,843 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105021904828516
2019-11-05 02:19:04,865 [salt.minion      :1432][INFO    ][9985] Starting a new job with PID 9985
2019-11-05 02:19:04,889 [salt.minion      :1711][INFO    ][9985] Returning information for job: 20191105021904828516
2019-11-05 02:19:34,905 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105021934891524
2019-11-05 02:19:34,926 [salt.minion      :1432][INFO    ][10178] Starting a new job with PID 10178
2019-11-05 02:19:34,950 [salt.minion      :1711][INFO    ][10178] Returning information for job: 20191105021934891524
2019-11-05 02:19:36,158 [salt.loaded.ext.module.maas:1023][INFO    ][8820] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1308.4744072s left)
2019-11-05 02:20:04,967 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105022004954648
2019-11-05 02:20:04,990 [salt.minion      :1432][INFO    ][10307] Starting a new job with PID 10307
2019-11-05 02:20:05,013 [salt.minion      :1711][INFO    ][10307] Returning information for job: 20191105022004954648
2019-11-05 02:20:09,581 [salt.state       :300 ][INFO    ][8820] {'ret': True}
2019-11-05 02:20:09,582 [salt.state       :1951][INFO    ][8820] Completed state [maas.wait_for_machine_status] at time 02:20:09.581935 duration_in_ms=224957.214
2019-11-05 02:20:09,586 [salt.minion      :1711][INFO    ][8820] Returning information for job: 20191105021619508118
2019-11-05 02:20:10,210 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command state.apply with jid 20191105022010201002
2019-11-05 02:20:10,229 [salt.minion      :1432][INFO    ][10337] Starting a new job with PID 10337
2019-11-05 02:20:14,035 [salt.state       :915 ][INFO    ][10337] Loading fresh modules for state activity
2019-11-05 02:20:14,088 [salt.fileclient  :1219][INFO    ][10337] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-11-05 02:20:14,178 [salt.state       :1780][INFO    ][10337] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:20:14.178261
2019-11-05 02:20:14,178 [salt.state       :1813][INFO    ][10337] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-05 02:20:14,180 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10337] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-05 02:20:15,668 [salt.state       :300 ][INFO    ][10337] {'pid': 10344, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-05 02:20:15,669 [salt.state       :1951][INFO    ][10337] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:20:15.669769 duration_in_ms=1491.507
2019-11-05 02:20:15,673 [salt.state       :1780][INFO    ][10337] Running state [maas_machines_storage_cmp002_lvm] at time 02:20:15.672939
2019-11-05 02:20:15,673 [salt.state       :1813][INFO    ][10337] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-11-05 02:20:16,896 [salt.loaded.ext.module.maasng:610 ][INFO    ][10337] hnbc46
2019-11-05 02:20:16,897 [salt.loaded.ext.module.maasng:626 ][INFO    ][10337] sda
2019-11-05 02:20:17,503 [salt.loaded.ext.module.maasng:361 ][INFO    ][10337] hnbc46
2019-11-05 02:20:17,630 [salt.loaded.ext.module.maasng:367 ][INFO    ][10337] [{u'size': 2397998940160, u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'cfc02c63-6017-4816-bffd-34c82f72c3b1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hnbc46', u'filesystem': {u'uuid': u'ca4c5413-696a-4b53-8abf-8a3639bfa969', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/hnbc46/blockdevices/5/partition/5', u'type': u'partition', u'id': 5, u'device_id': 5}], u'filesystem': None, u'name': u'sda', u'system_id': u'hnbc46', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'used_for': u'GPT partitioned with 1 partition', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/hnbc46/blockdevices/5/'}, {u'size': 2397988454400, u'available_size': 0, u'uuid': u'976a24dc-3296-4770-a797-29795981cfa2', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'filesystem': {u'uuid': u'85455d05-7d96-4f7c-846b-0bf42380752f', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'name': u'vgroot-lvroot', u'system_id': u'hnbc46', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 10, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/hnbc46/blockdevices/10/'}]
2019-11-05 02:20:17,631 [salt.loaded.ext.module.maasng:632 ][INFO    ][10337] vgroot
2019-11-05 02:20:17,631 [salt.loaded.ext.module.maasng:635 ][INFO    ][10337] lvroot
2019-11-05 02:20:17,631 [salt.loaded.ext.module.maasng:639 ][INFO    ][10337] 107374182400
2019-11-05 02:20:18,304 [salt.loaded.ext.module.maasng:645 ][INFO    ][10337] {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'status_action': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.41'], u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'e7d9af67-6aae-46dc-843a-c46872eac067', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hnbc46', u'filesystem': {u'uuid': u'95a7c7bf-63b6-49ff-8090-ca9f1a51599f', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/hnbc46/blockdevices/5/partition/6', u'type': u'partition', u'id': 6, u'device_id': 5}], u'filesystem': None, u'name': u'sda', u'system_id': u'hnbc46', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'used_for': u'GPT partitioned with 1 partition', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/hnbc46/blockdevices/5/'}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'hnbc46', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'physicalblockdevice_set': [{u'size': 2397998940160, u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'e7d9af67-6aae-46dc-843a-c46872eac067', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hnbc46', u'filesystem': {u'uuid': u'95a7c7bf-63b6-49ff-8090-ca9f1a51599f', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/hnbc46/blockdevices/5/partition/6', u'type': u'partition', u'id': 6, u'device_id': 5}], u'filesystem': None, u'name': u'sda', u'system_id': u'hnbc46', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'used_for': u'GPT partitioned with 1 partition', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/hnbc46/blockdevices/5/'}], u'blockdevice_set': [{u'size': 2397998940160, u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'e7d9af67-6aae-46dc-843a-c46872eac067', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hnbc46', u'filesystem': {u'uuid': u'95a7c7bf-63b6-49ff-8090-ca9f1a51599f', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/hnbc46/blockdevices/5/partition/6', u'type': u'partition', u'id': 6, u'device_id': 5}], u'filesystem': None, u'name': u'sda', u'system_id': u'hnbc46', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'used_for': u'GPT partitioned with 1 partition', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/hnbc46/blockdevices/5/'}, {u'size': 107374182400, u'available_size': 0, u'uuid': u'610bc6d3-b330-479e-86cb-2c7368a44be4', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'307509c5-800a-4133-93c1-8483e7305b84', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'name': u'vgroot-lvroot', u'system_id': u'hnbc46', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/hnbc46/blockdevices/11/'}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'hnbc46', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nxs43g', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nxs43g', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41', u'id': 32, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nxs43g', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41'}], u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'hnbc46', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/hnbc46/interfaces/4/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp7s0', u'links': [{u'id': 34, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6b', u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'hnbc46', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/hnbc46/interfaces/20/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp9s0', u'links': [{u'id': 36, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6d', u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'hnbc46', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/hnbc46/interfaces/22/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp8s0', u'links': [{u'id': 38, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6c', u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'hnbc46', u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/hnbc46/interfaces/23/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'virtualblockdevice_set': [{u'size': 107374182400, u'available_size': 0, u'uuid': u'610bc6d3-b330-479e-86cb-2c7368a44be4', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'307509c5-800a-4133-93c1-8483e7305b84', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'name': u'vgroot-lvroot', u'system_id': u'hnbc46', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/hnbc46/blockdevices/11/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nxs43g', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nxs43g', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41', u'id': 32, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nxs43g', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41'}], u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'hnbc46', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/hnbc46/interfaces/4/'}, u'current_commissioning_result_id': 2, u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/hnbc46/', u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-11-05 02:20:18,306 [salt.state       :300 ][INFO    ][10337] {'new': {'storage_layout': 'lvm'}}
2019-11-05 02:20:18,306 [salt.state       :1951][INFO    ][10337] Completed state [maas_machines_storage_cmp002_lvm] at time 02:20:18.306850 duration_in_ms=2633.91
2019-11-05 02:20:18,307 [salt.state       :1780][INFO    ][10337] Running state [maas_machines_storage_cmp001_lvm] at time 02:20:18.307411
2019-11-05 02:20:18,307 [salt.state       :1813][INFO    ][10337] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-11-05 02:20:19,671 [salt.loaded.ext.module.maasng:610 ][INFO    ][10337] 74gw3r
2019-11-05 02:20:19,672 [salt.loaded.ext.module.maasng:626 ][INFO    ][10337] sda
2019-11-05 02:20:20,468 [salt.loaded.ext.module.maasng:361 ][INFO    ][10337] 74gw3r
2019-11-05 02:20:20,594 [salt.loaded.ext.module.maasng:367 ][INFO    ][10337] [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/74gw3r/blockdevices/1/', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'b294ef9d-233e-4ac0-aa7c-18e8dbd8905d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'74gw3r', u'device_id': 1, u'filesystem': {u'mount_options': None, u'uuid': u'a6c92040-7bb2-4e22-8442-5edb28c5fb9b', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/74gw3r/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'74gw3r', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 1, u'size': 2397998940160}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/74gw3r/blockdevices/6/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'uuid': u'42b78df6-0557-4587-bb64-682477e40745', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'74gw3r', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'bc42af8b-b389-44a1-9985-94dbedbf7be8', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 6, u'size': 2397988454400}]
2019-11-05 02:20:20,595 [salt.loaded.ext.module.maasng:632 ][INFO    ][10337] vgroot
2019-11-05 02:20:20,595 [salt.loaded.ext.module.maasng:635 ][INFO    ][10337] lvroot
2019-11-05 02:20:20,595 [salt.loaded.ext.module.maasng:639 ][INFO    ][10337] 107374182400
2019-11-05 02:20:21,322 [salt.loaded.ext.module.maasng:645 ][INFO    ][10337] {u'hwe_kernel': u'', u'swap_size': None, u'ip_addresses': [u'192.168.11.38'], u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'id': 1, u'system_id': u'74gw3r', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/74gw3r/blockdevices/1/', u'type': u'physical', u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'83b4bc55-9a55-436c-8a86-b78739478802', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'74gw3r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'c64c61cc-b11a-4d30-a086-cd4c154743ed', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/74gw3r/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'device_id': 1}]}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'9fceeffc-b881-4aaf-8f46-21b9d275c18b', u'fstype': u'ext4'}, u'uuid': u'f42bcbca-456e-42ae-855c-01e9ed98e966', u'id': 12, u'system_id': u'74gw3r', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/74gw3r/blockdevices/12/', u'type': u'virtual', u'serial': None, u'partitions': []}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, 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'74gw3r', 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'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/74gw3r/blockdevices/1/', u'used_size': 2397998940160, u'tags': [u'rotary'], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'74gw3r', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'83b4bc55-9a55-436c-8a86-b78739478802', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'74gw3r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'c64c61cc-b11a-4d30-a086-cd4c154743ed', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/74gw3r/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'device_id': 1}], u'type': u'physical', u'id': 1, u'name': u'sda'}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'74gw3r', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/74gw3r/blockdevices/1/', u'used_size': 2397998940160, u'tags': [u'rotary'], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'74gw3r', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'83b4bc55-9a55-436c-8a86-b78739478802', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'74gw3r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'c64c61cc-b11a-4d30-a086-cd4c154743ed', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/74gw3r/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'device_id': 1}], u'type': u'physical', u'id': 1, u'name': u'sda'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'current_commissioning_result_id': 4, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'f42bcbca-456e-42ae-855c-01e9ed98e966', u'resource_uri': u'/MAAS/api/2.0/nodes/74gw3r/blockdevices/12/', u'used_size': 107374182400, u'tags': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'9fceeffc-b881-4aaf-8f46-21b9d275c18b', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'74gw3r', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 12, u'name': u'vgroot-lvroot'}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nxs43g', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 15, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nxs43g', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nxs43g', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'74gw3r', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/74gw3r/interfaces/5/'}, 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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nxs43g', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 15, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nxs43g', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nxs43g', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'74gw3r', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/74gw3r/interfaces/5/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 16}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 9, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'74gw3r', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/74gw3r/interfaces/9/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 17}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 10, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'74gw3r', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/74gw3r/interfaces/10/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 19}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 11, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'74gw3r', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/74gw3r/interfaces/11/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/74gw3r/', u'distro_series': u'', u'memory_test_status': -1}
2019-11-05 02:20:21,324 [salt.state       :300 ][INFO    ][10337] {'new': {'storage_layout': 'lvm'}}
2019-11-05 02:20:21,325 [salt.state       :1951][INFO    ][10337] Completed state [maas_machines_storage_cmp001_lvm] at time 02:20:21.325155 duration_in_ms=3017.744
2019-11-05 02:20:21,329 [salt.minion      :1711][INFO    ][10337] Returning information for job: 20191105022010201002
2019-11-05 02:20:21,909 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command state.apply with jid 20191105022021894119
2019-11-05 02:20:21,924 [salt.minion      :1432][INFO    ][10387] Starting a new job with PID 10387
2019-11-05 02:20:22,585 [salt.state       :915 ][INFO    ][10387] Loading fresh modules for state activity
2019-11-05 02:20:22,639 [salt.fileclient  :1219][INFO    ][10387] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-11-05 02:20:22,677 [salt.state       :1780][INFO    ][10387] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:20:22.677711
2019-11-05 02:20:22,678 [salt.state       :1813][INFO    ][10387] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-05 02:20:22,680 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10387] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-05 02:20:24,096 [salt.state       :300 ][INFO    ][10387] {'pid': 10402, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-05 02:20:24,097 [salt.state       :1951][INFO    ][10387] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:20:24.097295 duration_in_ms=1419.583
2019-11-05 02:20:24,099 [salt.state       :1780][INFO    ][10387] Running state [maas.deploy_machines] at time 02:20:24.099813
2019-11-05 02:20:24,100 [salt.state       :1813][INFO    ][10387] Executing state module.run for [maas.deploy_machines]
2019-11-05 02:20:24,101 [salt.utils.decorators:613 ][WARNING ][10387] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-05 02:20:24,546 [salt.loaded.ext.module.maas:684 ][INFO    ][10387] deploymachines hwe_kernel=ga-18.04 system_id=hnbc46 distro_series=bionic
2019-11-05 02:20:27,016 [salt.loaded.ext.module.maas:684 ][INFO    ][10387] deploymachines hwe_kernel=ga-18.04 system_id=74gw3r distro_series=bionic
2019-11-05 02:20:29,273 [salt.loaded.ext.module.maas:684 ][INFO    ][10387] deploymachines hwe_kernel=ga-18.04 system_id=ghf8nt distro_series=bionic
2019-11-05 02:20:31,616 [salt.loaded.ext.module.maas:684 ][INFO    ][10387] deploymachines hwe_kernel=ga-18.04 system_id=b3xw4q distro_series=bionic
2019-11-05 02:20:34,094 [salt.loaded.ext.module.maas:684 ][INFO    ][10387] deploymachines hwe_kernel=ga-18.04 system_id=rperyk distro_series=bionic
2019-11-05 02:20:36,240 [salt.state       :300 ][INFO    ][10387] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-11-05 02:20:36,240 [salt.state       :1951][INFO    ][10387] Completed state [maas.deploy_machines] at time 02:20:36.240758 duration_in_ms=12140.942
2019-11-05 02:20:36,244 [salt.minion      :1711][INFO    ][10387] Returning information for job: 20191105022021894119
2019-11-05 02:20:36,838 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command state.apply with jid 20191105022036823238
2019-11-05 02:20:36,859 [salt.minion      :1432][INFO    ][10687] Starting a new job with PID 10687
2019-11-05 02:20:40,699 [salt.state       :915 ][INFO    ][10687] Loading fresh modules for state activity
2019-11-05 02:20:40,747 [salt.fileclient  :1219][INFO    ][10687] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-11-05 02:20:40,787 [salt.state       :1780][INFO    ][10687] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:20:40.787821
2019-11-05 02:20:40,788 [salt.state       :1813][INFO    ][10687] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-05 02:20:40,790 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10687] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-05 02:20:42,282 [salt.state       :300 ][INFO    ][10687] {'pid': 10702, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-05 02:20:42,282 [salt.state       :1951][INFO    ][10687] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:20:42.282544 duration_in_ms=1494.725
2019-11-05 02:20:42,284 [salt.state       :1780][INFO    ][10687] Running state [maas.wait_for_machine_status] at time 02:20:42.284168
2019-11-05 02:20:42,284 [salt.state       :1813][INFO    ][10687] Executing state module.run for [maas.wait_for_machine_status]
2019-11-05 02:20:42,284 [salt.utils.decorators:613 ][WARNING ][10687] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-05 02:20:45,592 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.69718504s left)
2019-11-05 02:20:51,875 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105022051860883
2019-11-05 02:20:51,897 [salt.minion      :1432][INFO    ][10715] Starting a new job with PID 10715
2019-11-05 02:20:51,921 [salt.minion      :1711][INFO    ][10715] Returning information for job: 20191105022051860883
2019-11-05 02:21:19,109 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.17990899s left)
2019-11-05 02:21:21,919 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105022121906559
2019-11-05 02:21:21,939 [salt.minion      :1432][INFO    ][10764] Starting a new job with PID 10764
2019-11-05 02:21:21,956 [salt.minion      :1711][INFO    ][10764] Returning information for job: 20191105022121906559
2019-11-05 02:21:51,950 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105022151937368
2019-11-05 02:21:51,971 [salt.minion      :1432][INFO    ][10788] Starting a new job with PID 10788
2019-11-05 02:21:51,994 [salt.minion      :1711][INFO    ][10788] Returning information for job: 20191105022151937368
2019-11-05 02:21:52,580 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.70842195s left)
2019-11-05 02:22:21,992 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105022221983970
2019-11-05 02:22:22,010 [salt.minion      :1432][INFO    ][10947] Starting a new job with PID 10947
2019-11-05 02:22:22,021 [salt.minion      :1711][INFO    ][10947] Returning information for job: 20191105022221983970
2019-11-05 02:22:25,808 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.48029613s left)
2019-11-05 02:22:52,034 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105022252021162
2019-11-05 02:22:52,056 [salt.minion      :1432][INFO    ][11192] Starting a new job with PID 11192
2019-11-05 02:22:52,078 [salt.minion      :1711][INFO    ][11192] Returning information for job: 20191105022252021162
2019-11-05 02:22:59,094 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.19471312s left)
2019-11-05 02:23:22,091 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105022322077815
2019-11-05 02:23:22,111 [salt.minion      :1432][INFO    ][11588] Starting a new job with PID 11588
2019-11-05 02:23:22,129 [salt.minion      :1711][INFO    ][11588] Returning information for job: 20191105022322077815
2019-11-05 02:23:32,388 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.90045905s left)
2019-11-05 02:23:52,144 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105022352131778
2019-11-05 02:23:52,166 [salt.minion      :1432][INFO    ][11956] Starting a new job with PID 11956
2019-11-05 02:23:52,190 [salt.minion      :1711][INFO    ][11956] Returning information for job: 20191105022352131778
2019-11-05 02:24:05,872 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.41664314s left)
2019-11-05 02:24:22,206 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105022422193579
2019-11-05 02:24:22,228 [salt.minion      :1432][INFO    ][12106] Starting a new job with PID 12106
2019-11-05 02:24:22,250 [salt.minion      :1711][INFO    ][12106] Returning information for job: 20191105022422193579
2019-11-05 02:24:39,158 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.13117194s left)
2019-11-05 02:24:52,264 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105022452252247
2019-11-05 02:24:52,287 [salt.minion      :1432][INFO    ][12138] Starting a new job with PID 12138
2019-11-05 02:24:52,312 [salt.minion      :1711][INFO    ][12138] Returning information for job: 20191105022452252247
2019-11-05 02:25:12,990 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.29893303s left)
2019-11-05 02:25:22,340 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105022522327286
2019-11-05 02:25:22,359 [salt.minion      :1432][INFO    ][12301] Starting a new job with PID 12301
2019-11-05 02:25:22,383 [salt.minion      :1711][INFO    ][12301] Returning information for job: 20191105022522327286
2019-11-05 02:25:46,389 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1945.90003014s left)
2019-11-05 02:25:52,412 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105022552400533
2019-11-05 02:25:52,434 [salt.minion      :1432][INFO    ][12412] Starting a new job with PID 12412
2019-11-05 02:25:52,456 [salt.minion      :1711][INFO    ][12412] Returning information for job: 20191105022552400533
2019-11-05 02:26:20,300 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1911.98924899s left)
2019-11-05 02:26:22,490 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105022622478922
2019-11-05 02:26:22,512 [salt.minion      :1432][INFO    ][12711] Starting a new job with PID 12711
2019-11-05 02:26:22,533 [salt.minion      :1711][INFO    ][12711] Returning information for job: 20191105022622478922
2019-11-05 02:26:52,568 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105022652554686
2019-11-05 02:26:52,588 [salt.minion      :1432][INFO    ][12922] Starting a new job with PID 12922
2019-11-05 02:26:52,611 [salt.minion      :1711][INFO    ][12922] Returning information for job: 20191105022652554686
2019-11-05 02:26:53,380 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1878.90848207s left)
2019-11-05 02:27:22,651 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105022722638457
2019-11-05 02:27:22,673 [salt.minion      :1432][INFO    ][13010] Starting a new job with PID 13010
2019-11-05 02:27:22,697 [salt.minion      :1711][INFO    ][13010] Returning information for job: 20191105022722638457
2019-11-05 02:27:27,113 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1845.17566204s left)
2019-11-05 02:27:52,751 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105022752737292
2019-11-05 02:27:52,773 [salt.minion      :1432][INFO    ][13142] Starting a new job with PID 13142
2019-11-05 02:27:52,796 [salt.minion      :1711][INFO    ][13142] Returning information for job: 20191105022752737292
2019-11-05 02:27:59,868 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['cmp002', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.42114305s left)
2019-11-05 02:28:22,832 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105022822819915
2019-11-05 02:28:22,853 [salt.minion      :1432][INFO    ][13343] Starting a new job with PID 13343
2019-11-05 02:28:22,875 [salt.minion      :1711][INFO    ][13343] Returning information for job: 20191105022822819915
2019-11-05 02:28:33,252 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1779.03647494s left)
2019-11-05 02:28:52,936 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105022852923467
2019-11-05 02:28:52,958 [salt.minion      :1432][INFO    ][13500] Starting a new job with PID 13500
2019-11-05 02:28:52,980 [salt.minion      :1711][INFO    ][13500] Returning information for job: 20191105022852923467
2019-11-05 02:29:05,817 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1746.471807s left)
2019-11-05 02:29:23,044 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105022923028396
2019-11-05 02:29:23,064 [salt.minion      :1432][INFO    ][13767] Starting a new job with PID 13767
2019-11-05 02:29:23,086 [salt.minion      :1711][INFO    ][13767] Returning information for job: 20191105022923028396
2019-11-05 02:29:39,357 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1712.93167806s left)
2019-11-05 02:29:53,162 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105022953149941
2019-11-05 02:29:53,183 [salt.minion      :1432][INFO    ][13881] Starting a new job with PID 13881
2019-11-05 02:29:53,203 [salt.minion      :1711][INFO    ][13881] Returning information for job: 20191105022953149941
2019-11-05 02:30:12,966 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1679.323277s left)
2019-11-05 02:30:23,286 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105023023270093
2019-11-05 02:30:23,307 [salt.minion      :1432][INFO    ][13922] Starting a new job with PID 13922
2019-11-05 02:30:23,328 [salt.minion      :1711][INFO    ][13922] Returning information for job: 20191105023023270093
2019-11-05 02:30:46,408 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1645.88099599s left)
2019-11-05 02:30:53,416 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105023053403317
2019-11-05 02:30:53,437 [salt.minion      :1432][INFO    ][13956] Starting a new job with PID 13956
2019-11-05 02:30:53,461 [salt.minion      :1711][INFO    ][13956] Returning information for job: 20191105023053403317
2019-11-05 02:31:19,921 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1612.36733699s left)
2019-11-05 02:31:23,555 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105023123542416
2019-11-05 02:31:23,577 [salt.minion      :1432][INFO    ][14002] Starting a new job with PID 14002
2019-11-05 02:31:23,600 [salt.minion      :1711][INFO    ][14002] Returning information for job: 20191105023123542416
2019-11-05 02:31:53,519 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1578.77014017s left)
2019-11-05 02:31:53,703 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105023153691246
2019-11-05 02:31:53,723 [salt.minion      :1432][INFO    ][14038] Starting a new job with PID 14038
2019-11-05 02:31:53,744 [salt.minion      :1711][INFO    ][14038] Returning information for job: 20191105023153691246
2019-11-05 02:32:23,855 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105023223842215
2019-11-05 02:32:23,878 [salt.minion      :1432][INFO    ][14082] Starting a new job with PID 14082
2019-11-05 02:32:23,901 [salt.minion      :1711][INFO    ][14082] Returning information for job: 20191105023223842215
2019-11-05 02:32:27,125 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1545.16409802s left)
2019-11-05 02:32:54,021 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105023254008792
2019-11-05 02:32:54,043 [salt.minion      :1432][INFO    ][14200] Starting a new job with PID 14200
2019-11-05 02:32:54,065 [salt.minion      :1711][INFO    ][14200] Returning information for job: 20191105023254008792
2019-11-05 02:33:00,365 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1511.92371511s left)
2019-11-05 02:33:24,200 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105023324187695
2019-11-05 02:33:24,222 [salt.minion      :1432][INFO    ][14268] Starting a new job with PID 14268
2019-11-05 02:33:24,246 [salt.minion      :1711][INFO    ][14268] Returning information for job: 20191105023324187695
2019-11-05 02:33:33,898 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1478.39069009s left)
2019-11-05 02:33:54,385 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105023354372329
2019-11-05 02:33:54,408 [salt.minion      :1432][INFO    ][14302] Starting a new job with PID 14302
2019-11-05 02:33:54,433 [salt.minion      :1711][INFO    ][14302] Returning information for job: 20191105023354372329
2019-11-05 02:34:07,445 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1444.8438611s left)
2019-11-05 02:34:24,587 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105023424571378
2019-11-05 02:34:24,606 [salt.minion      :1432][INFO    ][14348] Starting a new job with PID 14348
2019-11-05 02:34:24,627 [salt.minion      :1711][INFO    ][14348] Returning information for job: 20191105023424571378
2019-11-05 02:34:40,609 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1411.68006516s left)
2019-11-05 02:34:54,788 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105023454775241
2019-11-05 02:34:54,810 [salt.minion      :1432][INFO    ][14386] Starting a new job with PID 14386
2019-11-05 02:34:54,833 [salt.minion      :1711][INFO    ][14386] Returning information for job: 20191105023454775241
2019-11-05 02:35:14,105 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1378.18350697s left)
2019-11-05 02:35:25,007 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105023524994921
2019-11-05 02:35:25,029 [salt.minion      :1432][INFO    ][14428] Starting a new job with PID 14428
2019-11-05 02:35:25,051 [salt.minion      :1711][INFO    ][14428] Returning information for job: 20191105023524994921
2019-11-05 02:35:47,505 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1344.78405905s left)
2019-11-05 02:35:55,234 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105023555219781
2019-11-05 02:35:55,256 [salt.minion      :1432][INFO    ][14464] Starting a new job with PID 14464
2019-11-05 02:35:55,275 [salt.minion      :1711][INFO    ][14464] Returning information for job: 20191105023555219781
2019-11-05 02:36:20,620 [salt.loaded.ext.module.maas:1023][INFO    ][10687] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1311.668401s left)
2019-11-05 02:36:25,256 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105023625244301
2019-11-05 02:36:25,279 [salt.minion      :1432][INFO    ][14508] Starting a new job with PID 14508
2019-11-05 02:36:25,303 [salt.minion      :1711][INFO    ][14508] Returning information for job: 20191105023625244301
2019-11-05 02:36:53,220 [salt.loaded.ext.module.maas:993 ][INFO    ][10687] Machine b3xw4q mark broken
2019-11-05 02:36:53,713 [salt.loaded.ext.module.maas:996 ][INFO    ][10687] Machine b3xw4q mark fixed
2019-11-05 02:36:54,868 [salt.loaded.ext.module.maas:684 ][INFO    ][10687] deploymachines hwe_kernel=ga-18.04 system_id=b3xw4q distro_series=bionic
2019-11-05 02:36:55,299 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105023655287387
2019-11-05 02:36:55,321 [salt.minion      :1432][INFO    ][14562] Starting a new job with PID 14562
2019-11-05 02:36:55,345 [salt.minion      :1711][INFO    ][14562] Returning information for job: 20191105023655287387
2019-11-05 02:36:57,606 [salt.loaded.ext.module.maas:160 ][ERROR   ][10687] Failed for object kvm03 reason Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node.
2019-11-05 02:36:57,609 [salt.state       :302 ][ERROR   ][10687] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm02'], 'errors': {'kvm03': "Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node."}, 'success': []}
2019-11-05 02:36:57,609 [salt.state       :1951][INFO    ][10687] Completed state [maas.wait_for_machine_status] at time 02:36:57.609744 duration_in_ms=975325.567
2019-11-05 02:36:57,621 [salt.minion      :1711][INFO    ][10687] Returning information for job: 20191105022036823238
2019-11-05 02:37:08,375 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command pillar.get with jid 20191105023708363407
2019-11-05 02:37:08,393 [salt.minion      :1432][INFO    ][14635] Starting a new job with PID 14635
2019-11-05 02:37:08,401 [salt.minion      :1711][INFO    ][14635] Returning information for job: 20191105023708363407
2019-11-05 02:37:08,956 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command service.status with jid 20191105023708944369
2019-11-05 02:37:08,977 [salt.minion      :1432][INFO    ][14640] Starting a new job with PID 14640
2019-11-05 02:37:09,404 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14640] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-11-05 02:37:09,438 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14640] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-11-05 02:37:09,453 [salt.minion      :1711][INFO    ][14640] Returning information for job: 20191105023708944369
2019-11-05 02:37:10,053 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command state.apply with jid 20191105023710040155
2019-11-05 02:37:10,076 [salt.minion      :1432][INFO    ][14651] Starting a new job with PID 14651
2019-11-05 02:37:13,902 [salt.state       :915 ][INFO    ][14651] Loading fresh modules for state activity
2019-11-05 02:37:14,341 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14651] Executing command 'salt-minion --version' in directory '/root'
2019-11-05 02:37:14,711 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14651] Executing command 'salt-minion --version' in directory '/root'
2019-11-05 02:37:15,609 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14651] Executing command 'salt-minion --version' in directory '/root'
2019-11-05 02:37:15,950 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14651] Executing command 'salt-minion --version' in directory '/root'
2019-11-05 02:37:17,303 [salt.state       :1780][INFO    ][14651] Running state [salt-minion] at time 02:37:17.303292
2019-11-05 02:37:17,303 [salt.state       :1813][INFO    ][14651] Executing state pkg.installed for [salt-minion]
2019-11-05 02:37:17,304 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14651] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-05 02:37:17,393 [salt.state       :300 ][INFO    ][14651] All specified packages are already installed
2019-11-05 02:37:17,393 [salt.state       :1951][INFO    ][14651] Completed state [salt-minion] at time 02:37:17.393311 duration_in_ms=90.018
2019-11-05 02:37:17,393 [salt.state       :1780][INFO    ][14651] Running state [salt_minion_dependency_packages] at time 02:37:17.393646
2019-11-05 02:37:17,393 [salt.state       :1813][INFO    ][14651] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-11-05 02:37:17,400 [salt.state       :300 ][INFO    ][14651] All specified packages are already installed
2019-11-05 02:37:17,400 [salt.state       :1951][INFO    ][14651] Completed state [salt_minion_dependency_packages] at time 02:37:17.400394 duration_in_ms=6.748
2019-11-05 02:37:17,403 [salt.state       :1780][INFO    ][14651] Running state [/etc/salt/minion.d/minion.conf] at time 02:37:17.403399
2019-11-05 02:37:17,403 [salt.state       :1813][INFO    ][14651] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-11-05 02:37:17,604 [salt.state       :300 ][INFO    ][14651] File /etc/salt/minion.d/minion.conf is in the correct state
2019-11-05 02:37:17,604 [salt.state       :1951][INFO    ][14651] Completed state [/etc/salt/minion.d/minion.conf] at time 02:37:17.604598 duration_in_ms=201.199
2019-11-05 02:37:17,604 [salt.state       :1780][INFO    ][14651] Running state [python-netaddr] at time 02:37:17.604866
2019-11-05 02:37:17,605 [salt.state       :1813][INFO    ][14651] Executing state pkg.installed for [python-netaddr]
2019-11-05 02:37:17,612 [salt.state       :300 ][INFO    ][14651] All specified packages are already installed
2019-11-05 02:37:17,612 [salt.state       :1951][INFO    ][14651] Completed state [python-netaddr] at time 02:37:17.612351 duration_in_ms=7.485
2019-11-05 02:37:17,615 [salt.state       :1780][INFO    ][14651] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:37:17.615555
2019-11-05 02:37:17,615 [salt.state       :1813][INFO    ][14651] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-11-05 02:37:17,626 [salt.state       :300 ][INFO    ][14651] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-11-05 02:37:17,626 [salt.state       :1951][INFO    ][14651] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:37:17.626686 duration_in_ms=11.132
2019-11-05 02:37:17,627 [salt.state       :1780][INFO    ][14651] Running state [salt-minion] at time 02:37:17.627669
2019-11-05 02:37:17,627 [salt.state       :1813][INFO    ][14651] Executing state service.running for [salt-minion]
2019-11-05 02:37:17,628 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14651] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-11-05 02:37:17,667 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14651] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-11-05 02:37:17,684 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14651] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-11-05 02:37:17,702 [salt.state       :300 ][INFO    ][14651] The service salt-minion is already running
2019-11-05 02:37:17,702 [salt.state       :1951][INFO    ][14651] Completed state [salt-minion] at time 02:37:17.702577 duration_in_ms=74.907
2019-11-05 02:37:17,705 [salt.state       :1780][INFO    ][14651] Running state [/etc/salt/grains.d] at time 02:37:17.705024
2019-11-05 02:37:17,705 [salt.state       :1813][INFO    ][14651] Executing state file.directory for [/etc/salt/grains.d]
2019-11-05 02:37:17,707 [salt.state       :300 ][INFO    ][14651] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-11-05 02:37:17,707 [salt.state       :1951][INFO    ][14651] Completed state [/etc/salt/grains.d] at time 02:37:17.707381 duration_in_ms=2.357
2019-11-05 02:37:17,708 [salt.state       :1780][INFO    ][14651] Running state [/etc/salt/grains] at time 02:37:17.708462
2019-11-05 02:37:17,709 [salt.state       :1813][INFO    ][14651] Executing state file.managed for [/etc/salt/grains]
2019-11-05 02:37:17,709 [salt.state       :300 ][INFO    ][14651] File /etc/salt/grains exists with proper permissions. No changes made.
2019-11-05 02:37:17,710 [salt.state       :1951][INFO    ][14651] Completed state [/etc/salt/grains] at time 02:37:17.710125 duration_in_ms=1.663
2019-11-05 02:37:17,710 [salt.state       :1780][INFO    ][14651] Running state [/etc/salt/grains.d/placeholder] at time 02:37:17.710871
2019-11-05 02:37:17,711 [salt.state       :1813][INFO    ][14651] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-11-05 02:37:17,712 [salt.state       :300 ][INFO    ][14651] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-11-05 02:37:17,712 [salt.state       :1951][INFO    ][14651] Completed state [/etc/salt/grains.d/placeholder] at time 02:37:17.712440 duration_in_ms=1.569
2019-11-05 02:37:17,713 [salt.state       :1780][INFO    ][14651] Running state [/etc/salt/grains.d/sphinx] at time 02:37:17.713152
2019-11-05 02:37:17,713 [salt.state       :1813][INFO    ][14651] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-11-05 02:37:17,724 [salt.state       :300 ][INFO    ][14651] File /etc/salt/grains.d/sphinx is in the correct state
2019-11-05 02:37:17,724 [salt.state       :1951][INFO    ][14651] Completed state [/etc/salt/grains.d/sphinx] at time 02:37:17.724596 duration_in_ms=11.443
2019-11-05 02:37:17,727 [salt.state       :1780][INFO    ][14651] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:37:17.727883
2019-11-05 02:37:17,728 [salt.state       :1813][INFO    ][14651] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-11-05 02:37:17,728 [salt.state       :300 ][INFO    ][14651] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-11-05 02:37:17,729 [salt.state       :1951][INFO    ][14651] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:37:17.729151 duration_in_ms=1.269
2019-11-05 02:37:17,729 [salt.state       :1780][INFO    ][14651] Running state [/etc/salt/grains.d/dns_records] at time 02:37:17.729860
2019-11-05 02:37:17,730 [salt.state       :1813][INFO    ][14651] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-11-05 02:37:17,742 [salt.state       :300 ][INFO    ][14651] File /etc/salt/grains.d/dns_records is in the correct state
2019-11-05 02:37:17,742 [salt.state       :1951][INFO    ][14651] Completed state [/etc/salt/grains.d/dns_records] at time 02:37:17.742444 duration_in_ms=12.584
2019-11-05 02:37:17,743 [salt.state       :1780][INFO    ][14651] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:37:17.743835
2019-11-05 02:37:17,744 [salt.state       :1813][INFO    ][14651] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-11-05 02:37:17,744 [salt.state       :300 ][INFO    ][14651] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-11-05 02:37:17,745 [salt.state       :1951][INFO    ][14651] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:37:17.745089 duration_in_ms=1.254
2019-11-05 02:37:17,745 [salt.state       :1780][INFO    ][14651] Running state [/etc/salt/grains.d/salt] at time 02:37:17.745803
2019-11-05 02:37:17,746 [salt.state       :1813][INFO    ][14651] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-11-05 02:37:17,760 [salt.state       :300 ][INFO    ][14651] File /etc/salt/grains.d/salt is in the correct state
2019-11-05 02:37:17,760 [salt.state       :1951][INFO    ][14651] Completed state [/etc/salt/grains.d/salt] at time 02:37:17.760380 duration_in_ms=14.576
2019-11-05 02:37:17,761 [salt.state       :1780][INFO    ][14651] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:37:17.761701
2019-11-05 02:37:17,762 [salt.state       :1813][INFO    ][14651] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-11-05 02:37:17,762 [salt.state       :300 ][INFO    ][14651] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-11-05 02:37:17,763 [salt.state       :1951][INFO    ][14651] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:37:17.762948 duration_in_ms=1.248
2019-11-05 02:37:17,765 [salt.state       :1780][INFO    ][14651] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:37:17.765800
2019-11-05 02:37:17,766 [salt.state       :1813][INFO    ][14651] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-11-05 02:37:17,766 [salt.state       :300 ][INFO    ][14651] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-11-05 02:37:17,767 [salt.state       :1951][INFO    ][14651] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:37:17.767076 duration_in_ms=1.277
2019-11-05 02:37:17,768 [salt.state       :1780][INFO    ][14651] Running state [mine.update] at time 02:37:17.768079
2019-11-05 02:37:17,768 [salt.state       :1813][INFO    ][14651] Executing state module.wait for [mine.update]
2019-11-05 02:37:17,768 [salt.state       :300 ][INFO    ][14651] No changes made for mine.update
2019-11-05 02:37:17,769 [salt.state       :1951][INFO    ][14651] Completed state [mine.update] at time 02:37:17.769230 duration_in_ms=1.151
2019-11-05 02:37:17,769 [salt.state       :1780][INFO    ][14651] Running state [ca-certificates] at time 02:37:17.769607
2019-11-05 02:37:17,770 [salt.state       :1813][INFO    ][14651] Executing state pkg.installed for [ca-certificates]
2019-11-05 02:37:17,780 [salt.state       :300 ][INFO    ][14651] All specified packages are already installed
2019-11-05 02:37:17,780 [salt.state       :1951][INFO    ][14651] Completed state [ca-certificates] at time 02:37:17.780469 duration_in_ms=10.863
2019-11-05 02:37:17,781 [salt.state       :1780][INFO    ][14651] Running state [update-ca-certificates] at time 02:37:17.781329
2019-11-05 02:37:17,781 [salt.state       :1813][INFO    ][14651] Executing state cmd.wait for [update-ca-certificates]
2019-11-05 02:37:17,782 [salt.state       :300 ][INFO    ][14651] No changes made for update-ca-certificates
2019-11-05 02:37:17,782 [salt.state       :1951][INFO    ][14651] Completed state [update-ca-certificates] at time 02:37:17.782309 duration_in_ms=0.981
2019-11-05 02:37:17,782 [salt.state       :1780][INFO    ][14651] Running state [iptables] at time 02:37:17.782630
2019-11-05 02:37:17,782 [salt.state       :1813][INFO    ][14651] Executing state pkg.installed for [iptables]
2019-11-05 02:37:17,791 [salt.state       :300 ][INFO    ][14651] All specified packages are already installed
2019-11-05 02:37:17,791 [salt.state       :1951][INFO    ][14651] Completed state [iptables] at time 02:37:17.791641 duration_in_ms=9.011
2019-11-05 02:37:17,791 [salt.state       :1780][INFO    ][14651] Running state [iptables-persistent] at time 02:37:17.791946
2019-11-05 02:37:17,792 [salt.state       :1813][INFO    ][14651] Executing state pkg.installed for [iptables-persistent]
2019-11-05 02:37:17,800 [salt.state       :300 ][INFO    ][14651] All specified packages are already installed
2019-11-05 02:37:17,800 [salt.state       :1951][INFO    ][14651] Completed state [iptables-persistent] at time 02:37:17.800608 duration_in_ms=8.663
2019-11-05 02:37:17,801 [salt.state       :1780][INFO    ][14651] Running state [iptables_modules_v4_load] at time 02:37:17.801798
2019-11-05 02:37:17,802 [salt.state       :1813][INFO    ][14651] Executing state kmod.present for [iptables_modules_v4_load]
2019-11-05 02:37:17,802 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14651] Executing command 'lsmod' in directory '/root'
2019-11-05 02:37:17,827 [salt.state       :300 ][INFO    ][14651] Kernel modules iptable_filter, ip_tables are already present
2019-11-05 02:37:17,827 [salt.state       :1951][INFO    ][14651] Completed state [iptables_modules_v4_load] at time 02:37:17.827855 duration_in_ms=26.057
2019-11-05 02:37:17,828 [salt.state       :1780][INFO    ][14651] Running state [/etc/iptables/rules.v4] at time 02:37:17.828586
2019-11-05 02:37:17,829 [salt.state       :1813][INFO    ][14651] Executing state file.managed for [/etc/iptables/rules.v4]
2019-11-05 02:37:17,915 [salt.state       :300 ][INFO    ][14651] File /etc/iptables/rules.v4 is in the correct state
2019-11-05 02:37:17,916 [salt.state       :1951][INFO    ][14651] Completed state [/etc/iptables/rules.v4] at time 02:37:17.916096 duration_in_ms=87.51
2019-11-05 02:37:17,917 [salt.state       :1780][INFO    ][14651] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:37:17.917006
2019-11-05 02:37:17,917 [salt.state       :1813][INFO    ][14651] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-11-05 02:37:17,917 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14651] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-11-05 02:37:17,936 [salt.state       :300 ][INFO    ][14651] onlyif execution failed
2019-11-05 02:37:17,936 [salt.state       :1951][INFO    ][14651] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:37:17.936646 duration_in_ms=19.64
2019-11-05 02:37:17,937 [salt.state       :1780][INFO    ][14651] Running state [netfilter-persistent] at time 02:37:17.937489
2019-11-05 02:37:17,937 [salt.state       :1813][INFO    ][14651] Executing state service.running for [netfilter-persistent]
2019-11-05 02:37:17,938 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14651] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-11-05 02:37:17,956 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14651] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-11-05 02:37:17,973 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14651] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-11-05 02:37:17,989 [salt.state       :300 ][INFO    ][14651] The service netfilter-persistent is already running
2019-11-05 02:37:17,989 [salt.state       :1951][INFO    ][14651] Completed state [netfilter-persistent] at time 02:37:17.989627 duration_in_ms=52.148
2019-11-05 02:37:17,990 [salt.state       :1780][INFO    ][14651] Running state [iptables_extra.remove_stale_tables] at time 02:37:17.990380
2019-11-05 02:37:17,990 [salt.state       :1813][INFO    ][14651] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-11-05 02:37:17,991 [salt.state       :300 ][INFO    ][14651] No changes made for iptables_extra.remove_stale_tables
2019-11-05 02:37:17,991 [salt.state       :1951][INFO    ][14651] Completed state [iptables_extra.remove_stale_tables] at time 02:37:17.991271 duration_in_ms=0.891
2019-11-05 02:37:17,991 [salt.state       :1780][INFO    ][14651] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:37:17.991524
2019-11-05 02:37:17,991 [salt.state       :1813][INFO    ][14651] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-11-05 02:37:17,992 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14651] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-11-05 02:37:18,005 [salt.state       :300 ][INFO    ][14651] onlyif execution failed
2019-11-05 02:37:18,006 [salt.state       :1951][INFO    ][14651] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:37:18.006091 duration_in_ms=14.567
2019-11-05 02:37:18,007 [salt.state       :1780][INFO    ][14651] Running state [/etc/iptables/rules.v6] at time 02:37:18.006954
2019-11-05 02:37:18,007 [salt.state       :1813][INFO    ][14651] Executing state file.absent for [/etc/iptables/rules.v6]
2019-11-05 02:37:18,007 [salt.state       :300 ][INFO    ][14651] File /etc/iptables/rules.v6 is not present
2019-11-05 02:37:18,007 [salt.state       :1951][INFO    ][14651] Completed state [/etc/iptables/rules.v6] at time 02:37:18.007918 duration_in_ms=0.964
2019-11-05 02:37:18,008 [salt.state       :1780][INFO    ][14651] Running state [iptables_extra.flush_all] at time 02:37:18.008525
2019-11-05 02:37:18,008 [salt.state       :1813][INFO    ][14651] Executing state module.wait for [iptables_extra.flush_all]
2019-11-05 02:37:18,009 [salt.state       :300 ][INFO    ][14651] No changes made for iptables_extra.flush_all
2019-11-05 02:37:18,009 [salt.state       :1951][INFO    ][14651] Completed state [iptables_extra.flush_all] at time 02:37:18.009267 duration_in_ms=0.742
2019-11-05 02:37:18,012 [salt.minion      :1711][INFO    ][14651] Returning information for job: 20191105023710040155
2019-11-05 02:37:18,668 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command state.apply with jid 20191105023718655076
2019-11-05 02:37:18,689 [salt.minion      :1432][INFO    ][14726] Starting a new job with PID 14726
2019-11-05 02:37:19,451 [salt.state       :915 ][INFO    ][14726] Loading fresh modules for state activity
2019-11-05 02:37:20,119 [salt.state       :1780][INFO    ][14726] Running state [maas-rack-controller] at time 02:37:20.119620
2019-11-05 02:37:20,119 [salt.state       :1813][INFO    ][14726] Executing state pkg.installed for [maas-rack-controller]
2019-11-05 02:37:20,120 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14726] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-05 02:37:20,224 [salt.state       :300 ][INFO    ][14726] All specified packages are already installed
2019-11-05 02:37:20,224 [salt.state       :1951][INFO    ][14726] Completed state [maas-rack-controller] at time 02:37:20.224636 duration_in_ms=105.016
2019-11-05 02:37:20,225 [salt.state       :1780][INFO    ][14726] Running state [ipmitool] at time 02:37:20.225042
2019-11-05 02:37:20,225 [salt.state       :1813][INFO    ][14726] Executing state pkg.installed for [ipmitool]
2019-11-05 02:37:20,233 [salt.state       :300 ][INFO    ][14726] All specified packages are already installed
2019-11-05 02:37:20,233 [salt.state       :1951][INFO    ][14726] Completed state [ipmitool] at time 02:37:20.233822 duration_in_ms=8.78
2019-11-05 02:37:20,237 [salt.state       :1780][INFO    ][14726] Running state [/etc/maas/rackd.conf] at time 02:37:20.237387
2019-11-05 02:37:20,237 [salt.state       :1813][INFO    ][14726] Executing state file.line for [/etc/maas/rackd.conf]
2019-11-05 02:37:20,239 [salt.state       :300 ][INFO    ][14726] No changes needed to be made
2019-11-05 02:37:20,239 [salt.state       :1951][INFO    ][14726] Completed state [/etc/maas/rackd.conf] at time 02:37:20.239288 duration_in_ms=1.901
2019-11-05 02:37:20,239 [salt.state       :1780][INFO    ][14726] Running state [/etc/maas/rackd.conf] at time 02:37:20.239597
2019-11-05 02:37:20,239 [salt.state       :1813][INFO    ][14726] Executing state file.managed for [/etc/maas/rackd.conf]
2019-11-05 02:37:20,240 [salt.loaded.int.states.file:2298][WARNING ][14726] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-11-05 02:37:20,240 [salt.state       :300 ][INFO    ][14726] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-11-05 02:37:20,241 [salt.state       :1951][INFO    ][14726] Completed state [/etc/maas/rackd.conf] at time 02:37:20.241130 duration_in_ms=1.533
2019-11-05 02:37:20,242 [salt.state       :1780][INFO    ][14726] Running state [maas-rackd] at time 02:37:20.242289
2019-11-05 02:37:20,242 [salt.state       :1813][INFO    ][14726] Executing state service.running for [maas-rackd]
2019-11-05 02:37:20,243 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14726] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-11-05 02:37:20,277 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14726] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-11-05 02:37:20,295 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14726] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-11-05 02:37:20,312 [salt.state       :300 ][INFO    ][14726] The service maas-rackd is already running
2019-11-05 02:37:20,312 [salt.state       :1951][INFO    ][14726] Completed state [maas-rackd] at time 02:37:20.312579 duration_in_ms=70.29
2019-11-05 02:37:20,314 [salt.minion      :1711][INFO    ][14726] Returning information for job: 20191105023718655076
2019-11-05 02:37:20,911 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command state.apply with jid 20191105023720897758
2019-11-05 02:37:20,933 [salt.minion      :1432][INFO    ][14749] Starting a new job with PID 14749
2019-11-05 02:37:21,698 [salt.state       :915 ][INFO    ][14749] Loading fresh modules for state activity
2019-11-05 02:37:22,387 [salt.state       :1780][INFO    ][14749] Running state [maas-region-controller] at time 02:37:22.387904
2019-11-05 02:37:22,388 [salt.state       :1813][INFO    ][14749] Executing state pkg.installed for [maas-region-controller]
2019-11-05 02:37:22,388 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14749] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-05 02:37:22,484 [salt.state       :300 ][INFO    ][14749] All specified packages are already installed
2019-11-05 02:37:22,484 [salt.state       :1951][INFO    ][14749] Completed state [maas-region-controller] at time 02:37:22.484626 duration_in_ms=96.722
2019-11-05 02:37:22,485 [salt.state       :1780][INFO    ][14749] Running state [python-oauth] at time 02:37:22.485020
2019-11-05 02:37:22,485 [salt.state       :1813][INFO    ][14749] Executing state pkg.installed for [python-oauth]
2019-11-05 02:37:22,492 [salt.state       :300 ][INFO    ][14749] All specified packages are already installed
2019-11-05 02:37:22,493 [salt.state       :1951][INFO    ][14749] Completed state [python-oauth] at time 02:37:22.493142 duration_in_ms=8.122
2019-11-05 02:37:22,496 [salt.state       :1780][INFO    ][14749] Running state [/etc/maas/regiond.conf] at time 02:37:22.496838
2019-11-05 02:37:22,497 [salt.state       :1813][INFO    ][14749] Executing state file.replace for [/etc/maas/regiond.conf]
2019-11-05 02:37:22,551 [salt.state       :300 ][INFO    ][14749] No changes needed to be made
2019-11-05 02:37:22,552 [salt.state       :1951][INFO    ][14749] Completed state [/etc/maas/regiond.conf] at time 02:37:22.551987 duration_in_ms=55.148
2019-11-05 02:37:22,552 [salt.state       :1780][INFO    ][14749] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:37:22.552580
2019-11-05 02:37:22,552 [salt.state       :1813][INFO    ][14749] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-11-05 02:37:22,616 [salt.state       :300 ][INFO    ][14749] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-11-05 02:37:22,617 [salt.state       :1951][INFO    ][14749] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:37:22.617085 duration_in_ms=64.504
2019-11-05 02:37:22,617 [salt.state       :1780][INFO    ][14749] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:37:22.617746
2019-11-05 02:37:22,618 [salt.state       :1813][INFO    ][14749] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-11-05 02:37:22,630 [salt.state       :300 ][INFO    ][14749] No changes needed to be made
2019-11-05 02:37:22,630 [salt.state       :1951][INFO    ][14749] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:37:22.630770 duration_in_ms=13.024
2019-11-05 02:37:22,631 [salt.state       :1780][INFO    ][14749] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:37:22.631372
2019-11-05 02:37:22,631 [salt.state       :1813][INFO    ][14749] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-11-05 02:37:22,648 [salt.state       :300 ][INFO    ][14749] No changes needed to be made
2019-11-05 02:37:22,648 [salt.state       :1951][INFO    ][14749] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:37:22.648757 duration_in_ms=17.384
2019-11-05 02:37:22,649 [salt.state       :1780][INFO    ][14749] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:37:22.649352
2019-11-05 02:37:22,649 [salt.state       :1813][INFO    ][14749] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-11-05 02:37:22,678 [salt.state       :300 ][INFO    ][14749] No changes needed to be made
2019-11-05 02:37:22,678 [salt.state       :1951][INFO    ][14749] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:37:22.678797 duration_in_ms=29.446
2019-11-05 02:37:22,679 [salt.state       :1780][INFO    ][14749] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:37:22.679335
2019-11-05 02:37:22,682 [salt.state       :1813][INFO    ][14749] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-11-05 02:37:22,699 [salt.state       :300 ][INFO    ][14749] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-11-05 02:37:22,699 [salt.state       :1951][INFO    ][14749] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:37:22.699410 duration_in_ms=20.075
2019-11-05 02:37:22,702 [salt.state       :1780][INFO    ][14749] Running state [a2enmod headers] at time 02:37:22.701951
2019-11-05 02:37:22,702 [salt.state       :1813][INFO    ][14749] Executing state cmd.run for [a2enmod headers]
2019-11-05 02:37:22,703 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14749] Executing command 'a2enmod headers' in directory '/root'
2019-11-05 02:37:22,777 [salt.state       :300 ][INFO    ][14749] {'pid': 14784, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-11-05 02:37:22,778 [salt.state       :1951][INFO    ][14749] Completed state [a2enmod headers] at time 02:37:22.778057 duration_in_ms=76.105
2019-11-05 02:37:22,778 [salt.state       :1780][INFO    ][14749] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:37:22.778684
2019-11-05 02:37:22,779 [salt.state       :1813][INFO    ][14749] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-11-05 02:37:22,799 [salt.state       :300 ][INFO    ][14749] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-11-05 02:37:22,799 [salt.state       :1951][INFO    ][14749] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:37:22.799399 duration_in_ms=20.715
2019-11-05 02:37:22,800 [salt.state       :1780][INFO    ][14749] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:37:22.800331
2019-11-05 02:37:22,800 [salt.state       :1813][INFO    ][14749] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-11-05 02:37:22,874 [salt.state       :300 ][INFO    ][14749] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-11-05 02:37:22,874 [salt.state       :1951][INFO    ][14749] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:37:22.874842 duration_in_ms=74.511
2019-11-05 02:37:22,875 [salt.state       :1780][INFO    ][14749] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:37:22.875443
2019-11-05 02:37:22,875 [salt.state       :1813][INFO    ][14749] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-11-05 02:37:22,934 [salt.state       :300 ][INFO    ][14749] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-11-05 02:37:22,934 [salt.state       :1951][INFO    ][14749] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:37:22.934338 duration_in_ms=58.895
2019-11-05 02:37:22,934 [salt.state       :1780][INFO    ][14749] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:37:22.934821
2019-11-05 02:37:22,935 [salt.state       :1813][INFO    ][14749] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-11-05 02:37:22,988 [salt.state       :300 ][INFO    ][14749] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-11-05 02:37:22,988 [salt.state       :1951][INFO    ][14749] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:37:22.988210 duration_in_ms=53.389
2019-11-05 02:37:22,988 [salt.state       :1780][INFO    ][14749] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 02:37:22.988681
2019-11-05 02:37:22,988 [salt.state       :1813][INFO    ][14749] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic]
2019-11-05 02:37:23,036 [salt.state       :300 ][INFO    ][14749] File /etc/maas/preseeds/curtin_userdata_amd64_generic_bionic is in the correct state
2019-11-05 02:37:23,036 [salt.state       :1951][INFO    ][14749] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 02:37:23.036305 duration_in_ms=47.624
2019-11-05 02:37:23,036 [salt.state       :1780][INFO    ][14749] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 02:37:23.036733
2019-11-05 02:37:23,037 [salt.state       :1813][INFO    ][14749] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic]
2019-11-05 02:37:23,089 [salt.state       :300 ][INFO    ][14749] File /etc/maas/preseeds/curtin_userdata_arm64_generic_bionic is in the correct state
2019-11-05 02:37:23,090 [salt.state       :1951][INFO    ][14749] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 02:37:23.090073 duration_in_ms=53.34
2019-11-05 02:37:23,090 [salt.state       :1780][INFO    ][14749] Running state [/root/.pgpass] at time 02:37:23.090315
2019-11-05 02:37:23,090 [salt.state       :1813][INFO    ][14749] Executing state file.managed for [/root/.pgpass]
2019-11-05 02:37:23,137 [salt.state       :300 ][INFO    ][14749] File /root/.pgpass is in the correct state
2019-11-05 02:37:23,137 [salt.state       :1951][INFO    ][14749] Completed state [/root/.pgpass] at time 02:37:23.137794 duration_in_ms=47.479
2019-11-05 02:37:23,141 [salt.state       :1780][INFO    ][14749] Running state [maas-region syncdb --noinput] at time 02:37:23.141681
2019-11-05 02:37:23,141 [salt.state       :1813][INFO    ][14749] Executing state cmd.run for [maas-region syncdb --noinput]
2019-11-05 02:37:23,142 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14749] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-11-05 02:37:24,941 [salt.state       :300 ][INFO    ][14749] {'pid': 14805, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: contenttypes, sites, metadataserver, maasserver, sessions, piston3, auth\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-11-05 02:37:24,942 [salt.state       :1951][INFO    ][14749] Completed state [maas-region syncdb --noinput] at time 02:37:24.942284 duration_in_ms=1800.602
2019-11-05 02:37:24,942 [salt.state       :2022][WARNING ][14749] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-11-05 02:37:24,945 [salt.state       :1780][INFO    ][14749] Running state [maas-regiond] at time 02:37:24.945283
2019-11-05 02:37:24,945 [salt.state       :1813][INFO    ][14749] Executing state service.running for [maas-regiond]
2019-11-05 02:37:24,947 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14749] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-11-05 02:37:24,985 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14749] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-11-05 02:37:25,003 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14749] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-11-05 02:37:25,021 [salt.state       :300 ][INFO    ][14749] The service maas-regiond is already running
2019-11-05 02:37:25,022 [salt.state       :1951][INFO    ][14749] Completed state [maas-regiond] at time 02:37:25.022154 duration_in_ms=76.871
2019-11-05 02:37:25,024 [salt.state       :1780][INFO    ][14749] Running state [bind9] at time 02:37:25.024621
2019-11-05 02:37:25,025 [salt.state       :1813][INFO    ][14749] Executing state service.running for [bind9]
2019-11-05 02:37:25,026 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14749] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-11-05 02:37:25,045 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14749] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-11-05 02:37:25,063 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14749] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-11-05 02:37:25,082 [salt.state       :300 ][INFO    ][14749] The service bind9 is already running
2019-11-05 02:37:25,082 [salt.state       :1951][INFO    ][14749] Completed state [bind9] at time 02:37:25.082785 duration_in_ms=58.164
2019-11-05 02:37:25,085 [salt.state       :1780][INFO    ][14749] Running state [apache2] at time 02:37:25.085449
2019-11-05 02:37:25,085 [salt.state       :1813][INFO    ][14749] Executing state service.running for [apache2]
2019-11-05 02:37:25,087 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14749] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-11-05 02:37:25,106 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14749] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-11-05 02:37:25,124 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14749] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-11-05 02:37:25,147 [salt.state       :300 ][INFO    ][14749] The service apache2 is already running
2019-11-05 02:37:25,147 [salt.state       :1951][INFO    ][14749] Completed state [apache2] at time 02:37:25.147688 duration_in_ms=62.239
2019-11-05 02:37:25,149 [salt.state       :1780][INFO    ][14749] Running state [maasng.wait_for_http_code] at time 02:37:25.149798
2019-11-05 02:37:25,150 [salt.state       :1813][INFO    ][14749] Executing state module.run for [maasng.wait_for_http_code]
2019-11-05 02:37:25,151 [salt.utils.decorators:613 ][WARNING ][14749] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-05 02:37:25,279 [salt.state       :300 ][INFO    ][14749] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-11-05 02:37:25,280 [salt.state       :1951][INFO    ][14749] Completed state [maasng.wait_for_http_code] at time 02:37:25.280404 duration_in_ms=130.605
2019-11-05 02:37:25,282 [salt.state       :1780][INFO    ][14749] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:37:25.282023
2019-11-05 02:37:25,282 [salt.state       :1813][INFO    ][14749] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-11-05 02:37:25,283 [salt.state       :300 ][INFO    ][14749] /var/lib/maas/.setup_admin exists
2019-11-05 02:37:25,283 [salt.state       :1951][INFO    ][14749] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:37:25.283489 duration_in_ms=1.453
2019-11-05 02:37:25,284 [salt.state       :1780][INFO    ][14749] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:37:25.284542
2019-11-05 02:37:25,285 [salt.state       :1813][INFO    ][14749] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-05 02:37:25,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14749] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-05 02:37:26,746 [salt.state       :300 ][INFO    ][14749] {'pid': 14826, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-05 02:37:26,747 [salt.state       :1951][INFO    ][14749] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:37:26.747343 duration_in_ms=1462.8
2019-11-05 02:37:26,755 [salt.state       :1780][INFO    ][14749] Running state [maas_region_boot_source_resources_mirror] at time 02:37:26.755082
2019-11-05 02:37:26,755 [salt.state       :1813][INFO    ][14749] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-11-05 02:37:26,872 [salt.state       :300 ][INFO    ][14749] {'changes': {}}
2019-11-05 02:37:26,873 [salt.state       :1951][INFO    ][14749] Completed state [maas_region_boot_source_resources_mirror] at time 02:37:26.873082 duration_in_ms=117.999
2019-11-05 02:37:26,874 [salt.state       :1780][INFO    ][14749] Running state [maasng.boot_resources_import] at time 02:37:26.874286
2019-11-05 02:37:26,874 [salt.state       :1813][INFO    ][14749] Executing state module.run for [maasng.boot_resources_import]
2019-11-05 02:37:26,875 [salt.utils.decorators:613 ][WARNING ][14749] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-05 02:37:26,981 [salt.loaded.ext.module.maasng:1600][INFO    ][14749] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-11-05 02:37:32,039 [salt.loaded.ext.module.maasng:1600][INFO    ][14749] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-11-05 02:37:35,965 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105023735953734
2019-11-05 02:37:35,985 [salt.minion      :1432][INFO    ][14858] Starting a new job with PID 14858
2019-11-05 02:37:36,006 [salt.minion      :1711][INFO    ][14858] Returning information for job: 20191105023735953734
2019-11-05 02:37:37,109 [salt.loaded.ext.module.maasng:1600][INFO    ][14749] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-11-05 02:37:42,216 [salt.state       :300 ][INFO    ][14749] {'ret': True}
2019-11-05 02:37:42,217 [salt.state       :1951][INFO    ][14749] Completed state [maasng.boot_resources_import] at time 02:37:42.217307 duration_in_ms=15343.02
2019-11-05 02:37:42,218 [salt.state       :1780][INFO    ][14749] Running state [maas_region_boot_sources_selection_bionic] at time 02:37:42.218645
2019-11-05 02:37:42,219 [salt.state       :1813][INFO    ][14749] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_bionic]
2019-11-05 02:37:42,419 [salt.state       :300 ][INFO    ][14749] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-11-05 02:37:42,420 [salt.state       :1951][INFO    ][14749] Completed state [maas_region_boot_sources_selection_bionic] at time 02:37:42.420313 duration_in_ms=201.669
2019-11-05 02:37:42,421 [salt.state       :1780][INFO    ][14749] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:37:42.421538
2019-11-05 02:37:42,422 [salt.state       :1813][INFO    ][14749] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-11-05 02:37:42,422 [salt.utils.decorators:613 ][WARNING ][14749] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-05 02:37:42,423 [salt.loaded.ext.module.maasng:1771][INFO    ][14749] boot-sources sync initiated for ALL Rack's
2019-11-05 02:37:43,389 [salt.state       :300 ][INFO    ][14749] {'ret': True}
2019-11-05 02:37:43,390 [salt.state       :1951][INFO    ][14749] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:37:43.390225 duration_in_ms=968.686
2019-11-05 02:37:43,392 [salt.state       :1780][INFO    ][14749] Running state [maas.process_maas_config] at time 02:37:43.392441
2019-11-05 02:37:43,393 [salt.state       :1813][INFO    ][14749] Executing state module.run for [maas.process_maas_config]
2019-11-05 02:37:43,393 [salt.utils.decorators:613 ][WARNING ][14749] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-05 02:37:43,394 [salt.loaded.ext.module.maas:92  ][INFO    ][14749] maasconfig name=enable_http_proxy value=True
2019-11-05 02:37:43,458 [salt.loaded.ext.module.maas:92  ][INFO    ][14749] maasconfig name=upstream_dns value=8.8.8.8
2019-11-05 02:37:43,528 [salt.loaded.ext.module.maas:92  ][INFO    ][14749] maasconfig name=commissioning_distro_series value=bionic
2019-11-05 02:37:43,587 [salt.loaded.ext.module.maas:92  ][INFO    ][14749] maasconfig name=default_osystem value=ubuntu
2019-11-05 02:37:43,647 [salt.loaded.ext.module.maas:92  ][INFO    ][14749] maasconfig name=active_discovery_interval value=600
2019-11-05 02:37:43,701 [salt.loaded.ext.module.maas:92  ][INFO    ][14749] maasconfig name=dnssec_validation value=no
2019-11-05 02:37:43,755 [salt.loaded.ext.module.maas:92  ][INFO    ][14749] maasconfig name=maas_name value=mas01
2019-11-05 02:37:46,407 [salt.loaded.ext.module.maas:92  ][INFO    ][14749] maasconfig name=network_discovery value=enabled
2019-11-05 02:37:46,542 [salt.loaded.ext.module.maas:92  ][INFO    ][14749] maasconfig name=enable_third_party_drivers value=True
2019-11-05 02:37:46,599 [salt.loaded.ext.module.maas:92  ][INFO    ][14749] maasconfig name=default_storage_layout value=lvm
2019-11-05 02:37:46,653 [salt.loaded.ext.module.maas:92  ][INFO    ][14749] maasconfig name=ntp_external_only value=True
2019-11-05 02:37:46,701 [salt.loaded.ext.module.maas:92  ][INFO    ][14749] maasconfig name=disk_erase_with_secure_erase value=False
2019-11-05 02:37:46,755 [salt.loaded.ext.module.maas:92  ][INFO    ][14749] maasconfig name=default_distro_series value=bionic
2019-11-05 02:37:46,835 [salt.loaded.ext.module.maas:92  ][INFO    ][14749] maasconfig name=default_min_hwe_kernel value=ga-18.04
2019-11-05 02:37:46,923 [salt.state       :300 ][INFO    ][14749] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-11-05 02:37:46,924 [salt.state       :1951][INFO    ][14749] Completed state [maas.process_maas_config] at time 02:37:46.924027 duration_in_ms=3531.586
2019-11-05 02:37:46,924 [salt.state       :1780][INFO    ][14749] Running state [pxe_admin] at time 02:37:46.924743
2019-11-05 02:37:46,925 [salt.state       :1813][INFO    ][14749] Executing state maasng.fabric_present for [pxe_admin]
2019-11-05 02:37:47,006 [salt.loaded.ext.module.maasng:945 ][INFO    ][14749] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'nxs43g', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-05 02:37:47,060 [salt.loaded.ext.module.maasng:1008][WARNING ][14749] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-11-05 02:37:47,060 [salt.loaded.ext.module.maasng:1011][WARNING ][14749] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-11-05 02:37:47,129 [salt.state       :300 ][INFO    ][14749] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-11-05 02:37:47,130 [salt.state       :1951][INFO    ][14749] Completed state [pxe_admin] at time 02:37:47.130220 duration_in_ms=205.475
2019-11-05 02:37:47,130 [salt.state       :1780][INFO    ][14749] Running state [vlan 0] at time 02:37:47.130707
2019-11-05 02:37:47,131 [salt.state       :1813][INFO    ][14749] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-11-05 02:37:47,196 [salt.loaded.ext.module.maasng:945 ][INFO    ][14749] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'nxs43g', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-05 02:37:47,327 [salt.loaded.ext.module.maasng:945 ][INFO    ][14749] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'id': 1}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nxs43g', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'id': 2}]
2019-11-05 02:37:47,617 [salt.loaded.ext.module.maasng:945 ][INFO    ][14749] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'nxs43g', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-05 02:37:47,713 [salt.state       :300 ][INFO    ][14749] {'new': 'Vlan untagged was updated'}
2019-11-05 02:37:47,713 [salt.state       :1951][INFO    ][14749] Completed state [vlan 0] at time 02:37:47.713742 duration_in_ms=583.035
2019-11-05 02:37:47,714 [salt.state       :1780][INFO    ][14749] Running state [192.168.11.0/24] at time 02:37:47.714684
2019-11-05 02:37:47,715 [salt.state       :1813][INFO    ][14749] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-11-05 02:37:47,947 [salt.loaded.ext.module.maasng:945 ][INFO    ][14749] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'id': 1}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nxs43g', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'id': 2}]
2019-11-05 02:37:47,947 [salt.loaded.ext.module.maasng:1235][WARNING ][14749] Ignoring parameter vlan:0
2019-11-05 02:37:48,028 [salt.state       :300 ][INFO    ][14749] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-11-05 02:37:48,029 [salt.state       :1951][INFO    ][14749] Completed state [192.168.11.0/24] at time 02:37:48.029017 duration_in_ms=314.331
2019-11-05 02:37:48,030 [salt.state       :1780][INFO    ][14749] Running state [maas_create_iprange_1] at time 02:37:48.030268
2019-11-05 02:37:48,030 [salt.state       :1813][INFO    ][14749] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-11-05 02:37:48,088 [salt.state       :300 ][INFO    ][14749] Iprange maas_create_iprange_1 already exist.
2019-11-05 02:37:48,089 [salt.state       :1951][INFO    ][14749] Completed state [maas_create_iprange_1] at time 02:37:48.088996 duration_in_ms=58.726
2019-11-05 02:37:48,089 [salt.state       :1780][INFO    ][14749] Running state [vlan 0] at time 02:37:48.089560
2019-11-05 02:37:48,090 [salt.state       :1813][INFO    ][14749] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-11-05 02:37:48,147 [salt.loaded.ext.module.maasng:945 ][INFO    ][14749] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'id': 1}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nxs43g', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'id': 2}]
2019-11-05 02:37:48,268 [salt.loaded.ext.module.maasng:945 ][INFO    ][14749] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nxs43g', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-11-05 02:37:48,491 [salt.loaded.ext.module.maasng:945 ][INFO    ][14749] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nxs43g', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-05 02:37:48,576 [salt.state       :300 ][INFO    ][14749] {'new': 'Vlan untagged was updated'}
2019-11-05 02:37:48,576 [salt.state       :1951][INFO    ][14749] Completed state [vlan 0] at time 02:37:48.576536 duration_in_ms=486.976
2019-11-05 02:37:48,577 [salt.state       :1780][INFO    ][14749] Running state [opnfv] at time 02:37:48.577275
2019-11-05 02:37:48,577 [salt.state       :1813][INFO    ][14749] Executing state maasng.sshkey_present for [opnfv]
2019-11-05 02:37:48,617 [salt.loaded.ext.module.maasng:1903][INFO    ][14749] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'keysource': u''}]
2019-11-05 02:37:48,618 [salt.state       :300 ][INFO    ][14749] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-11-05 02:37:48,618 [salt.state       :1951][INFO    ][14749] Completed state [opnfv] at time 02:37:48.618524 duration_in_ms=41.246
2019-11-05 02:37:48,665 [salt.minion      :1711][INFO    ][14749] Returning information for job: 20191105023720897758
2019-11-05 02:37:49,213 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command state.apply with jid 20191105023749201394
2019-11-05 02:37:49,231 [salt.minion      :1432][INFO    ][15123] Starting a new job with PID 15123
2019-11-05 02:37:52,972 [salt.state       :915 ][INFO    ][15123] Loading fresh modules for state activity
2019-11-05 02:37:53,066 [salt.state       :1780][INFO    ][15123] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:37:53.065947
2019-11-05 02:37:53,066 [salt.state       :1813][INFO    ][15123] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-05 02:37:53,068 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15123] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-05 02:37:54,561 [salt.state       :300 ][INFO    ][15123] {'pid': 15147, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-05 02:37:54,562 [salt.state       :1951][INFO    ][15123] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:37:54.562100 duration_in_ms=1496.152
2019-11-05 02:37:54,564 [salt.state       :1780][INFO    ][15123] Running state [maas.process_machines] at time 02:37:54.564761
2019-11-05 02:37:54,565 [salt.state       :1813][INFO    ][15123] Executing state module.run for [maas.process_machines]
2019-11-05 02:37:54,566 [salt.utils.decorators:613 ][WARNING ][15123] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-05 02:37:55,360 [salt.loaded.ext.module.maas:412 ][WARNING ][15123] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-05 02:37:55,361 [salt.loaded.ext.module.maas:92  ][INFO    ][15123] 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=hnbc46 architecture=amd64/generic power_parameters_power_user=admin
2019-11-05 02:37:56,600 [salt.loaded.ext.module.maas:412 ][WARNING ][15123] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-05 02:37:56,601 [salt.loaded.ext.module.maas:92  ][INFO    ][15123] 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=74gw3r architecture=amd64/generic power_parameters_power_user=admin
2019-11-05 02:37:58,014 [salt.loaded.ext.module.maas:412 ][WARNING ][15123] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-05 02:37:58,015 [salt.loaded.ext.module.maas:92  ][INFO    ][15123] 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=ghf8nt architecture=amd64/generic power_parameters_power_user=admin
2019-11-05 02:37:59,110 [salt.loaded.ext.module.maas:412 ][WARNING ][15123] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-05 02:37:59,110 [salt.loaded.ext.module.maas:92  ][INFO    ][15123] 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=b3xw4q architecture=amd64/generic power_parameters_power_user=admin
2019-11-05 02:38:00,356 [salt.loaded.ext.module.maas:412 ][WARNING ][15123] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-05 02:38:00,357 [salt.loaded.ext.module.maas:92  ][INFO    ][15123] 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=rperyk architecture=amd64/generic power_parameters_power_user=admin
2019-11-05 02:38:01,571 [salt.state       :300 ][INFO    ][15123] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-11-05 02:38:01,572 [salt.state       :1951][INFO    ][15123] Completed state [maas.process_machines] at time 02:38:01.572336 duration_in_ms=7007.574
2019-11-05 02:38:01,575 [salt.minion      :1711][INFO    ][15123] Returning information for job: 20191105023749201394
2019-11-05 02:38:34,864 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command state.apply with jid 20191105023834851374
2019-11-05 02:38:34,887 [salt.minion      :1432][INFO    ][15415] Starting a new job with PID 15415
2019-11-05 02:38:38,672 [salt.state       :915 ][INFO    ][15415] Loading fresh modules for state activity
2019-11-05 02:38:38,758 [salt.state       :1780][INFO    ][15415] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:38:38.758326
2019-11-05 02:38:38,758 [salt.state       :1813][INFO    ][15415] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-05 02:38:38,760 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15415] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-05 02:38:40,001 [salt.state       :300 ][INFO    ][15415] {'pid': 15423, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-05 02:38:40,002 [salt.state       :1951][INFO    ][15415] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:38:40.001998 duration_in_ms=1243.673
2019-11-05 02:38:40,003 [salt.state       :1780][INFO    ][15415] Running state [maas.wait_for_machine_status] at time 02:38:40.003036
2019-11-05 02:38:40,003 [salt.state       :1813][INFO    ][15415] Executing state module.run for [maas.wait_for_machine_status]
2019-11-05 02:38:40,003 [salt.utils.decorators:613 ][WARNING ][15415] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-05 02:38:42,375 [salt.loaded.ext.module.maas:993 ][INFO    ][15415] Machine b3xw4q mark broken
2019-11-05 02:38:43,024 [salt.loaded.ext.module.maas:996 ][INFO    ][15415] Machine b3xw4q mark fixed
2019-11-05 02:38:44,274 [salt.loaded.ext.module.maas:684 ][INFO    ][15415] deploymachines hwe_kernel=ga-18.04 system_id=b3xw4q distro_series=bionic
2019-11-05 02:38:47,388 [salt.loaded.ext.module.maas:1023][INFO    ][15415] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1492.619524s left)
2019-11-05 02:38:49,977 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105023849922669
2019-11-05 02:38:49,998 [salt.minion      :1432][INFO    ][15490] Starting a new job with PID 15490
2019-11-05 02:38:50,019 [salt.minion      :1711][INFO    ][15490] Returning information for job: 20191105023849922669
2019-11-05 02:39:20,014 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105023920001890
2019-11-05 02:39:20,036 [salt.minion      :1432][INFO    ][15528] Starting a new job with PID 15528
2019-11-05 02:39:20,061 [salt.minion      :1711][INFO    ][15528] Returning information for job: 20191105023920001890
2019-11-05 02:39:20,975 [salt.loaded.ext.module.maas:1023][INFO    ][15415] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1459.03239703s left)
2019-11-05 02:39:50,082 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105023950069819
2019-11-05 02:39:50,105 [salt.minion      :1432][INFO    ][15570] Starting a new job with PID 15570
2019-11-05 02:39:50,132 [salt.minion      :1711][INFO    ][15570] Returning information for job: 20191105023950069819
2019-11-05 02:39:54,398 [salt.loaded.ext.module.maas:1023][INFO    ][15415] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1425.60942817s left)
2019-11-05 02:40:20,132 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105024020118288
2019-11-05 02:40:20,154 [salt.minion      :1432][INFO    ][15607] Starting a new job with PID 15607
2019-11-05 02:40:20,179 [salt.minion      :1711][INFO    ][15607] Returning information for job: 20191105024020118288
2019-11-05 02:40:26,800 [salt.loaded.ext.module.maas:1023][INFO    ][15415] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1393.20740008s left)
2019-11-05 02:40:50,188 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105024050175888
2019-11-05 02:40:50,207 [salt.minion      :1432][INFO    ][15705] Starting a new job with PID 15705
2019-11-05 02:40:50,221 [salt.minion      :1711][INFO    ][15705] Returning information for job: 20191105024050175888
2019-11-05 02:41:00,534 [salt.loaded.ext.module.maas:1023][INFO    ][15415] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1359.47280097s left)
2019-11-05 02:41:20,225 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105024120213310
2019-11-05 02:41:20,248 [salt.minion      :1432][INFO    ][15802] Starting a new job with PID 15802
2019-11-05 02:41:20,276 [salt.minion      :1711][INFO    ][15802] Returning information for job: 20191105024120213310
2019-11-05 02:41:34,188 [salt.loaded.ext.module.maas:1023][INFO    ][15415] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1325.8191421s left)
2019-11-05 02:41:50,288 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105024150275748
2019-11-05 02:41:50,310 [salt.minion      :1432][INFO    ][15911] Starting a new job with PID 15911
2019-11-05 02:41:50,336 [salt.minion      :1711][INFO    ][15911] Returning information for job: 20191105024150275748
2019-11-05 02:42:07,627 [salt.loaded.ext.module.maas:1023][INFO    ][15415] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1292.37989998s left)
2019-11-05 02:42:20,347 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105024220334995
2019-11-05 02:42:20,370 [salt.minion      :1432][INFO    ][15979] Starting a new job with PID 15979
2019-11-05 02:42:20,395 [salt.minion      :1711][INFO    ][15979] Returning information for job: 20191105024220334995
2019-11-05 02:42:40,961 [salt.loaded.ext.module.maas:1023][INFO    ][15415] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1259.04629016s left)
2019-11-05 02:42:50,413 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105024250400271
2019-11-05 02:42:50,435 [salt.minion      :1432][INFO    ][16027] Starting a new job with PID 16027
2019-11-05 02:42:50,460 [salt.minion      :1711][INFO    ][16027] Returning information for job: 20191105024250400271
2019-11-05 02:43:14,514 [salt.loaded.ext.module.maas:1023][INFO    ][15415] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1225.49338412s left)
2019-11-05 02:43:20,486 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105024320473730
2019-11-05 02:43:20,509 [salt.minion      :1432][INFO    ][16097] Starting a new job with PID 16097
2019-11-05 02:43:20,533 [salt.minion      :1711][INFO    ][16097] Returning information for job: 20191105024320473730
2019-11-05 02:43:48,221 [salt.loaded.ext.module.maas:1023][INFO    ][15415] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1191.78619504s left)
2019-11-05 02:43:50,559 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105024350546538
2019-11-05 02:43:50,582 [salt.minion      :1432][INFO    ][16157] Starting a new job with PID 16157
2019-11-05 02:43:50,609 [salt.minion      :1711][INFO    ][16157] Returning information for job: 20191105024350546538
2019-11-05 02:44:20,641 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105024420628250
2019-11-05 02:44:20,663 [salt.minion      :1432][INFO    ][16248] Starting a new job with PID 16248
2019-11-05 02:44:20,688 [salt.minion      :1711][INFO    ][16248] Returning information for job: 20191105024420628250
2019-11-05 02:44:21,762 [salt.loaded.ext.module.maas:1023][INFO    ][15415] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1158.24512911s left)
2019-11-05 02:44:50,723 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105024450710556
2019-11-05 02:44:50,745 [salt.minion      :1432][INFO    ][16328] Starting a new job with PID 16328
2019-11-05 02:44:50,772 [salt.minion      :1711][INFO    ][16328] Returning information for job: 20191105024450710556
2019-11-05 02:44:55,472 [salt.loaded.ext.module.maas:1023][INFO    ][15415] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1124.53462696s left)
2019-11-05 02:45:20,815 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105024520802251
2019-11-05 02:45:20,838 [salt.minion      :1432][INFO    ][16398] Starting a new job with PID 16398
2019-11-05 02:45:20,864 [salt.minion      :1711][INFO    ][16398] Returning information for job: 20191105024520802251
2019-11-05 02:45:29,078 [salt.loaded.ext.module.maas:1023][INFO    ][15415] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1090.92860007s left)
2019-11-05 02:45:50,911 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105024550897961
2019-11-05 02:45:50,933 [salt.minion      :1432][INFO    ][16439] Starting a new job with PID 16439
2019-11-05 02:45:50,957 [salt.minion      :1711][INFO    ][16439] Returning information for job: 20191105024550897961
2019-11-05 02:46:02,764 [salt.loaded.ext.module.maas:1023][INFO    ][15415] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1057.24283099s left)
2019-11-05 02:46:21,010 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command saltutil.find_job with jid 20191105024620996987
2019-11-05 02:46:21,034 [salt.minion      :1432][INFO    ][16507] Starting a new job with PID 16507
2019-11-05 02:46:21,061 [salt.minion      :1711][INFO    ][16507] Returning information for job: 20191105024620996987
2019-11-05 02:46:36,473 [salt.state       :300 ][INFO    ][15415] {'ret': True}
2019-11-05 02:46:36,474 [salt.state       :1951][INFO    ][15415] Completed state [maas.wait_for_machine_status] at time 02:46:36.474093 duration_in_ms=476471.054
2019-11-05 02:46:36,478 [salt.minion      :1711][INFO    ][15415] Returning information for job: 20191105023834851374
2019-11-05 02:46:37,113 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command state.apply with jid 20191105024637099561
2019-11-05 02:46:37,136 [salt.minion      :1432][INFO    ][16582] Starting a new job with PID 16582
2019-11-05 02:46:40,942 [salt.state       :915 ][INFO    ][16582] Loading fresh modules for state activity
2019-11-05 02:46:41,082 [salt.state       :1780][INFO    ][16582] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:46:41.082593
2019-11-05 02:46:41,082 [salt.state       :1813][INFO    ][16582] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-05 02:46:41,084 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16582] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-05 02:46:42,643 [salt.state       :300 ][INFO    ][16582] {'pid': 16590, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-05 02:46:42,643 [salt.state       :1951][INFO    ][16582] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:46:42.643846 duration_in_ms=1561.252
2019-11-05 02:46:42,647 [salt.state       :1780][INFO    ][16582] Running state [maas_machines_storage_cmp002_lvm] at time 02:46:42.647059
2019-11-05 02:46:42,647 [salt.state       :1813][INFO    ][16582] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-11-05 02:46:43,409 [salt.state       :300 ][INFO    ][16582] Machine cmp002 is not in Ready state.
2019-11-05 02:46:43,409 [salt.state       :1951][INFO    ][16582] Completed state [maas_machines_storage_cmp002_lvm] at time 02:46:43.409705 duration_in_ms=762.646
2019-11-05 02:46:43,410 [salt.state       :1780][INFO    ][16582] Running state [maas_machines_storage_cmp001_lvm] at time 02:46:43.410245
2019-11-05 02:46:43,410 [salt.state       :1813][INFO    ][16582] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-11-05 02:46:44,180 [salt.state       :300 ][INFO    ][16582] Machine cmp001 is not in Ready state.
2019-11-05 02:46:44,181 [salt.state       :1951][INFO    ][16582] Completed state [maas_machines_storage_cmp001_lvm] at time 02:46:44.180952 duration_in_ms=770.707
2019-11-05 02:46:44,185 [salt.minion      :1711][INFO    ][16582] Returning information for job: 20191105024637099561
2019-11-05 02:46:44,821 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command state.apply with jid 20191105024644805972
2019-11-05 02:46:44,847 [salt.minion      :1432][INFO    ][16600] Starting a new job with PID 16600
2019-11-05 02:46:45,675 [salt.state       :915 ][INFO    ][16600] Loading fresh modules for state activity
2019-11-05 02:46:45,763 [salt.state       :1780][INFO    ][16600] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:46:45.763112
2019-11-05 02:46:45,763 [salt.state       :1813][INFO    ][16600] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-05 02:46:45,765 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16600] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-05 02:46:47,327 [salt.state       :300 ][INFO    ][16600] {'pid': 16607, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-05 02:46:47,327 [salt.state       :1951][INFO    ][16600] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:46:47.327808 duration_in_ms=1564.696
2019-11-05 02:46:47,330 [salt.state       :1780][INFO    ][16600] Running state [maas.deploy_machines] at time 02:46:47.330480
2019-11-05 02:46:47,331 [salt.state       :1813][INFO    ][16600] Executing state module.run for [maas.deploy_machines]
2019-11-05 02:46:47,332 [salt.utils.decorators:613 ][WARNING ][16600] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-05 02:46:48,069 [salt.state       :300 ][INFO    ][16600] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-11-05 02:46:48,070 [salt.state       :1951][INFO    ][16600] Completed state [maas.deploy_machines] at time 02:46:48.070340 duration_in_ms=739.859
2019-11-05 02:46:48,074 [salt.minion      :1711][INFO    ][16600] Returning information for job: 20191105024644805972
2019-11-05 02:46:48,715 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command state.apply with jid 20191105024648700477
2019-11-05 02:46:48,736 [salt.minion      :1432][INFO    ][16623] Starting a new job with PID 16623
2019-11-05 02:46:49,506 [salt.state       :915 ][INFO    ][16623] Loading fresh modules for state activity
2019-11-05 02:46:49,599 [salt.state       :1780][INFO    ][16623] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:46:49.599012
2019-11-05 02:46:49,599 [salt.state       :1813][INFO    ][16623] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-05 02:46:49,601 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16623] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-05 02:46:51,182 [salt.state       :300 ][INFO    ][16623] {'pid': 16630, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-05 02:46:51,183 [salt.state       :1951][INFO    ][16623] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:46:51.183698 duration_in_ms=1584.686
2019-11-05 02:46:51,187 [salt.state       :1780][INFO    ][16623] Running state [maas.wait_for_machine_status] at time 02:46:51.187130
2019-11-05 02:46:51,187 [salt.state       :1813][INFO    ][16623] Executing state module.run for [maas.wait_for_machine_status]
2019-11-05 02:46:51,188 [salt.utils.decorators:613 ][WARNING ][16623] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-05 02:46:54,179 [salt.state       :300 ][INFO    ][16623] {'ret': True}
2019-11-05 02:46:54,180 [salt.state       :1951][INFO    ][16623] Completed state [maas.wait_for_machine_status] at time 02:46:54.180119 duration_in_ms=2992.987
2019-11-05 02:46:54,183 [salt.minion      :1711][INFO    ][16623] Returning information for job: 20191105024648700477
2019-11-05 03:15:39,712 [salt.utils.schedule:1377][INFO    ][8423] Running scheduled job: __mine_interval
2019-11-05 04:15:39,712 [salt.utils.schedule:1377][INFO    ][8423] Running scheduled job: __mine_interval
2019-11-05 04:19:06,127 [salt.minion      :1308][INFO    ][8423] User sudo_ubuntu Executing command cp.push_dir with jid 20191105041906114571
2019-11-05 04:19:06,142 [salt.minion      :1432][INFO    ][23213] Starting a new job with PID 23213
