2019-08-11 02:02:36,921 [salt.minion      :870 ][ERROR   ][360] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-11 02:03:26,966 [salt.minion      :870 ][ERROR   ][360] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-11 02:04:17,012 [salt.minion      :870 ][ERROR   ][360] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-11 02:05:07,055 [salt.minion      :870 ][ERROR   ][360] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-11 02:05:57,103 [salt.minion      :870 ][ERROR   ][360] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-11 02:08:06,346 [salt.utils.decorators:613 ][WARNING ][2702] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-11 02:08:06,928 [salt.utils.decorators:613 ][WARNING ][2702] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-11 02:08:09,124 [salt.loaded.int.states.file:2298][WARNING ][2840] 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-08-11 02:08:14,810 [salt.state       :2022][WARNING ][2952] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-08-11 02:08:17,333 [salt.utils.decorators:613 ][WARNING ][2952] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-11 02:08:31,360 [salt.utils.decorators:613 ][WARNING ][2952] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-11 02:09:07,275 [salt.utils.decorators:613 ][WARNING ][2952] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-11 02:09:08,149 [salt.utils.decorators:613 ][WARNING ][2952] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-11 02:09:12,190 [salt.loaded.ext.module.maasng:1008][WARNING ][2952] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-08-11 02:09:12,190 [salt.loaded.ext.module.maasng:1011][WARNING ][2952] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-08-11 02:09:12,856 [salt.loaded.ext.module.maasng:1235][WARNING ][2952] Ignoring parameter vlan:0
2019-08-11 02:09:19,573 [salt.utils.decorators:613 ][WARNING ][5911] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-11 02:09:19,634 [salt.loaded.ext.module.maas:412 ][WARNING ][5911] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-11 02:09:21,192 [salt.loaded.ext.module.maas:412 ][WARNING ][5911] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-11 02:09:22,652 [salt.loaded.ext.module.maas:412 ][WARNING ][5911] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-11 02:09:24,048 [salt.loaded.ext.module.maas:412 ][WARNING ][5911] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-11 02:09:25,443 [salt.loaded.ext.module.maas:412 ][WARNING ][5911] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-11 02:09:29,847 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6559] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-08-11 02:09:29,877 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6559] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-08-11 02:09:29,898 [salt.utils.parsers:1051][WARNING ][360] Minion received a SIGTERM. Exiting.
2019-08-11 02:09:30,949 [salt.cli.daemons :293 ][INFO    ][6611] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-08-11 02:09:31,051 [salt.cli.daemons :82  ][INFO    ][6611] Starting up the Salt Minion
2019-08-11 02:09:31,051 [salt.utils.event :1017][INFO    ][6611] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-08-11 02:09:31,979 [salt.minion      :976 ][INFO    ][6611] Creating minion process manager
2019-08-11 02:09:33,539 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6611] Executing command ['date', '+%z'] in directory '/root'
2019-08-11 02:09:33,558 [salt.utils.schedule:568 ][INFO    ][6611] Updating job settings for scheduled job: __mine_interval
2019-08-11 02:09:33,559 [salt.minion      :1108][INFO    ][6611] Added mine.update to scheduler
2019-08-11 02:09:33,562 [salt.minion      :1975][INFO    ][6611] Minion is starting as user 'root'
2019-08-11 02:09:33,576 [salt.minion      :2336][INFO    ][6611] Minion is ready to receive requests!
2019-08-11 02:09:57,900 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command state.apply with jid 20190811020957884977
2019-08-11 02:09:57,922 [salt.minion      :1432][INFO    ][6703] Starting a new job with PID 6703
2019-08-11 02:10:01,654 [salt.state       :915 ][INFO    ][6703] Loading fresh modules for state activity
2019-08-11 02:10:01,705 [salt.fileclient  :1219][INFO    ][6703] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-08-11 02:10:01,748 [salt.state       :1780][INFO    ][6703] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:10:01.748695
2019-08-11 02:10:01,749 [salt.state       :1813][INFO    ][6703] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-11 02:10:01,751 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6703] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-11 02:10:03,183 [salt.state       :300 ][INFO    ][6703] {'pid': 6711, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-11 02:10:03,183 [salt.state       :1951][INFO    ][6703] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:10:03.183449 duration_in_ms=1434.755
2019-08-11 02:10:03,184 [salt.state       :1780][INFO    ][6703] Running state [maas.wait_for_machine_status] at time 02:10:03.184584
2019-08-11 02:10:03,184 [salt.state       :1813][INFO    ][6703] Executing state module.run for [maas.wait_for_machine_status]
2019-08-11 02:10:03,185 [salt.utils.decorators:613 ][WARNING ][6703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-11 02:10:04,041 [salt.loaded.ext.module.maas:1023][INFO    ][6703] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.14880085s left)
2019-08-11 02:10:12,940 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811021012924400
2019-08-11 02:10:12,963 [salt.minion      :1432][INFO    ][6736] Starting a new job with PID 6736
2019-08-11 02:10:12,986 [salt.minion      :1711][INFO    ][6736] Returning information for job: 20190811021012924400
2019-08-11 02:10:35,021 [salt.loaded.ext.module.maas:1023][INFO    ][6703] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.16856194s left)
2019-08-11 02:10:42,989 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811021042977393
2019-08-11 02:10:43,012 [salt.minion      :1432][INFO    ][6781] Starting a new job with PID 6781
2019-08-11 02:10:43,035 [salt.minion      :1711][INFO    ][6781] Returning information for job: 20190811021042977393
2019-08-11 02:11:06,407 [salt.loaded.ext.module.maas:1023][INFO    ][6703] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.78284383s left)
2019-08-11 02:11:13,066 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811021113053608
2019-08-11 02:11:13,086 [salt.minion      :1432][INFO    ][6928] Starting a new job with PID 6928
2019-08-11 02:11:13,106 [salt.minion      :1711][INFO    ][6928] Returning information for job: 20190811021113053608
2019-08-11 02:11:37,842 [salt.loaded.ext.module.maas:1023][INFO    ][6703] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.34697199s left)
2019-08-11 02:11:43,111 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811021143095899
2019-08-11 02:11:43,134 [salt.minion      :1432][INFO    ][7116] Starting a new job with PID 7116
2019-08-11 02:11:43,157 [salt.minion      :1711][INFO    ][7116] Returning information for job: 20190811021143095899
2019-08-11 02:12:09,567 [salt.loaded.ext.module.maas:1023][INFO    ][6703] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.62249303s left)
2019-08-11 02:12:13,169 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811021213156242
2019-08-11 02:12:13,192 [salt.minion      :1432][INFO    ][7289] Starting a new job with PID 7289
2019-08-11 02:12:13,215 [salt.minion      :1711][INFO    ][7289] Returning information for job: 20190811021213156242
2019-08-11 02:12:41,374 [salt.loaded.ext.module.maas:1023][INFO    ][6703] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.81532288s left)
2019-08-11 02:12:43,228 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811021243210951
2019-08-11 02:12:43,250 [salt.minion      :1432][INFO    ][7843] Starting a new job with PID 7843
2019-08-11 02:12:43,275 [salt.minion      :1711][INFO    ][7843] Returning information for job: 20190811021243210951
2019-08-11 02:13:13,291 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811021313274643
2019-08-11 02:13:13,313 [salt.minion      :1432][INFO    ][8013] Starting a new job with PID 8013
2019-08-11 02:13:13,336 [salt.minion      :1711][INFO    ][8013] Returning information for job: 20190811021313274643
2019-08-11 02:13:14,830 [salt.state       :300 ][INFO    ][6703] {'ret': True}
2019-08-11 02:13:14,830 [salt.state       :1951][INFO    ][6703] Completed state [maas.wait_for_machine_status] at time 02:13:14.830399 duration_in_ms=191645.812
2019-08-11 02:13:14,834 [salt.minion      :1711][INFO    ][6703] Returning information for job: 20190811020957884977
2019-08-11 02:13:15,476 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command state.apply with jid 20190811021315463171
2019-08-11 02:13:15,498 [salt.minion      :1432][INFO    ][8031] Starting a new job with PID 8031
2019-08-11 02:13:19,476 [salt.state       :915 ][INFO    ][8031] Loading fresh modules for state activity
2019-08-11 02:13:19,530 [salt.fileclient  :1219][INFO    ][8031] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-08-11 02:13:19,625 [salt.state       :1780][INFO    ][8031] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:19.625882
2019-08-11 02:13:19,626 [salt.state       :1813][INFO    ][8031] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-11 02:13:19,628 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8031] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-11 02:13:21,077 [salt.state       :300 ][INFO    ][8031] {'pid': 8106, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-11 02:13:21,078 [salt.state       :1951][INFO    ][8031] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:21.078398 duration_in_ms=1452.516
2019-08-11 02:13:21,079 [salt.state       :1780][INFO    ][8031] Running state [maas_machines_storage_cmp002_lvm] at time 02:13:21.079916
2019-08-11 02:13:21,080 [salt.state       :1813][INFO    ][8031] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-08-11 02:13:22,358 [salt.loaded.ext.module.maasng:610 ][INFO    ][8031] 4dmked
2019-08-11 02:13:22,358 [salt.loaded.ext.module.maasng:626 ][INFO    ][8031] sda
2019-08-11 02:13:23,067 [salt.loaded.ext.module.maasng:361 ][INFO    ][8031] 4dmked
2019-08-11 02:13:23,204 [salt.loaded.ext.module.maasng:367 ][INFO    ][8031] [{u'size': 2397998940160, u'partition_table_type': u'GPT', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'id': 4, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'4dmked', u'resource_uri': u'/MAAS/api/2.0/nodes/4dmked/blockdevices/4/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'7772ba23-5f73-40b4-8ec0-4183fc3d9df1', u'resource_uri': u'/MAAS/api/2.0/nodes/4dmked/blockdevices/4/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4dmked', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'6b17271a-596c-4fd4-a5b9-05843b90edda', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 5, u'device_id': 4}], u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda'}, {u'size': 2397988454400, u'partition_table_type': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'3b69df5a-db5e-4f32-89ea-ce5290d672b7', u'tags': [], u'type': u'virtual', u'id': 10, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'4dmked', u'resource_uri': u'/MAAS/api/2.0/nodes/4dmked/blockdevices/10/', u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'5fd65ad8-1bb2-4e14-b9f5-3d940e77d9a6', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'used_size': 2397988454400, u'model': None, u'name': u'vgroot-lvroot'}]
2019-08-11 02:13:23,204 [salt.loaded.ext.module.maasng:632 ][INFO    ][8031] vgroot
2019-08-11 02:13:23,205 [salt.loaded.ext.module.maasng:635 ][INFO    ][8031] lvroot
2019-08-11 02:13:23,205 [salt.loaded.ext.module.maasng:639 ][INFO    ][8031] 107374182400
2019-08-11 02:13:23,894 [salt.loaded.ext.module.maasng:645 ][INFO    ][8031] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'ip_addresses': [u'192.168.11.42'], u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-16.04', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'fqdn': u'cmp002.maas', u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'4dmked', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'block_size': 4096, u'uuid': u'dc4589ce-149b-44e3-9bf6-0f52e55e25d2', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'4dmked', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'b0cb6e35-ef8a-41fa-9e46-ba1309a21fb2', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/4dmked/blockdevices/11/'}], u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'b78b23b7-60c1-42ff-bcf1-4c042f23d69d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4dmked', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'7152d908-e32a-4ce7-9311-31d6d727886f', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/4dmked/blockdevices/4/partition/6', u'type': u'partition', u'id': 6, u'device_id': 4}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'4dmked', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/4dmked/blockdevices/4/', u'used_size': 2397998940160, u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'name': u'sda'}, {u'size': 107374182400, u'block_size': 4096, u'uuid': u'dc4589ce-149b-44e3-9bf6-0f52e55e25d2', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'4dmked', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'b0cb6e35-ef8a-41fa-9e46-ba1309a21fb2', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/4dmked/blockdevices/11/', u'used_size': 107374182400, u'id': 11, u'serial': None, u'name': u'vgroot-lvroot'}], 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'4dmked', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'b78b23b7-60c1-42ff-bcf1-4c042f23d69d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4dmked', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'7152d908-e32a-4ce7-9311-31d6d727886f', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/4dmked/blockdevices/4/partition/6', u'type': u'partition', u'id': 6, u'device_id': 4}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'4dmked', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/4dmked/blockdevices/4/'}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hfrg87', 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.42', u'id': 32, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hfrg87', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'parents': [], u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hfrg87', 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.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'4dmked', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4dmked/interfaces/4/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 34}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', 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'enabled': True, u'parents': [], u'id': 18, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6b', u'system_id': u'4dmked', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4dmked/interfaces/18/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 36}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', 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'enabled': True, u'parents': [], u'id': 20, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6c', u'system_id': u'4dmked', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4dmked/interfaces/20/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 38}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', 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'enabled': True, u'parents': [], u'id': 22, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6d', u'system_id': u'4dmked', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4dmked/interfaces/22/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'b78b23b7-60c1-42ff-bcf1-4c042f23d69d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4dmked', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'7152d908-e32a-4ce7-9311-31d6d727886f', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/4dmked/blockdevices/4/partition/6', u'type': u'partition', u'id': 6, u'device_id': 4}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'4dmked', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/4dmked/blockdevices/4/'}], u'netboot': True, u'osystem': u'', u'status_action': u'', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hfrg87', 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.42', u'id': 32, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hfrg87', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'parents': [], u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hfrg87', 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.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'4dmked', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4dmked/interfaces/4/'}, u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/4dmked/', u'distro_series': u'', u'memory_test_status': -1}
2019-08-11 02:13:23,896 [salt.state       :300 ][INFO    ][8031] {'new': {'storage_layout': 'lvm'}}
2019-08-11 02:13:23,897 [salt.state       :1951][INFO    ][8031] Completed state [maas_machines_storage_cmp002_lvm] at time 02:13:23.897221 duration_in_ms=2817.303
2019-08-11 02:13:23,897 [salt.state       :1780][INFO    ][8031] Running state [maas_machines_storage_cmp001_lvm] at time 02:13:23.897755
2019-08-11 02:13:23,898 [salt.state       :1813][INFO    ][8031] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-08-11 02:13:25,333 [salt.loaded.ext.module.maasng:610 ][INFO    ][8031] bfxmny
2019-08-11 02:13:25,333 [salt.loaded.ext.module.maasng:626 ][INFO    ][8031] sda
2019-08-11 02:13:26,050 [salt.loaded.ext.module.maasng:361 ][INFO    ][8031] bfxmny
2019-08-11 02:13:26,176 [salt.loaded.ext.module.maasng:367 ][INFO    ][8031] [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/bfxmny/blockdevices/1/', u'used_size': 2397998940160, u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'bfxmny', 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'uuid': u'751a5013-eb5d-4af9-9938-c3fd091abff9', u'resource_uri': u'/MAAS/api/2.0/nodes/bfxmny/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'bfxmny', u'filesystem': {u'uuid': u'8239ec40-5d72-48a8-a684-207eab4004ac', 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'size': 2397992648704, u'type': u'partition', u'id': 1, u'device_id': 1}], u'type': u'physical', u'id': 1, u'tags': [u'rotary'], u'size': 2397998940160}, {u'model': None, u'block_size': 4096, u'uuid': u'266c601e-a622-4350-b31c-c0416933b43f', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/bfxmny/blockdevices/6/', u'used_size': 2397988454400, u'filesystem': {u'uuid': u'31a24a7c-50d7-477a-b4a8-2afc7b814115', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'bfxmny', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 6, u'tags': [], u'size': 2397988454400}]
2019-08-11 02:13:26,177 [salt.loaded.ext.module.maasng:632 ][INFO    ][8031] vgroot
2019-08-11 02:13:26,177 [salt.loaded.ext.module.maasng:635 ][INFO    ][8031] lvroot
2019-08-11 02:13:26,178 [salt.loaded.ext.module.maasng:639 ][INFO    ][8031] 107374182400
2019-08-11 02:13:26,926 [salt.loaded.ext.module.maasng:645 ][INFO    ][8031] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hfrg87', u'name': u'untagged', 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'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hfrg87', u'name': u'untagged', 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'mode': u'dhcp', u'id': 15}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hfrg87', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 5, u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'bfxmny', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/bfxmny/interfaces/5/'}, u'min_hwe_kernel': u'ga-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'1df2f2d6-6d62-46fa-9dd8-4b3916cab14d', u'resource_uri': u'/MAAS/api/2.0/nodes/bfxmny/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'bfxmny', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'4264d672-1044-4971-969d-1c80ac654a92', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'bfxmny', 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'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/bfxmny/blockdevices/1/'}, 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'hostname': u'cmp001', u'storage': 2397998.9401599998, u'node_type': 0, u'testing_status': 2, u'system_id': u'bfxmny', 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'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'1df2f2d6-6d62-46fa-9dd8-4b3916cab14d', u'resource_uri': u'/MAAS/api/2.0/nodes/bfxmny/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'bfxmny', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'4264d672-1044-4971-969d-1c80ac654a92', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'bfxmny', 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'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/bfxmny/blockdevices/1/'}], u'blockdevice_set': [{u'size': 2397998940160, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'1df2f2d6-6d62-46fa-9dd8-4b3916cab14d', u'resource_uri': u'/MAAS/api/2.0/nodes/bfxmny/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'bfxmny', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'4264d672-1044-4971-969d-1c80ac654a92', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'bfxmny', 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'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/bfxmny/blockdevices/1/'}, {u'size': 107374182400, u'uuid': u'e1d1a8e9-a41e-450e-b73e-300fcaee9c61', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'758fe065-21aa-479e-a89b-9376647bc630', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'bfxmny', 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'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/bfxmny/blockdevices/12/'}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'bfxmny', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, 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'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'uuid': u'e1d1a8e9-a41e-450e-b73e-300fcaee9c61', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'758fe065-21aa-479e-a89b-9376647bc630', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'bfxmny', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/bfxmny/blockdevices/12/'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hfrg87', u'name': u'untagged', 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'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hfrg87', u'name': u'untagged', 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'mode': u'dhcp', u'id': 15}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hfrg87', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 5, u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'bfxmny', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/bfxmny/interfaces/5/'}, {u'discovered': None, u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 16}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 9, u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'system_id': u'bfxmny', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/bfxmny/interfaces/9/'}, {u'discovered': None, u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 17}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 10, u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'system_id': u'bfxmny', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/bfxmny/interfaces/10/'}, {u'discovered': None, u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 18}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 11, u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'system_id': u'bfxmny', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/bfxmny/interfaces/11/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/bfxmny/', u'distro_series': u'', u'memory_test_status': -1}
2019-08-11 02:13:26,928 [salt.state       :300 ][INFO    ][8031] {'new': {'storage_layout': 'lvm'}}
2019-08-11 02:13:26,929 [salt.state       :1951][INFO    ][8031] Completed state [maas_machines_storage_cmp001_lvm] at time 02:13:26.928963 duration_in_ms=3031.207
2019-08-11 02:13:26,932 [salt.minion      :1711][INFO    ][8031] Returning information for job: 20190811021315463171
2019-08-11 02:13:27,582 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command state.apply with jid 20190811021327565714
2019-08-11 02:13:27,604 [salt.minion      :1432][INFO    ][8126] Starting a new job with PID 8126
2019-08-11 02:13:28,372 [salt.state       :915 ][INFO    ][8126] Loading fresh modules for state activity
2019-08-11 02:13:28,425 [salt.fileclient  :1219][INFO    ][8126] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-08-11 02:13:28,470 [salt.state       :1780][INFO    ][8126] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:28.469945
2019-08-11 02:13:28,470 [salt.state       :1813][INFO    ][8126] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-11 02:13:28,472 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8126] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-11 02:13:29,898 [salt.state       :300 ][INFO    ][8126] {'pid': 8133, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-11 02:13:29,899 [salt.state       :1951][INFO    ][8126] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:29.899069 duration_in_ms=1429.126
2019-08-11 02:13:29,900 [salt.state       :1780][INFO    ][8126] Running state [maas.deploy_machines] at time 02:13:29.900151
2019-08-11 02:13:29,900 [salt.state       :1813][INFO    ][8126] Executing state module.run for [maas.deploy_machines]
2019-08-11 02:13:29,901 [salt.utils.decorators:613 ][WARNING ][8126] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-11 02:13:30,727 [salt.loaded.ext.module.maas:684 ][INFO    ][8126] deploymachines hwe_kernel=ga-16.04 system_id=4dmked distro_series=xenial
2019-08-11 02:13:33,506 [salt.loaded.ext.module.maas:684 ][INFO    ][8126] deploymachines hwe_kernel=ga-16.04 system_id=bfxmny distro_series=xenial
2019-08-11 02:13:36,155 [salt.loaded.ext.module.maas:684 ][INFO    ][8126] deploymachines hwe_kernel=ga-16.04 system_id=yfbtst distro_series=xenial
2019-08-11 02:13:38,862 [salt.loaded.ext.module.maas:684 ][INFO    ][8126] deploymachines hwe_kernel=ga-16.04 system_id=w8pcfb distro_series=xenial
2019-08-11 02:13:41,030 [salt.loaded.ext.module.maas:684 ][INFO    ][8126] deploymachines hwe_kernel=ga-16.04 system_id=nm3qrs distro_series=xenial
2019-08-11 02:13:42,648 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811021342634548
2019-08-11 02:13:42,668 [salt.minion      :1432][INFO    ][8394] Starting a new job with PID 8394
2019-08-11 02:13:42,690 [salt.minion      :1711][INFO    ][8394] Returning information for job: 20190811021342634548
2019-08-11 02:13:43,713 [salt.state       :300 ][INFO    ][8126] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-08-11 02:13:43,713 [salt.state       :1951][INFO    ][8126] Completed state [maas.deploy_machines] at time 02:13:43.713543 duration_in_ms=13813.389
2019-08-11 02:13:43,717 [salt.minion      :1711][INFO    ][8126] Returning information for job: 20190811021327565714
2019-08-11 02:13:44,339 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command state.apply with jid 20190811021344329615
2019-08-11 02:13:44,361 [salt.minion      :1432][INFO    ][8413] Starting a new job with PID 8413
2019-08-11 02:13:48,154 [salt.state       :915 ][INFO    ][8413] Loading fresh modules for state activity
2019-08-11 02:13:48,193 [salt.fileclient  :1219][INFO    ][8413] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-08-11 02:13:48,223 [salt.state       :1780][INFO    ][8413] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:48.223438
2019-08-11 02:13:48,223 [salt.state       :1813][INFO    ][8413] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-11 02:13:48,225 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8413] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-11 02:13:49,753 [salt.state       :300 ][INFO    ][8413] {'pid': 8426, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-11 02:13:49,754 [salt.state       :1951][INFO    ][8413] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:49.753941 duration_in_ms=1530.503
2019-08-11 02:13:49,757 [salt.state       :1780][INFO    ][8413] Running state [maas.wait_for_machine_status] at time 02:13:49.757264
2019-08-11 02:13:49,757 [salt.state       :1813][INFO    ][8413] Executing state module.run for [maas.wait_for_machine_status]
2019-08-11 02:13:49,758 [salt.utils.decorators:613 ][WARNING ][8413] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-11 02:13:53,348 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.42064595s left)
2019-08-11 02:13:59,442 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811021359429342
2019-08-11 02:13:59,462 [salt.minion      :1432][INFO    ][8438] Starting a new job with PID 8438
2019-08-11 02:13:59,484 [salt.minion      :1711][INFO    ][8438] Returning information for job: 20190811021359429342
2019-08-11 02:14:26,639 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.12986588s left)
2019-08-11 02:14:29,495 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811021429483336
2019-08-11 02:14:29,518 [salt.minion      :1432][INFO    ][8491] Starting a new job with PID 8491
2019-08-11 02:14:29,543 [salt.minion      :1711][INFO    ][8491] Returning information for job: 20190811021429483336
2019-08-11 02:14:59,543 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811021459529359
2019-08-11 02:14:59,565 [salt.minion      :1432][INFO    ][8511] Starting a new job with PID 8511
2019-08-11 02:14:59,589 [salt.minion      :1711][INFO    ][8511] Returning information for job: 20190811021459529359
2019-08-11 02:14:59,986 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.78328204s left)
2019-08-11 02:15:29,590 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811021529578280
2019-08-11 02:15:29,609 [salt.minion      :1432][INFO    ][8653] Starting a new job with PID 8653
2019-08-11 02:15:29,621 [salt.minion      :1711][INFO    ][8653] Returning information for job: 20190811021529578280
2019-08-11 02:15:33,269 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.49994206s left)
2019-08-11 02:15:59,625 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811021559613831
2019-08-11 02:15:59,647 [salt.minion      :1432][INFO    ][8827] Starting a new job with PID 8827
2019-08-11 02:15:59,673 [salt.minion      :1711][INFO    ][8827] Returning information for job: 20190811021559613831
2019-08-11 02:16:06,748 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.02111697s left)
2019-08-11 02:16:29,679 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811021629666667
2019-08-11 02:16:29,702 [salt.minion      :1432][INFO    ][9625] Starting a new job with PID 9625
2019-08-11 02:16:29,724 [salt.minion      :1711][INFO    ][9625] Returning information for job: 20190811021629666667
2019-08-11 02:16:40,535 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.23368406s left)
2019-08-11 02:16:59,735 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811021659721296
2019-08-11 02:16:59,757 [salt.minion      :1432][INFO    ][9645] Starting a new job with PID 9645
2019-08-11 02:16:59,781 [salt.minion      :1711][INFO    ][9645] Returning information for job: 20190811021659721296
2019-08-11 02:17:13,396 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.37293792s left)
2019-08-11 02:17:29,795 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811021729782893
2019-08-11 02:17:29,818 [salt.minion      :1432][INFO    ][9782] Starting a new job with PID 9782
2019-08-11 02:17:29,842 [salt.minion      :1711][INFO    ][9782] Returning information for job: 20190811021729782893
2019-08-11 02:17:47,081 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2012.68806505s left)
2019-08-11 02:17:59,862 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811021759846669
2019-08-11 02:17:59,885 [salt.minion      :1432][INFO    ][9812] Starting a new job with PID 9812
2019-08-11 02:17:59,908 [salt.minion      :1711][INFO    ][9812] Returning information for job: 20190811021759846669
2019-08-11 02:18:19,857 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.91139793s left)
2019-08-11 02:18:29,931 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811021829915299
2019-08-11 02:18:29,955 [salt.minion      :1432][INFO    ][10164] Starting a new job with PID 10164
2019-08-11 02:18:29,979 [salt.minion      :1711][INFO    ][10164] Returning information for job: 20190811021829915299
2019-08-11 02:18:53,374 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.39465594s left)
2019-08-11 02:19:00,006 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811021859993018
2019-08-11 02:19:00,028 [salt.minion      :1432][INFO    ][10195] Starting a new job with PID 10195
2019-08-11 02:19:00,052 [salt.minion      :1711][INFO    ][10195] Returning information for job: 20190811021859993018
2019-08-11 02:19:26,983 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1912.78552389s left)
2019-08-11 02:19:30,083 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811021930071060
2019-08-11 02:19:30,104 [salt.minion      :1432][INFO    ][10519] Starting a new job with PID 10519
2019-08-11 02:19:30,128 [salt.minion      :1711][INFO    ][10519] Returning information for job: 20190811021930071060
2019-08-11 02:20:00,163 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811022000150915
2019-08-11 02:20:00,186 [salt.minion      :1432][INFO    ][10545] Starting a new job with PID 10545
2019-08-11 02:20:00,209 [salt.minion      :1711][INFO    ][10545] Returning information for job: 20190811022000150915
2019-08-11 02:20:00,430 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.33933306s left)
2019-08-11 02:20:30,247 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811022030234703
2019-08-11 02:20:30,270 [salt.minion      :1432][INFO    ][10933] Starting a new job with PID 10933
2019-08-11 02:20:30,294 [salt.minion      :1711][INFO    ][10933] Returning information for job: 20190811022030234703
2019-08-11 02:20:34,006 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1845.76289606s left)
2019-08-11 02:21:00,339 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811022100327348
2019-08-11 02:21:00,362 [salt.minion      :1432][INFO    ][10953] Starting a new job with PID 10953
2019-08-11 02:21:00,386 [salt.minion      :1711][INFO    ][10953] Returning information for job: 20190811022100327348
2019-08-11 02:21:07,480 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.28911185s left)
2019-08-11 02:21:30,441 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811022130428267
2019-08-11 02:21:30,463 [salt.minion      :1432][INFO    ][11010] Starting a new job with PID 11010
2019-08-11 02:21:30,485 [salt.minion      :1711][INFO    ][11010] Returning information for job: 20190811022130428267
2019-08-11 02:21:41,244 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1778.52497602s left)
2019-08-11 02:22:00,550 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811022200538417
2019-08-11 02:22:00,571 [salt.minion      :1432][INFO    ][11124] Starting a new job with PID 11124
2019-08-11 02:22:00,595 [salt.minion      :1711][INFO    ][11124] Returning information for job: 20190811022200538417
2019-08-11 02:22:14,342 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1745.426934s left)
2019-08-11 02:22:30,742 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811022230730679
2019-08-11 02:22:30,762 [salt.minion      :1432][INFO    ][11497] Starting a new job with PID 11497
2019-08-11 02:22:30,784 [salt.minion      :1711][INFO    ][11497] Returning information for job: 20190811022230730679
2019-08-11 02:22:47,743 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1712.02634406s left)
2019-08-11 02:23:00,854 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811022300841470
2019-08-11 02:23:00,877 [salt.minion      :1432][INFO    ][11521] Starting a new job with PID 11521
2019-08-11 02:23:00,902 [salt.minion      :1711][INFO    ][11521] Returning information for job: 20190811022300841470
2019-08-11 02:23:20,727 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1679.04145789s left)
2019-08-11 02:23:30,985 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811022330968825
2019-08-11 02:23:31,008 [salt.minion      :1432][INFO    ][11802] Starting a new job with PID 11802
2019-08-11 02:23:31,032 [salt.minion      :1711][INFO    ][11802] Returning information for job: 20190811022330968825
2019-08-11 02:23:54,267 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1645.50208306s left)
2019-08-11 02:24:01,123 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811022401110447
2019-08-11 02:24:01,147 [salt.minion      :1432][INFO    ][11822] Starting a new job with PID 11822
2019-08-11 02:24:01,172 [salt.minion      :1711][INFO    ][11822] Returning information for job: 20190811022401110447
2019-08-11 02:24:27,671 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1612.09769988s left)
2019-08-11 02:24:31,273 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811022431257868
2019-08-11 02:24:31,296 [salt.minion      :1432][INFO    ][11877] Starting a new job with PID 11877
2019-08-11 02:24:31,319 [salt.minion      :1711][INFO    ][11877] Returning information for job: 20190811022431257868
2019-08-11 02:25:01,193 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1578.57622695s left)
2019-08-11 02:25:01,424 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811022501411597
2019-08-11 02:25:01,447 [salt.minion      :1432][INFO    ][11896] Starting a new job with PID 11896
2019-08-11 02:25:01,470 [salt.minion      :1711][INFO    ][11896] Returning information for job: 20190811022501411597
2019-08-11 02:25:31,583 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811022531570474
2019-08-11 02:25:31,607 [salt.minion      :1432][INFO    ][11947] Starting a new job with PID 11947
2019-08-11 02:25:31,631 [salt.minion      :1711][INFO    ][11947] Returning information for job: 20190811022531570474
2019-08-11 02:25:34,750 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1545.01873493s left)
2019-08-11 02:26:01,755 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811022601743022
2019-08-11 02:26:01,775 [salt.minion      :1432][INFO    ][11966] Starting a new job with PID 11966
2019-08-11 02:26:01,798 [salt.minion      :1711][INFO    ][11966] Returning information for job: 20190811022601743022
2019-08-11 02:26:08,319 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1511.44955397s left)
2019-08-11 02:26:31,937 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811022631925551
2019-08-11 02:26:31,959 [salt.minion      :1432][INFO    ][12028] Starting a new job with PID 12028
2019-08-11 02:26:31,982 [salt.minion      :1711][INFO    ][12028] Returning information for job: 20190811022631925551
2019-08-11 02:26:42,032 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1477.73648787s left)
2019-08-11 02:27:02,125 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811022702113408
2019-08-11 02:27:02,148 [salt.minion      :1432][INFO    ][12056] Starting a new job with PID 12056
2019-08-11 02:27:02,172 [salt.minion      :1711][INFO    ][12056] Returning information for job: 20190811022702113408
2019-08-11 02:27:15,536 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1444.23258686s left)
2019-08-11 02:27:32,328 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811022732315919
2019-08-11 02:27:32,351 [salt.minion      :1432][INFO    ][12113] Starting a new job with PID 12113
2019-08-11 02:27:32,375 [salt.minion      :1711][INFO    ][12113] Returning information for job: 20190811022732315919
2019-08-11 02:27:49,225 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1410.54363203s left)
2019-08-11 02:28:02,540 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811022802528442
2019-08-11 02:28:02,564 [salt.minion      :1432][INFO    ][12134] Starting a new job with PID 12134
2019-08-11 02:28:02,589 [salt.minion      :1711][INFO    ][12134] Returning information for job: 20190811022802528442
2019-08-11 02:28:22,711 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1377.05753589s left)
2019-08-11 02:28:32,765 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811022832752121
2019-08-11 02:28:32,788 [salt.minion      :1432][INFO    ][12337] Starting a new job with PID 12337
2019-08-11 02:28:32,810 [salt.minion      :1711][INFO    ][12337] Returning information for job: 20190811022832752121
2019-08-11 02:28:55,641 [salt.loaded.ext.module.maas:1023][INFO    ][8413] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1344.12790203s left)
2019-08-11 02:29:02,784 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811022902771232
2019-08-11 02:29:02,806 [salt.minion      :1432][INFO    ][12362] Starting a new job with PID 12362
2019-08-11 02:29:02,831 [salt.minion      :1711][INFO    ][12362] Returning information for job: 20190811022902771232
2019-08-11 02:29:28,627 [salt.loaded.ext.module.maas:993 ][INFO    ][8413] Machine w8pcfb mark broken
2019-08-11 02:29:29,379 [salt.loaded.ext.module.maas:996 ][INFO    ][8413] Machine w8pcfb mark fixed
2019-08-11 02:29:30,521 [salt.loaded.ext.module.maas:684 ][INFO    ][8413] deploymachines hwe_kernel=ga-16.04 system_id=w8pcfb distro_series=xenial
2019-08-11 02:29:32,818 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811022932807683
2019-08-11 02:29:32,841 [salt.minion      :1432][INFO    ][12469] Starting a new job with PID 12469
2019-08-11 02:29:32,865 [salt.minion      :1711][INFO    ][12469] Returning information for job: 20190811022932807683
2019-08-11 02:29:33,359 [salt.loaded.ext.module.maas:160 ][ERROR   ][8413] 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-08-11 02:29:33,360 [salt.state       :302 ][ERROR   ][8413] 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-08-11 02:29:33,361 [salt.state       :1951][INFO    ][8413] Completed state [maas.wait_for_machine_status] at time 02:29:33.361301 duration_in_ms=943604.032
2019-08-11 02:29:33,368 [salt.minion      :1711][INFO    ][8413] Returning information for job: 20190811021344329615
2019-08-11 02:29:44,128 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command pillar.get with jid 20190811022944115784
2019-08-11 02:29:44,148 [salt.minion      :1432][INFO    ][12503] Starting a new job with PID 12503
2019-08-11 02:29:44,156 [salt.minion      :1711][INFO    ][12503] Returning information for job: 20190811022944115784
2019-08-11 02:29:44,654 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command service.status with jid 20190811022944640750
2019-08-11 02:29:44,675 [salt.minion      :1432][INFO    ][12508] Starting a new job with PID 12508
2019-08-11 02:29:45,128 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12508] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-08-11 02:29:45,164 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12508] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-08-11 02:29:45,180 [salt.minion      :1711][INFO    ][12508] Returning information for job: 20190811022944640750
2019-08-11 02:29:45,720 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command state.apply with jid 20190811022945712909
2019-08-11 02:29:45,741 [salt.minion      :1432][INFO    ][12519] Starting a new job with PID 12519
2019-08-11 02:29:49,570 [salt.state       :915 ][INFO    ][12519] Loading fresh modules for state activity
2019-08-11 02:29:50,031 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12519] Executing command 'salt-minion --version' in directory '/root'
2019-08-11 02:29:50,349 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12519] Executing command 'salt-minion --version' in directory '/root'
2019-08-11 02:29:51,304 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12519] Executing command 'salt-minion --version' in directory '/root'
2019-08-11 02:29:51,674 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12519] Executing command 'salt-minion --version' in directory '/root'
2019-08-11 02:29:53,095 [salt.state       :1780][INFO    ][12519] Running state [salt-minion] at time 02:29:53.095228
2019-08-11 02:29:53,095 [salt.state       :1813][INFO    ][12519] Executing state pkg.installed for [salt-minion]
2019-08-11 02:29:53,096 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12519] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-11 02:29:53,178 [salt.state       :300 ][INFO    ][12519] All specified packages are already installed
2019-08-11 02:29:53,178 [salt.state       :1951][INFO    ][12519] Completed state [salt-minion] at time 02:29:53.178600 duration_in_ms=83.373
2019-08-11 02:29:53,178 [salt.state       :1780][INFO    ][12519] Running state [salt_minion_dependency_packages] at time 02:29:53.178889
2019-08-11 02:29:53,179 [salt.state       :1813][INFO    ][12519] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-08-11 02:29:53,184 [salt.state       :300 ][INFO    ][12519] All specified packages are already installed
2019-08-11 02:29:53,185 [salt.state       :1951][INFO    ][12519] Completed state [salt_minion_dependency_packages] at time 02:29:53.184974 duration_in_ms=6.085
2019-08-11 02:29:53,187 [salt.state       :1780][INFO    ][12519] Running state [/etc/salt/minion.d/minion.conf] at time 02:29:53.187708
2019-08-11 02:29:53,188 [salt.state       :1813][INFO    ][12519] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-08-11 02:29:53,386 [salt.state       :300 ][INFO    ][12519] File /etc/salt/minion.d/minion.conf is in the correct state
2019-08-11 02:29:53,386 [salt.state       :1951][INFO    ][12519] Completed state [/etc/salt/minion.d/minion.conf] at time 02:29:53.386923 duration_in_ms=199.215
2019-08-11 02:29:53,387 [salt.state       :1780][INFO    ][12519] Running state [python-netaddr] at time 02:29:53.387127
2019-08-11 02:29:53,387 [salt.state       :1813][INFO    ][12519] Executing state pkg.installed for [python-netaddr]
2019-08-11 02:29:53,392 [salt.state       :300 ][INFO    ][12519] All specified packages are already installed
2019-08-11 02:29:53,392 [salt.state       :1951][INFO    ][12519] Completed state [python-netaddr] at time 02:29:53.392521 duration_in_ms=5.394
2019-08-11 02:29:53,394 [salt.state       :1780][INFO    ][12519] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:29:53.394876
2019-08-11 02:29:53,395 [salt.state       :1813][INFO    ][12519] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-08-11 02:29:53,404 [salt.state       :300 ][INFO    ][12519] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-08-11 02:29:53,404 [salt.state       :1951][INFO    ][12519] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:29:53.404269 duration_in_ms=9.392
2019-08-11 02:29:53,405 [salt.state       :1780][INFO    ][12519] Running state [salt-minion] at time 02:29:53.404984
2019-08-11 02:29:53,405 [salt.state       :1813][INFO    ][12519] Executing state service.running for [salt-minion]
2019-08-11 02:29:53,405 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12519] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-08-11 02:29:53,441 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12519] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-08-11 02:29:53,458 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12519] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-08-11 02:29:53,474 [salt.state       :300 ][INFO    ][12519] The service salt-minion is already running
2019-08-11 02:29:53,474 [salt.state       :1951][INFO    ][12519] Completed state [salt-minion] at time 02:29:53.474520 duration_in_ms=69.536
2019-08-11 02:29:53,476 [salt.state       :1780][INFO    ][12519] Running state [/etc/salt/grains.d] at time 02:29:53.475992
2019-08-11 02:29:53,476 [salt.state       :1813][INFO    ][12519] Executing state file.directory for [/etc/salt/grains.d]
2019-08-11 02:29:53,477 [salt.state       :300 ][INFO    ][12519] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-08-11 02:29:53,477 [salt.state       :1951][INFO    ][12519] Completed state [/etc/salt/grains.d] at time 02:29:53.477466 duration_in_ms=1.473
2019-08-11 02:29:53,478 [salt.state       :1780][INFO    ][12519] Running state [/etc/salt/grains] at time 02:29:53.478129
2019-08-11 02:29:53,478 [salt.state       :1813][INFO    ][12519] Executing state file.managed for [/etc/salt/grains]
2019-08-11 02:29:53,478 [salt.state       :300 ][INFO    ][12519] File /etc/salt/grains exists with proper permissions. No changes made.
2019-08-11 02:29:53,479 [salt.state       :1951][INFO    ][12519] Completed state [/etc/salt/grains] at time 02:29:53.479177 duration_in_ms=1.048
2019-08-11 02:29:53,479 [salt.state       :1780][INFO    ][12519] Running state [/etc/salt/grains.d/placeholder] at time 02:29:53.479637
2019-08-11 02:29:53,479 [salt.state       :1813][INFO    ][12519] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-08-11 02:29:53,480 [salt.state       :300 ][INFO    ][12519] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-08-11 02:29:53,480 [salt.state       :1951][INFO    ][12519] Completed state [/etc/salt/grains.d/placeholder] at time 02:29:53.480648 duration_in_ms=1.012
2019-08-11 02:29:53,481 [salt.state       :1780][INFO    ][12519] Running state [/etc/salt/grains.d/sphinx] at time 02:29:53.481090
2019-08-11 02:29:53,481 [salt.state       :1813][INFO    ][12519] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-08-11 02:29:53,494 [salt.state       :300 ][INFO    ][12519] File /etc/salt/grains.d/sphinx is in the correct state
2019-08-11 02:29:53,494 [salt.state       :1951][INFO    ][12519] Completed state [/etc/salt/grains.d/sphinx] at time 02:29:53.494632 duration_in_ms=13.542
2019-08-11 02:29:53,496 [salt.state       :1780][INFO    ][12519] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:29:53.496843
2019-08-11 02:29:53,497 [salt.state       :1813][INFO    ][12519] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-08-11 02:29:53,497 [salt.state       :300 ][INFO    ][12519] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-08-11 02:29:53,497 [salt.state       :1951][INFO    ][12519] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:29:53.497716 duration_in_ms=0.873
2019-08-11 02:29:53,498 [salt.state       :1780][INFO    ][12519] Running state [/etc/salt/grains.d/dns_records] at time 02:29:53.498177
2019-08-11 02:29:53,498 [salt.state       :1813][INFO    ][12519] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-08-11 02:29:53,512 [salt.state       :300 ][INFO    ][12519] File /etc/salt/grains.d/dns_records is in the correct state
2019-08-11 02:29:53,512 [salt.state       :1951][INFO    ][12519] Completed state [/etc/salt/grains.d/dns_records] at time 02:29:53.512580 duration_in_ms=14.403
2019-08-11 02:29:53,513 [salt.state       :1780][INFO    ][12519] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:29:53.513470
2019-08-11 02:29:53,513 [salt.state       :1813][INFO    ][12519] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-08-11 02:29:53,514 [salt.state       :300 ][INFO    ][12519] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-08-11 02:29:53,514 [salt.state       :1951][INFO    ][12519] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:29:53.514318 duration_in_ms=0.849
2019-08-11 02:29:53,514 [salt.state       :1780][INFO    ][12519] Running state [/etc/salt/grains.d/salt] at time 02:29:53.514796
2019-08-11 02:29:53,515 [salt.state       :1813][INFO    ][12519] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-08-11 02:29:53,524 [salt.state       :300 ][INFO    ][12519] File /etc/salt/grains.d/salt is in the correct state
2019-08-11 02:29:53,524 [salt.state       :1951][INFO    ][12519] Completed state [/etc/salt/grains.d/salt] at time 02:29:53.524627 duration_in_ms=9.831
2019-08-11 02:29:53,525 [salt.state       :1780][INFO    ][12519] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:29:53.525524
2019-08-11 02:29:53,525 [salt.state       :1813][INFO    ][12519] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-08-11 02:29:53,526 [salt.state       :300 ][INFO    ][12519] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-08-11 02:29:53,526 [salt.state       :1951][INFO    ][12519] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:29:53.526389 duration_in_ms=0.865
2019-08-11 02:29:53,528 [salt.state       :1780][INFO    ][12519] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:29:53.528281
2019-08-11 02:29:53,528 [salt.state       :1813][INFO    ][12519] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-08-11 02:29:53,528 [salt.state       :300 ][INFO    ][12519] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-08-11 02:29:53,529 [salt.state       :1951][INFO    ][12519] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:29:53.529182 duration_in_ms=0.901
2019-08-11 02:29:53,529 [salt.state       :1780][INFO    ][12519] Running state [mine.update] at time 02:29:53.529869
2019-08-11 02:29:53,530 [salt.state       :1813][INFO    ][12519] Executing state module.wait for [mine.update]
2019-08-11 02:29:53,530 [salt.state       :300 ][INFO    ][12519] No changes made for mine.update
2019-08-11 02:29:53,530 [salt.state       :1951][INFO    ][12519] Completed state [mine.update] at time 02:29:53.530703 duration_in_ms=0.834
2019-08-11 02:29:53,530 [salt.state       :1780][INFO    ][12519] Running state [ca-certificates] at time 02:29:53.530951
2019-08-11 02:29:53,531 [salt.state       :1813][INFO    ][12519] Executing state pkg.installed for [ca-certificates]
2019-08-11 02:29:53,538 [salt.state       :300 ][INFO    ][12519] All specified packages are already installed
2019-08-11 02:29:53,538 [salt.state       :1951][INFO    ][12519] Completed state [ca-certificates] at time 02:29:53.538818 duration_in_ms=7.866
2019-08-11 02:29:53,539 [salt.state       :1780][INFO    ][12519] Running state [update-ca-certificates] at time 02:29:53.539501
2019-08-11 02:29:53,539 [salt.state       :1813][INFO    ][12519] Executing state cmd.wait for [update-ca-certificates]
2019-08-11 02:29:53,540 [salt.state       :300 ][INFO    ][12519] No changes made for update-ca-certificates
2019-08-11 02:29:53,540 [salt.state       :1951][INFO    ][12519] Completed state [update-ca-certificates] at time 02:29:53.540304 duration_in_ms=0.804
2019-08-11 02:29:53,540 [salt.state       :1780][INFO    ][12519] Running state [iptables] at time 02:29:53.540557
2019-08-11 02:29:53,540 [salt.state       :1813][INFO    ][12519] Executing state pkg.installed for [iptables]
2019-08-11 02:29:53,547 [salt.state       :300 ][INFO    ][12519] All specified packages are already installed
2019-08-11 02:29:53,547 [salt.state       :1951][INFO    ][12519] Completed state [iptables] at time 02:29:53.547718 duration_in_ms=7.161
2019-08-11 02:29:53,548 [salt.state       :1780][INFO    ][12519] Running state [iptables-persistent] at time 02:29:53.547971
2019-08-11 02:29:53,548 [salt.state       :1813][INFO    ][12519] Executing state pkg.installed for [iptables-persistent]
2019-08-11 02:29:53,554 [salt.state       :300 ][INFO    ][12519] All specified packages are already installed
2019-08-11 02:29:53,554 [salt.state       :1951][INFO    ][12519] Completed state [iptables-persistent] at time 02:29:53.554864 duration_in_ms=6.893
2019-08-11 02:29:53,555 [salt.state       :1780][INFO    ][12519] Running state [iptables_modules_v4_load] at time 02:29:53.555835
2019-08-11 02:29:53,556 [salt.state       :1813][INFO    ][12519] Executing state kmod.present for [iptables_modules_v4_load]
2019-08-11 02:29:53,556 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12519] Executing command 'lsmod' in directory '/root'
2019-08-11 02:29:53,577 [salt.state       :300 ][INFO    ][12519] Kernel modules iptable_filter, ip_tables are already present
2019-08-11 02:29:53,578 [salt.state       :1951][INFO    ][12519] Completed state [iptables_modules_v4_load] at time 02:29:53.578278 duration_in_ms=22.443
2019-08-11 02:29:53,578 [salt.state       :1780][INFO    ][12519] Running state [/etc/iptables/rules.v4] at time 02:29:53.578900
2019-08-11 02:29:53,579 [salt.state       :1813][INFO    ][12519] Executing state file.managed for [/etc/iptables/rules.v4]
2019-08-11 02:29:53,662 [salt.state       :300 ][INFO    ][12519] File /etc/iptables/rules.v4 is in the correct state
2019-08-11 02:29:53,662 [salt.state       :1951][INFO    ][12519] Completed state [/etc/iptables/rules.v4] at time 02:29:53.662388 duration_in_ms=83.488
2019-08-11 02:29:53,663 [salt.state       :1780][INFO    ][12519] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:29:53.663304
2019-08-11 02:29:53,663 [salt.state       :1813][INFO    ][12519] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-08-11 02:29:53,664 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12519] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-08-11 02:29:53,682 [salt.state       :300 ][INFO    ][12519] onlyif execution failed
2019-08-11 02:29:53,682 [salt.state       :1951][INFO    ][12519] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:29:53.682413 duration_in_ms=19.109
2019-08-11 02:29:53,683 [salt.state       :1780][INFO    ][12519] Running state [netfilter-persistent] at time 02:29:53.683312
2019-08-11 02:29:53,683 [salt.state       :1813][INFO    ][12519] Executing state service.running for [netfilter-persistent]
2019-08-11 02:29:53,684 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12519] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-08-11 02:29:53,703 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12519] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-08-11 02:29:53,720 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12519] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-08-11 02:29:53,738 [salt.state       :300 ][INFO    ][12519] The service netfilter-persistent is already running
2019-08-11 02:29:53,738 [salt.state       :1951][INFO    ][12519] Completed state [netfilter-persistent] at time 02:29:53.738387 duration_in_ms=55.074
2019-08-11 02:29:53,739 [salt.state       :1780][INFO    ][12519] Running state [iptables_extra.remove_stale_tables] at time 02:29:53.739297
2019-08-11 02:29:53,739 [salt.state       :1813][INFO    ][12519] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-08-11 02:29:53,740 [salt.state       :300 ][INFO    ][12519] No changes made for iptables_extra.remove_stale_tables
2019-08-11 02:29:53,740 [salt.state       :1951][INFO    ][12519] Completed state [iptables_extra.remove_stale_tables] at time 02:29:53.740285 duration_in_ms=0.988
2019-08-11 02:29:53,740 [salt.state       :1780][INFO    ][12519] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:29:53.740580
2019-08-11 02:29:53,740 [salt.state       :1813][INFO    ][12519] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-08-11 02:29:53,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12519] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-08-11 02:29:53,756 [salt.state       :300 ][INFO    ][12519] onlyif execution failed
2019-08-11 02:29:53,757 [salt.state       :1951][INFO    ][12519] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:29:53.757058 duration_in_ms=16.477
2019-08-11 02:29:53,758 [salt.state       :1780][INFO    ][12519] Running state [/etc/iptables/rules.v6] at time 02:29:53.758679
2019-08-11 02:29:53,759 [salt.state       :1813][INFO    ][12519] Executing state file.absent for [/etc/iptables/rules.v6]
2019-08-11 02:29:53,760 [salt.state       :300 ][INFO    ][12519] File /etc/iptables/rules.v6 is not present
2019-08-11 02:29:53,760 [salt.state       :1951][INFO    ][12519] Completed state [/etc/iptables/rules.v6] at time 02:29:53.760381 duration_in_ms=1.702
2019-08-11 02:29:53,761 [salt.state       :1780][INFO    ][12519] Running state [iptables_extra.flush_all] at time 02:29:53.761515
2019-08-11 02:29:53,762 [salt.state       :1813][INFO    ][12519] Executing state module.wait for [iptables_extra.flush_all]
2019-08-11 02:29:53,762 [salt.state       :300 ][INFO    ][12519] No changes made for iptables_extra.flush_all
2019-08-11 02:29:53,762 [salt.state       :1951][INFO    ][12519] Completed state [iptables_extra.flush_all] at time 02:29:53.762814 duration_in_ms=1.299
2019-08-11 02:29:53,767 [salt.minion      :1711][INFO    ][12519] Returning information for job: 20190811022945712909
2019-08-11 02:29:54,407 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command state.apply with jid 20190811022954393865
2019-08-11 02:29:54,430 [salt.minion      :1432][INFO    ][12594] Starting a new job with PID 12594
2019-08-11 02:29:55,251 [salt.state       :915 ][INFO    ][12594] Loading fresh modules for state activity
2019-08-11 02:29:55,884 [salt.state       :1780][INFO    ][12594] Running state [maas-rack-controller] at time 02:29:55.884639
2019-08-11 02:29:55,885 [salt.state       :1813][INFO    ][12594] Executing state pkg.installed for [maas-rack-controller]
2019-08-11 02:29:55,885 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12594] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-11 02:29:55,960 [salt.state       :300 ][INFO    ][12594] All specified packages are already installed
2019-08-11 02:29:55,961 [salt.state       :1951][INFO    ][12594] Completed state [maas-rack-controller] at time 02:29:55.961199 duration_in_ms=76.56
2019-08-11 02:29:55,961 [salt.state       :1780][INFO    ][12594] Running state [ipmitool] at time 02:29:55.961450
2019-08-11 02:29:55,961 [salt.state       :1813][INFO    ][12594] Executing state pkg.installed for [ipmitool]
2019-08-11 02:29:55,966 [salt.state       :300 ][INFO    ][12594] All specified packages are already installed
2019-08-11 02:29:55,966 [salt.state       :1951][INFO    ][12594] Completed state [ipmitool] at time 02:29:55.966242 duration_in_ms=4.793
2019-08-11 02:29:55,968 [salt.state       :1780][INFO    ][12594] Running state [/etc/maas/rackd.conf] at time 02:29:55.968515
2019-08-11 02:29:55,968 [salt.state       :1813][INFO    ][12594] Executing state file.line for [/etc/maas/rackd.conf]
2019-08-11 02:29:55,969 [salt.state       :300 ][INFO    ][12594] No changes needed to be made
2019-08-11 02:29:55,969 [salt.state       :1951][INFO    ][12594] Completed state [/etc/maas/rackd.conf] at time 02:29:55.969651 duration_in_ms=1.136
2019-08-11 02:29:55,969 [salt.state       :1780][INFO    ][12594] Running state [/etc/maas/rackd.conf] at time 02:29:55.969815
2019-08-11 02:29:55,969 [salt.state       :1813][INFO    ][12594] Executing state file.managed for [/etc/maas/rackd.conf]
2019-08-11 02:29:55,970 [salt.loaded.int.states.file:2298][WARNING ][12594] 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-08-11 02:29:55,970 [salt.state       :300 ][INFO    ][12594] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-08-11 02:29:55,970 [salt.state       :1951][INFO    ][12594] Completed state [/etc/maas/rackd.conf] at time 02:29:55.970704 duration_in_ms=0.889
2019-08-11 02:29:55,971 [salt.state       :1780][INFO    ][12594] Running state [maas-rackd] at time 02:29:55.971387
2019-08-11 02:29:55,971 [salt.state       :1813][INFO    ][12594] Executing state service.running for [maas-rackd]
2019-08-11 02:29:55,972 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12594] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-08-11 02:29:56,004 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12594] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-08-11 02:29:56,020 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12594] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-08-11 02:29:56,035 [salt.state       :300 ][INFO    ][12594] The service maas-rackd is already running
2019-08-11 02:29:56,036 [salt.state       :1951][INFO    ][12594] Completed state [maas-rackd] at time 02:29:56.036100 duration_in_ms=64.712
2019-08-11 02:29:56,037 [salt.minion      :1711][INFO    ][12594] Returning information for job: 20190811022954393865
2019-08-11 02:29:56,611 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command state.apply with jid 20190811022956597476
2019-08-11 02:29:56,634 [salt.minion      :1432][INFO    ][12618] Starting a new job with PID 12618
2019-08-11 02:29:57,434 [salt.state       :915 ][INFO    ][12618] Loading fresh modules for state activity
2019-08-11 02:29:58,206 [salt.state       :1780][INFO    ][12618] Running state [maas-region-controller] at time 02:29:58.206714
2019-08-11 02:29:58,207 [salt.state       :1813][INFO    ][12618] Executing state pkg.installed for [maas-region-controller]
2019-08-11 02:29:58,208 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12618] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-11 02:29:58,306 [salt.state       :300 ][INFO    ][12618] All specified packages are already installed
2019-08-11 02:29:58,306 [salt.state       :1951][INFO    ][12618] Completed state [maas-region-controller] at time 02:29:58.306696 duration_in_ms=99.984
2019-08-11 02:29:58,307 [salt.state       :1780][INFO    ][12618] Running state [python-oauth] at time 02:29:58.307003
2019-08-11 02:29:58,307 [salt.state       :1813][INFO    ][12618] Executing state pkg.installed for [python-oauth]
2019-08-11 02:29:58,313 [salt.state       :300 ][INFO    ][12618] All specified packages are already installed
2019-08-11 02:29:58,313 [salt.state       :1951][INFO    ][12618] Completed state [python-oauth] at time 02:29:58.313458 duration_in_ms=6.455
2019-08-11 02:29:58,316 [salt.state       :1780][INFO    ][12618] Running state [/etc/maas/regiond.conf] at time 02:29:58.316345
2019-08-11 02:29:58,316 [salt.state       :1813][INFO    ][12618] Executing state file.replace for [/etc/maas/regiond.conf]
2019-08-11 02:29:58,393 [salt.state       :300 ][INFO    ][12618] No changes needed to be made
2019-08-11 02:29:58,393 [salt.state       :1951][INFO    ][12618] Completed state [/etc/maas/regiond.conf] at time 02:29:58.393858 duration_in_ms=77.512
2019-08-11 02:29:58,394 [salt.state       :1780][INFO    ][12618] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:29:58.394652
2019-08-11 02:29:58,395 [salt.state       :1813][INFO    ][12618] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-08-11 02:29:58,476 [salt.state       :300 ][INFO    ][12618] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-08-11 02:29:58,477 [salt.state       :1951][INFO    ][12618] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:29:58.477187 duration_in_ms=82.536
2019-08-11 02:29:58,477 [salt.state       :1780][INFO    ][12618] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:29:58.477885
2019-08-11 02:29:58,478 [salt.state       :1813][INFO    ][12618] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-08-11 02:29:58,495 [salt.state       :300 ][INFO    ][12618] No changes needed to be made
2019-08-11 02:29:58,495 [salt.state       :1951][INFO    ][12618] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:29:58.495857 duration_in_ms=17.972
2019-08-11 02:29:58,496 [salt.state       :1780][INFO    ][12618] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:29:58.496205
2019-08-11 02:29:58,496 [salt.state       :1813][INFO    ][12618] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-08-11 02:29:58,522 [salt.state       :300 ][INFO    ][12618] No changes needed to be made
2019-08-11 02:29:58,522 [salt.state       :1951][INFO    ][12618] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:29:58.522896 duration_in_ms=26.691
2019-08-11 02:29:58,523 [salt.state       :1780][INFO    ][12618] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:29:58.523255
2019-08-11 02:29:58,523 [salt.state       :1813][INFO    ][12618] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-08-11 02:29:58,542 [salt.state       :300 ][INFO    ][12618] No changes needed to be made
2019-08-11 02:29:58,542 [salt.state       :1951][INFO    ][12618] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:29:58.542549 duration_in_ms=19.294
2019-08-11 02:29:58,542 [salt.state       :1780][INFO    ][12618] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:29:58.542896
2019-08-11 02:29:58,543 [salt.state       :1813][INFO    ][12618] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-08-11 02:29:58,551 [salt.state       :300 ][INFO    ][12618] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-08-11 02:29:58,552 [salt.state       :1951][INFO    ][12618] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:29:58.551987 duration_in_ms=9.092
2019-08-11 02:29:58,554 [salt.state       :1780][INFO    ][12618] Running state [a2enmod headers] at time 02:29:58.554189
2019-08-11 02:29:58,554 [salt.state       :1813][INFO    ][12618] Executing state cmd.run for [a2enmod headers]
2019-08-11 02:29:58,554 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12618] Executing command 'a2enmod headers' in directory '/root'
2019-08-11 02:29:58,599 [salt.state       :300 ][INFO    ][12618] {'pid': 12637, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-08-11 02:29:58,599 [salt.state       :1951][INFO    ][12618] Completed state [a2enmod headers] at time 02:29:58.599841 duration_in_ms=45.651
2019-08-11 02:29:58,600 [salt.state       :1780][INFO    ][12618] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:29:58.600121
2019-08-11 02:29:58,600 [salt.state       :1813][INFO    ][12618] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-08-11 02:29:58,612 [salt.state       :300 ][INFO    ][12618] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-08-11 02:29:58,613 [salt.state       :1951][INFO    ][12618] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:29:58.613073 duration_in_ms=12.952
2019-08-11 02:29:58,613 [salt.state       :1780][INFO    ][12618] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:29:58.613579
2019-08-11 02:29:58,613 [salt.state       :1813][INFO    ][12618] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-08-11 02:29:58,678 [salt.state       :300 ][INFO    ][12618] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-08-11 02:29:58,678 [salt.state       :1951][INFO    ][12618] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:29:58.678364 duration_in_ms=64.784
2019-08-11 02:29:58,679 [salt.state       :1780][INFO    ][12618] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:29:58.679101
2019-08-11 02:29:58,679 [salt.state       :1813][INFO    ][12618] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-08-11 02:29:58,737 [salt.state       :300 ][INFO    ][12618] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-08-11 02:29:58,737 [salt.state       :1951][INFO    ][12618] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:29:58.737886 duration_in_ms=58.785
2019-08-11 02:29:58,738 [salt.state       :1780][INFO    ][12618] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:29:58.738414
2019-08-11 02:29:58,738 [salt.state       :1813][INFO    ][12618] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-08-11 02:29:58,797 [salt.state       :300 ][INFO    ][12618] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-08-11 02:29:58,797 [salt.state       :1951][INFO    ][12618] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:29:58.797539 duration_in_ms=59.125
2019-08-11 02:29:58,797 [salt.state       :1780][INFO    ][12618] Running state [/root/.pgpass] at time 02:29:58.797800
2019-08-11 02:29:58,798 [salt.state       :1813][INFO    ][12618] Executing state file.managed for [/root/.pgpass]
2019-08-11 02:29:58,833 [salt.state       :300 ][INFO    ][12618] File /root/.pgpass is in the correct state
2019-08-11 02:29:58,833 [salt.state       :1951][INFO    ][12618] Completed state [/root/.pgpass] at time 02:29:58.833373 duration_in_ms=35.572
2019-08-11 02:29:58,837 [salt.state       :1780][INFO    ][12618] Running state [maas-region syncdb --noinput] at time 02:29:58.836973
2019-08-11 02:29:58,837 [salt.state       :1813][INFO    ][12618] Executing state cmd.run for [maas-region syncdb --noinput]
2019-08-11 02:29:58,837 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12618] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-08-11 02:30:00,656 [salt.state       :300 ][INFO    ][12618] {'pid': 12650, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: maasserver, sessions, piston3, metadataserver, sites, auth, contenttypes\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-08-11 02:30:00,657 [salt.state       :1951][INFO    ][12618] Completed state [maas-region syncdb --noinput] at time 02:30:00.657179 duration_in_ms=1820.204
2019-08-11 02:30:00,657 [salt.state       :2022][WARNING ][12618] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-08-11 02:30:00,660 [salt.state       :1780][INFO    ][12618] Running state [maas-regiond] at time 02:30:00.660389
2019-08-11 02:30:00,660 [salt.state       :1813][INFO    ][12618] Executing state service.running for [maas-regiond]
2019-08-11 02:30:00,662 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12618] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-08-11 02:30:00,700 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12618] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-08-11 02:30:00,717 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12618] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-08-11 02:30:00,732 [salt.state       :300 ][INFO    ][12618] The service maas-regiond is already running
2019-08-11 02:30:00,733 [salt.state       :1951][INFO    ][12618] Completed state [maas-regiond] at time 02:30:00.732994 duration_in_ms=72.605
2019-08-11 02:30:00,735 [salt.state       :1780][INFO    ][12618] Running state [bind9] at time 02:30:00.735410
2019-08-11 02:30:00,735 [salt.state       :1813][INFO    ][12618] Executing state service.running for [bind9]
2019-08-11 02:30:00,736 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12618] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-08-11 02:30:00,752 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12618] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-08-11 02:30:00,768 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12618] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-08-11 02:30:00,783 [salt.state       :300 ][INFO    ][12618] The service bind9 is already running
2019-08-11 02:30:00,783 [salt.state       :1951][INFO    ][12618] Completed state [bind9] at time 02:30:00.783889 duration_in_ms=48.478
2019-08-11 02:30:00,786 [salt.state       :1780][INFO    ][12618] Running state [apache2] at time 02:30:00.786113
2019-08-11 02:30:00,786 [salt.state       :1813][INFO    ][12618] Executing state service.running for [apache2]
2019-08-11 02:30:00,787 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12618] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-08-11 02:30:00,802 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12618] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-08-11 02:30:00,816 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12618] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-08-11 02:30:00,834 [salt.state       :300 ][INFO    ][12618] The service apache2 is already running
2019-08-11 02:30:00,835 [salt.state       :1951][INFO    ][12618] Completed state [apache2] at time 02:30:00.835316 duration_in_ms=49.203
2019-08-11 02:30:00,837 [salt.state       :1780][INFO    ][12618] Running state [maasng.wait_for_http_code] at time 02:30:00.837034
2019-08-11 02:30:00,837 [salt.state       :1813][INFO    ][12618] Executing state module.run for [maasng.wait_for_http_code]
2019-08-11 02:30:00,837 [salt.utils.decorators:613 ][WARNING ][12618] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-11 02:30:00,845 [salt.state       :300 ][INFO    ][12618] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-08-11 02:30:00,845 [salt.state       :1951][INFO    ][12618] Completed state [maasng.wait_for_http_code] at time 02:30:00.845411 duration_in_ms=8.378
2019-08-11 02:30:00,846 [salt.state       :1780][INFO    ][12618] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:30:00.846649
2019-08-11 02:30:00,847 [salt.state       :1813][INFO    ][12618] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-08-11 02:30:00,847 [salt.state       :300 ][INFO    ][12618] /var/lib/maas/.setup_admin exists
2019-08-11 02:30:00,848 [salt.state       :1951][INFO    ][12618] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:30:00.848074 duration_in_ms=1.425
2019-08-11 02:30:00,849 [salt.state       :1780][INFO    ][12618] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:30:00.849151
2019-08-11 02:30:00,849 [salt.state       :1813][INFO    ][12618] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-11 02:30:00,850 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12618] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-11 02:30:02,330 [salt.state       :300 ][INFO    ][12618] {'pid': 12671, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-11 02:30:02,331 [salt.state       :1951][INFO    ][12618] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:30:02.331776 duration_in_ms=1482.625
2019-08-11 02:30:02,340 [salt.state       :1780][INFO    ][12618] Running state [maas_region_boot_source_resources_mirror] at time 02:30:02.340254
2019-08-11 02:30:02,340 [salt.state       :1813][INFO    ][12618] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-08-11 02:30:02,443 [salt.state       :300 ][INFO    ][12618] {'changes': {}}
2019-08-11 02:30:02,443 [salt.state       :1951][INFO    ][12618] Completed state [maas_region_boot_source_resources_mirror] at time 02:30:02.443857 duration_in_ms=103.603
2019-08-11 02:30:02,445 [salt.state       :1780][INFO    ][12618] Running state [maasng.boot_resources_import] at time 02:30:02.445032
2019-08-11 02:30:02,445 [salt.state       :1813][INFO    ][12618] Executing state module.run for [maasng.boot_resources_import]
2019-08-11 02:30:02,446 [salt.utils.decorators:613 ][WARNING ][12618] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-11 02:30:02,595 [salt.loaded.ext.module.maasng:1600][INFO    ][12618] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-08-11 02:30:07,661 [salt.loaded.ext.module.maasng:1600][INFO    ][12618] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-08-11 02:30:11,703 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811023011690830
2019-08-11 02:30:11,726 [salt.minion      :1432][INFO    ][12712] Starting a new job with PID 12712
2019-08-11 02:30:11,747 [salt.minion      :1711][INFO    ][12712] Returning information for job: 20190811023011690830
2019-08-11 02:30:12,716 [salt.loaded.ext.module.maasng:1600][INFO    ][12618] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-08-11 02:30:17,829 [salt.state       :300 ][INFO    ][12618] {'ret': True}
2019-08-11 02:30:17,830 [salt.state       :1951][INFO    ][12618] Completed state [maasng.boot_resources_import] at time 02:30:17.830307 duration_in_ms=15385.274
2019-08-11 02:30:17,831 [salt.state       :1780][INFO    ][12618] Running state [maas_region_boot_sources_selection_xenial] at time 02:30:17.831311
2019-08-11 02:30:17,831 [salt.state       :1813][INFO    ][12618] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-08-11 02:30:18,021 [salt.state       :300 ][INFO    ][12618] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-08-11 02:30:18,021 [salt.state       :1951][INFO    ][12618] Completed state [maas_region_boot_sources_selection_xenial] at time 02:30:18.021604 duration_in_ms=190.293
2019-08-11 02:30:18,022 [salt.state       :1780][INFO    ][12618] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:30:18.022823
2019-08-11 02:30:18,023 [salt.state       :1813][INFO    ][12618] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-08-11 02:30:18,023 [salt.utils.decorators:613 ][WARNING ][12618] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-11 02:30:18,024 [salt.loaded.ext.module.maasng:1771][INFO    ][12618] boot-sources sync initiated for ALL Rack's
2019-08-11 02:30:18,991 [salt.state       :300 ][INFO    ][12618] {'ret': True}
2019-08-11 02:30:18,991 [salt.state       :1951][INFO    ][12618] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:30:18.991441 duration_in_ms=968.618
2019-08-11 02:30:18,993 [salt.state       :1780][INFO    ][12618] Running state [maas.process_maas_config] at time 02:30:18.993559
2019-08-11 02:30:18,994 [salt.state       :1813][INFO    ][12618] Executing state module.run for [maas.process_maas_config]
2019-08-11 02:30:18,994 [salt.utils.decorators:613 ][WARNING ][12618] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-11 02:30:18,995 [salt.loaded.ext.module.maas:92  ][INFO    ][12618] maasconfig name=enable_http_proxy value=True
2019-08-11 02:30:19,059 [salt.loaded.ext.module.maas:92  ][INFO    ][12618] maasconfig name=upstream_dns value=8.8.8.8
2019-08-11 02:30:21,958 [salt.loaded.ext.module.maas:92  ][INFO    ][12618] maasconfig name=commissioning_distro_series value=xenial
2019-08-11 02:30:22,028 [salt.loaded.ext.module.maas:92  ][INFO    ][12618] maasconfig name=default_osystem value=ubuntu
2019-08-11 02:30:22,092 [salt.loaded.ext.module.maas:92  ][INFO    ][12618] maasconfig name=active_discovery_interval value=600
2019-08-11 02:30:22,146 [salt.loaded.ext.module.maas:92  ][INFO    ][12618] maasconfig name=dnssec_validation value=no
2019-08-11 02:30:22,200 [salt.loaded.ext.module.maas:92  ][INFO    ][12618] maasconfig name=maas_name value=mas01
2019-08-11 02:30:22,248 [salt.loaded.ext.module.maas:92  ][INFO    ][12618] maasconfig name=network_discovery value=enabled
2019-08-11 02:30:22,362 [salt.loaded.ext.module.maas:92  ][INFO    ][12618] maasconfig name=enable_third_party_drivers value=True
2019-08-11 02:30:22,436 [salt.loaded.ext.module.maas:92  ][INFO    ][12618] maasconfig name=default_storage_layout value=lvm
2019-08-11 02:30:22,503 [salt.loaded.ext.module.maas:92  ][INFO    ][12618] maasconfig name=ntp_external_only value=True
2019-08-11 02:30:22,560 [salt.loaded.ext.module.maas:92  ][INFO    ][12618] maasconfig name=disk_erase_with_secure_erase value=False
2019-08-11 02:30:22,614 [salt.loaded.ext.module.maas:92  ][INFO    ][12618] maasconfig name=default_distro_series value=xenial
2019-08-11 02:30:22,688 [salt.loaded.ext.module.maas:92  ][INFO    ][12618] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-08-11 02:30:22,820 [salt.state       :300 ][INFO    ][12618] {'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-08-11 02:30:22,820 [salt.state       :1951][INFO    ][12618] Completed state [maas.process_maas_config] at time 02:30:22.820531 duration_in_ms=3826.971
2019-08-11 02:30:22,821 [salt.state       :1780][INFO    ][12618] Running state [pxe_admin] at time 02:30:22.821449
2019-08-11 02:30:22,821 [salt.state       :1813][INFO    ][12618] Executing state maasng.fabric_present for [pxe_admin]
2019-08-11 02:30:22,902 [salt.loaded.ext.module.maasng:945 ][INFO    ][12618] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'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'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'hfrg87', 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'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-08-11 02:30:22,967 [salt.loaded.ext.module.maasng:1008][WARNING ][12618] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-08-11 02:30:22,968 [salt.loaded.ext.module.maasng:1011][WARNING ][12618] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-08-11 02:30:23,053 [salt.state       :300 ][INFO    ][12618] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-08-11 02:30:23,053 [salt.state       :1951][INFO    ][12618] Completed state [pxe_admin] at time 02:30:23.053810 duration_in_ms=232.36
2019-08-11 02:30:23,054 [salt.state       :1780][INFO    ][12618] Running state [vlan 0] at time 02:30:23.054249
2019-08-11 02:30:23,054 [salt.state       :1813][INFO    ][12618] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-08-11 02:30:23,123 [salt.loaded.ext.module.maasng:945 ][INFO    ][12618] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', 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'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', 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'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hfrg87', u'name': u'untagged', 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'id': 2}]
2019-08-11 02:30:23,248 [salt.loaded.ext.module.maasng:945 ][INFO    ][12618] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', 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'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', 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'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hfrg87', u'name': u'untagged', 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'id': 2}]
2019-08-11 02:30:23,547 [salt.loaded.ext.module.maasng:945 ][INFO    ][12618] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hfrg87', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-11 02:30:23,638 [salt.state       :300 ][INFO    ][12618] {'new': 'Vlan untagged was updated'}
2019-08-11 02:30:23,638 [salt.state       :1951][INFO    ][12618] Completed state [vlan 0] at time 02:30:23.638454 duration_in_ms=584.205
2019-08-11 02:30:23,639 [salt.state       :1780][INFO    ][12618] Running state [192.168.11.0/24] at time 02:30:23.639826
2019-08-11 02:30:23,640 [salt.state       :1813][INFO    ][12618] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-08-11 02:30:23,896 [salt.loaded.ext.module.maasng:945 ][INFO    ][12618] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hfrg87', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-11 02:30:23,896 [salt.loaded.ext.module.maasng:1235][WARNING ][12618] Ignoring parameter vlan:0
2019-08-11 02:30:23,962 [salt.state       :300 ][INFO    ][12618] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-08-11 02:30:23,962 [salt.state       :1951][INFO    ][12618] Completed state [192.168.11.0/24] at time 02:30:23.962770 duration_in_ms=322.943
2019-08-11 02:30:23,964 [salt.state       :1780][INFO    ][12618] Running state [maas_create_iprange_1] at time 02:30:23.964047
2019-08-11 02:30:23,964 [salt.state       :1813][INFO    ][12618] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-08-11 02:30:24,028 [salt.state       :300 ][INFO    ][12618] Iprange maas_create_iprange_1 already exist.
2019-08-11 02:30:24,029 [salt.state       :1951][INFO    ][12618] Completed state [maas_create_iprange_1] at time 02:30:24.028994 duration_in_ms=64.946
2019-08-11 02:30:24,029 [salt.state       :1780][INFO    ][12618] Running state [vlan 0] at time 02:30:24.029390
2019-08-11 02:30:24,029 [salt.state       :1813][INFO    ][12618] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-08-11 02:30:24,093 [salt.loaded.ext.module.maasng:945 ][INFO    ][12618] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hfrg87', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-11 02:30:24,183 [salt.loaded.ext.module.maasng:945 ][INFO    ][12618] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', 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'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', 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'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hfrg87', u'name': u'untagged', 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'id': 2}]
2019-08-11 02:30:24,404 [salt.loaded.ext.module.maasng:945 ][INFO    ][12618] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', 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'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', 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'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hfrg87', u'name': u'untagged', 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'id': 2}]
2019-08-11 02:30:24,500 [salt.state       :300 ][INFO    ][12618] {'new': 'Vlan untagged was updated'}
2019-08-11 02:30:24,501 [salt.state       :1951][INFO    ][12618] Completed state [vlan 0] at time 02:30:24.501313 duration_in_ms=471.922
2019-08-11 02:30:24,502 [salt.state       :1780][INFO    ][12618] Running state [opnfv] at time 02:30:24.502211
2019-08-11 02:30:24,502 [salt.state       :1813][INFO    ][12618] Executing state maasng.sshkey_present for [opnfv]
2019-08-11 02:30:24,548 [salt.loaded.ext.module.maasng:1903][INFO    ][12618] [{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-08-11 02:30:24,548 [salt.state       :300 ][INFO    ][12618] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-08-11 02:30:24,549 [salt.state       :1951][INFO    ][12618] Completed state [opnfv] at time 02:30:24.549083 duration_in_ms=46.872
2019-08-11 02:30:24,553 [salt.minion      :1711][INFO    ][12618] Returning information for job: 20190811022956597476
2019-08-11 02:30:25,116 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command state.apply with jid 20190811023025104977
2019-08-11 02:30:25,135 [salt.minion      :1432][INFO    ][13094] Starting a new job with PID 13094
2019-08-11 02:30:28,915 [salt.state       :915 ][INFO    ][13094] Loading fresh modules for state activity
2019-08-11 02:30:28,995 [salt.state       :1780][INFO    ][13094] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:30:28.995601
2019-08-11 02:30:28,995 [salt.state       :1813][INFO    ][13094] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-11 02:30:28,997 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13094] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-11 02:30:30,391 [salt.state       :300 ][INFO    ][13094] {'pid': 13118, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-11 02:30:30,392 [salt.state       :1951][INFO    ][13094] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:30:30.391936 duration_in_ms=1396.334
2019-08-11 02:30:30,394 [salt.state       :1780][INFO    ][13094] Running state [maas.process_machines] at time 02:30:30.394462
2019-08-11 02:30:30,395 [salt.state       :1813][INFO    ][13094] Executing state module.run for [maas.process_machines]
2019-08-11 02:30:30,396 [salt.utils.decorators:613 ][WARNING ][13094] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-11 02:30:30,858 [salt.loaded.ext.module.maas:412 ][WARNING ][13094] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-11 02:30:30,859 [salt.loaded.ext.module.maas:92  ][INFO    ][13094] 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=4dmked architecture=amd64/generic power_parameters_power_user=admin
2019-08-11 02:30:31,946 [salt.loaded.ext.module.maas:412 ][WARNING ][13094] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-11 02:30:31,947 [salt.loaded.ext.module.maas:92  ][INFO    ][13094] 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=bfxmny architecture=amd64/generic power_parameters_power_user=admin
2019-08-11 02:30:33,215 [salt.loaded.ext.module.maas:412 ][WARNING ][13094] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-11 02:30:33,216 [salt.loaded.ext.module.maas:92  ][INFO    ][13094] 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=yfbtst architecture=amd64/generic power_parameters_power_user=admin
2019-08-11 02:30:34,496 [salt.loaded.ext.module.maas:412 ][WARNING ][13094] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-11 02:30:34,497 [salt.loaded.ext.module.maas:92  ][INFO    ][13094] 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=w8pcfb architecture=amd64/generic power_parameters_power_user=admin
2019-08-11 02:30:35,754 [salt.loaded.ext.module.maas:412 ][WARNING ][13094] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-11 02:30:35,755 [salt.loaded.ext.module.maas:92  ][INFO    ][13094] 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=nm3qrs architecture=amd64/generic power_parameters_power_user=admin
2019-08-11 02:30:37,065 [salt.state       :300 ][INFO    ][13094] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-08-11 02:30:37,066 [salt.state       :1951][INFO    ][13094] Completed state [maas.process_machines] at time 02:30:37.066418 duration_in_ms=6671.954
2019-08-11 02:30:37,070 [salt.minion      :1711][INFO    ][13094] Returning information for job: 20190811023025104977
2019-08-11 02:31:10,186 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command state.apply with jid 20190811023110173188
2019-08-11 02:31:10,209 [salt.minion      :1432][INFO    ][13345] Starting a new job with PID 13345
2019-08-11 02:31:13,900 [salt.state       :915 ][INFO    ][13345] Loading fresh modules for state activity
2019-08-11 02:31:13,991 [salt.state       :1780][INFO    ][13345] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:31:13.990949
2019-08-11 02:31:13,991 [salt.state       :1813][INFO    ][13345] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-11 02:31:13,993 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13345] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-11 02:31:15,560 [salt.state       :300 ][INFO    ][13345] {'pid': 13368, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-11 02:31:15,560 [salt.state       :1951][INFO    ][13345] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:31:15.560608 duration_in_ms=1569.66
2019-08-11 02:31:15,563 [salt.state       :1780][INFO    ][13345] Running state [maas.wait_for_machine_status] at time 02:31:15.563189
2019-08-11 02:31:15,563 [salt.state       :1813][INFO    ][13345] Executing state module.run for [maas.wait_for_machine_status]
2019-08-11 02:31:15,564 [salt.utils.decorators:613 ][WARNING ][13345] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-11 02:31:18,464 [salt.loaded.ext.module.maas:993 ][INFO    ][13345] Machine w8pcfb mark broken
2019-08-11 02:31:19,100 [salt.loaded.ext.module.maas:996 ][INFO    ][13345] Machine w8pcfb mark fixed
2019-08-11 02:31:20,249 [salt.loaded.ext.module.maas:684 ][INFO    ][13345] deploymachines hwe_kernel=ga-16.04 system_id=w8pcfb distro_series=xenial
2019-08-11 02:31:23,345 [salt.loaded.ext.module.maas:1023][INFO    ][13345] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1492.22670221s left)
2019-08-11 02:31:25,303 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811023125287329
2019-08-11 02:31:25,327 [salt.minion      :1432][INFO    ][13461] Starting a new job with PID 13461
2019-08-11 02:31:25,350 [salt.minion      :1711][INFO    ][13461] Returning information for job: 20190811023125287329
2019-08-11 02:31:55,350 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811023155337583
2019-08-11 02:31:55,375 [salt.minion      :1432][INFO    ][13487] Starting a new job with PID 13487
2019-08-11 02:31:55,398 [salt.minion      :1711][INFO    ][13487] Returning information for job: 20190811023155337583
2019-08-11 02:31:56,826 [salt.loaded.ext.module.maas:1023][INFO    ][13345] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1458.74624801s left)
2019-08-11 02:32:25,599 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811023225550164
2019-08-11 02:32:25,619 [salt.minion      :1432][INFO    ][13547] Starting a new job with PID 13547
2019-08-11 02:32:25,642 [salt.minion      :1711][INFO    ][13547] Returning information for job: 20190811023225550164
2019-08-11 02:32:30,688 [salt.loaded.ext.module.maas:1023][INFO    ][13345] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1424.88458514s left)
2019-08-11 02:32:55,644 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811023255629276
2019-08-11 02:32:55,667 [salt.minion      :1432][INFO    ][13566] Starting a new job with PID 13566
2019-08-11 02:32:55,693 [salt.minion      :1711][INFO    ][13566] Returning information for job: 20190811023255629276
2019-08-11 02:33:04,016 [salt.loaded.ext.module.maas:1023][INFO    ][13345] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1391.55640221s left)
2019-08-11 02:33:25,711 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811023325697654
2019-08-11 02:33:25,733 [salt.minion      :1432][INFO    ][13712] Starting a new job with PID 13712
2019-08-11 02:33:25,759 [salt.minion      :1711][INFO    ][13712] Returning information for job: 20190811023325697654
2019-08-11 02:33:37,646 [salt.loaded.ext.module.maas:1023][INFO    ][13345] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1357.92593312s left)
2019-08-11 02:33:55,763 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811023355751043
2019-08-11 02:33:55,786 [salt.minion      :1432][INFO    ][13734] Starting a new job with PID 13734
2019-08-11 02:33:55,812 [salt.minion      :1711][INFO    ][13734] Returning information for job: 20190811023355751043
2019-08-11 02:34:11,035 [salt.loaded.ext.module.maas:1023][INFO    ][13345] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1324.53696704s left)
2019-08-11 02:34:25,810 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811023425797657
2019-08-11 02:34:25,832 [salt.minion      :1432][INFO    ][13890] Starting a new job with PID 13890
2019-08-11 02:34:25,860 [salt.minion      :1711][INFO    ][13890] Returning information for job: 20190811023425797657
2019-08-11 02:34:44,635 [salt.loaded.ext.module.maas:1023][INFO    ][13345] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1290.93692803s left)
2019-08-11 02:34:55,872 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811023455859592
2019-08-11 02:34:55,895 [salt.minion      :1432][INFO    ][13910] Starting a new job with PID 13910
2019-08-11 02:34:55,920 [salt.minion      :1711][INFO    ][13910] Returning information for job: 20190811023455859592
2019-08-11 02:35:18,321 [salt.loaded.ext.module.maas:1023][INFO    ][13345] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1257.25104403s left)
2019-08-11 02:35:25,940 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811023525927258
2019-08-11 02:35:25,962 [salt.minion      :1432][INFO    ][13971] Starting a new job with PID 13971
2019-08-11 02:35:25,988 [salt.minion      :1711][INFO    ][13971] Returning information for job: 20190811023525927258
2019-08-11 02:35:51,934 [salt.loaded.ext.module.maas:1023][INFO    ][13345] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1223.63817906s left)
2019-08-11 02:35:56,007 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811023555995058
2019-08-11 02:35:56,031 [salt.minion      :1432][INFO    ][13990] Starting a new job with PID 13990
2019-08-11 02:35:56,059 [salt.minion      :1711][INFO    ][13990] Returning information for job: 20190811023555995058
2019-08-11 02:36:25,183 [salt.loaded.ext.module.maas:1023][INFO    ][13345] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1190.38919616s left)
2019-08-11 02:36:26,082 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811023626070704
2019-08-11 02:36:26,101 [salt.minion      :1432][INFO    ][14128] Starting a new job with PID 14128
2019-08-11 02:36:26,121 [salt.minion      :1711][INFO    ][14128] Returning information for job: 20190811023626070704
2019-08-11 02:36:56,148 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811023656135639
2019-08-11 02:36:56,169 [salt.minion      :1432][INFO    ][14146] Starting a new job with PID 14146
2019-08-11 02:36:56,194 [salt.minion      :1711][INFO    ][14146] Returning information for job: 20190811023656135639
2019-08-11 02:36:58,444 [salt.loaded.ext.module.maas:1023][INFO    ][13345] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1157.1276412s left)
2019-08-11 02:37:26,228 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811023726214438
2019-08-11 02:37:26,250 [salt.minion      :1432][INFO    ][14247] Starting a new job with PID 14247
2019-08-11 02:37:26,275 [salt.minion      :1711][INFO    ][14247] Returning information for job: 20190811023726214438
2019-08-11 02:37:31,153 [salt.loaded.ext.module.maas:1023][INFO    ][13345] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1124.41938806s left)
2019-08-11 02:37:56,316 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811023756303721
2019-08-11 02:37:56,339 [salt.minion      :1432][INFO    ][14273] Starting a new job with PID 14273
2019-08-11 02:37:56,363 [salt.minion      :1711][INFO    ][14273] Returning information for job: 20190811023756303721
2019-08-11 02:38:04,529 [salt.loaded.ext.module.maas:1023][INFO    ][13345] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1091.04317522s left)
2019-08-11 02:38:26,406 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811023826393474
2019-08-11 02:38:26,429 [salt.minion      :1432][INFO    ][14415] Starting a new job with PID 14415
2019-08-11 02:38:26,456 [salt.minion      :1711][INFO    ][14415] Returning information for job: 20190811023826393474
2019-08-11 02:38:38,074 [salt.loaded.ext.module.maas:1023][INFO    ][13345] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1057.49806809s left)
2019-08-11 02:38:56,506 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811023856492869
2019-08-11 02:38:56,529 [salt.minion      :1432][INFO    ][14451] Starting a new job with PID 14451
2019-08-11 02:38:56,555 [salt.minion      :1711][INFO    ][14451] Returning information for job: 20190811023856492869
2019-08-11 02:39:11,564 [salt.loaded.ext.module.maas:1023][INFO    ][13345] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1024.00810504s left)
2019-08-11 02:39:26,616 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command saltutil.find_job with jid 20190811023926601590
2019-08-11 02:39:26,642 [salt.minion      :1432][INFO    ][14526] Starting a new job with PID 14526
2019-08-11 02:39:26,672 [salt.minion      :1711][INFO    ][14526] Returning information for job: 20190811023926601590
2019-08-11 02:39:44,714 [salt.state       :300 ][INFO    ][13345] {'ret': True}
2019-08-11 02:39:44,715 [salt.state       :1951][INFO    ][13345] Completed state [maas.wait_for_machine_status] at time 02:39:44.715189 duration_in_ms=509151.998
2019-08-11 02:39:44,718 [salt.minion      :1711][INFO    ][13345] Returning information for job: 20190811023110173188
2019-08-11 02:39:45,430 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command state.apply with jid 20190811023945417087
2019-08-11 02:39:45,453 [salt.minion      :1432][INFO    ][14563] Starting a new job with PID 14563
2019-08-11 02:39:49,169 [salt.state       :915 ][INFO    ][14563] Loading fresh modules for state activity
2019-08-11 02:39:49,314 [salt.state       :1780][INFO    ][14563] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:39:49.314467
2019-08-11 02:39:49,314 [salt.state       :1813][INFO    ][14563] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-11 02:39:49,316 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14563] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-11 02:39:50,799 [salt.state       :300 ][INFO    ][14563] {'pid': 14572, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-11 02:39:50,800 [salt.state       :1951][INFO    ][14563] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:39:50.800069 duration_in_ms=1485.601
2019-08-11 02:39:50,803 [salt.state       :1780][INFO    ][14563] Running state [maas_machines_storage_cmp002_lvm] at time 02:39:50.803233
2019-08-11 02:39:50,803 [salt.state       :1813][INFO    ][14563] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-08-11 02:39:51,531 [salt.state       :300 ][INFO    ][14563] Machine cmp002 is not in Ready state.
2019-08-11 02:39:51,532 [salt.state       :1951][INFO    ][14563] Completed state [maas_machines_storage_cmp002_lvm] at time 02:39:51.532427 duration_in_ms=729.193
2019-08-11 02:39:51,533 [salt.state       :1780][INFO    ][14563] Running state [maas_machines_storage_cmp001_lvm] at time 02:39:51.533057
2019-08-11 02:39:51,533 [salt.state       :1813][INFO    ][14563] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-08-11 02:39:52,193 [salt.state       :300 ][INFO    ][14563] Machine cmp001 is not in Ready state.
2019-08-11 02:39:52,194 [salt.state       :1951][INFO    ][14563] Completed state [maas_machines_storage_cmp001_lvm] at time 02:39:52.194143 duration_in_ms=661.085
2019-08-11 02:39:52,198 [salt.minion      :1711][INFO    ][14563] Returning information for job: 20190811023945417087
2019-08-11 02:39:52,831 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command state.apply with jid 20190811023952817714
2019-08-11 02:39:52,854 [salt.minion      :1432][INFO    ][14582] Starting a new job with PID 14582
2019-08-11 02:39:53,594 [salt.state       :915 ][INFO    ][14582] Loading fresh modules for state activity
2019-08-11 02:39:53,687 [salt.state       :1780][INFO    ][14582] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:39:53.687283
2019-08-11 02:39:53,687 [salt.state       :1813][INFO    ][14582] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-11 02:39:53,690 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14582] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-11 02:39:55,155 [salt.state       :300 ][INFO    ][14582] {'pid': 14589, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-11 02:39:55,156 [salt.state       :1951][INFO    ][14582] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:39:55.156108 duration_in_ms=1468.826
2019-08-11 02:39:55,157 [salt.state       :1780][INFO    ][14582] Running state [maas.deploy_machines] at time 02:39:55.157299
2019-08-11 02:39:55,157 [salt.state       :1813][INFO    ][14582] Executing state module.run for [maas.deploy_machines]
2019-08-11 02:39:55,158 [salt.utils.decorators:613 ][WARNING ][14582] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-11 02:39:55,860 [salt.state       :300 ][INFO    ][14582] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-08-11 02:39:55,861 [salt.state       :1951][INFO    ][14582] Completed state [maas.deploy_machines] at time 02:39:55.861181 duration_in_ms=703.879
2019-08-11 02:39:55,864 [salt.minion      :1711][INFO    ][14582] Returning information for job: 20190811023952817714
2019-08-11 02:39:56,507 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command state.apply with jid 20190811023956494823
2019-08-11 02:39:56,530 [salt.minion      :1432][INFO    ][14599] Starting a new job with PID 14599
2019-08-11 02:39:57,295 [salt.state       :915 ][INFO    ][14599] Loading fresh modules for state activity
2019-08-11 02:39:57,385 [salt.state       :1780][INFO    ][14599] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:39:57.385217
2019-08-11 02:39:57,385 [salt.state       :1813][INFO    ][14599] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-11 02:39:57,387 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14599] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-11 02:39:58,880 [salt.state       :300 ][INFO    ][14599] {'pid': 14606, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-11 02:39:58,881 [salt.state       :1951][INFO    ][14599] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:39:58.881361 duration_in_ms=1496.143
2019-08-11 02:39:58,884 [salt.state       :1780][INFO    ][14599] Running state [maas.wait_for_machine_status] at time 02:39:58.884671
2019-08-11 02:39:58,885 [salt.state       :1813][INFO    ][14599] Executing state module.run for [maas.wait_for_machine_status]
2019-08-11 02:39:58,885 [salt.utils.decorators:613 ][WARNING ][14599] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-11 02:40:02,313 [salt.state       :300 ][INFO    ][14599] {'ret': True}
2019-08-11 02:40:02,314 [salt.state       :1951][INFO    ][14599] Completed state [maas.wait_for_machine_status] at time 02:40:02.314519 duration_in_ms=3429.846
2019-08-11 02:40:02,318 [salt.minion      :1711][INFO    ][14599] Returning information for job: 20190811023956494823
2019-08-11 03:09:34,578 [salt.utils.schedule:1377][INFO    ][6611] Running scheduled job: __mine_interval
2019-08-11 04:09:34,578 [salt.utils.schedule:1377][INFO    ][6611] Running scheduled job: __mine_interval
2019-08-11 04:09:52,588 [salt.minion      :1308][INFO    ][6611] User sudo_ubuntu Executing command cp.push_dir with jid 20190811040952581682
2019-08-11 04:09:52,607 [salt.minion      :1432][INFO    ][21150] Starting a new job with PID 21150
