2019-04-24 12:26:36,059 [salt.utils.decorators:613 ][WARNING ][2006] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 12:26:37,159 [salt.utils.decorators:613 ][WARNING ][2006] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 12:26:40,592 [salt.loaded.int.states.file:2298][WARNING ][2270] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-24 12:27:07,785 [salt.state       :2022][WARNING ][2703] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-24 12:27:10,830 [salt.utils.decorators:613 ][WARNING ][2703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 12:27:24,897 [salt.utils.decorators:613 ][WARNING ][2703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 12:27:50,506 [salt.utils.decorators:613 ][WARNING ][2703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 12:27:51,473 [salt.utils.decorators:613 ][WARNING ][2703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 12:27:53,909 [salt.loaded.ext.module.maasng:1008][WARNING ][2703] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-04-24 12:27:53,909 [salt.loaded.ext.module.maasng:1011][WARNING ][2703] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-04-24 12:27:54,515 [salt.loaded.ext.module.maasng:1235][WARNING ][2703] Ignoring parameter vlan:0
2019-04-24 12:27:58,488 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4723] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-24 12:27:58,509 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4723] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-24 12:27:58,544 [salt.utils.parsers:1051][WARNING ][273] Minion received a SIGTERM. Exiting.
2019-04-24 12:27:59,646 [salt.cli.daemons :293 ][INFO    ][4810] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-04-24 12:27:59,812 [salt.cli.daemons :82  ][INFO    ][4810] Starting up the Salt Minion
2019-04-24 12:27:59,813 [salt.utils.event :1017][INFO    ][4810] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-04-24 12:28:00,954 [salt.minion      :976 ][INFO    ][4810] Creating minion process manager
2019-04-24 12:28:02,775 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4810] Executing command ['date', '+%z'] in directory '/root'
2019-04-24 12:28:02,794 [salt.utils.schedule:568 ][INFO    ][4810] Updating job settings for scheduled job: __mine_interval
2019-04-24 12:28:02,796 [salt.minion      :1108][INFO    ][4810] Added mine.update to scheduler
2019-04-24 12:28:02,802 [salt.minion      :1975][INFO    ][4810] Minion is starting as user 'root'
2019-04-24 12:28:02,816 [salt.minion      :2336][INFO    ][4810] Minion is ready to receive requests!
2019-04-24 12:28:04,412 [salt.utils.decorators:613 ][WARNING ][4733] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 12:28:04,509 [salt.loaded.ext.module.maas:412 ][WARNING ][4733] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 12:28:05,673 [salt.loaded.ext.module.maas:412 ][WARNING ][4733] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 12:28:06,752 [salt.loaded.ext.module.maas:412 ][WARNING ][4733] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 12:28:07,824 [salt.loaded.ext.module.maas:412 ][WARNING ][4733] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 12:28:08,970 [salt.loaded.ext.module.maas:412 ][WARNING ][4733] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 12:28:41,396 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command state.apply with jid 20190424122841386687
2019-04-24 12:28:41,416 [salt.minion      :1432][INFO    ][5268] Starting a new job with PID 5268
2019-04-24 12:28:47,287 [salt.state       :915 ][INFO    ][5268] Loading fresh modules for state activity
2019-04-24 12:28:47,357 [salt.fileclient  :1219][INFO    ][5268] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-24 12:28:47,399 [salt.state       :1780][INFO    ][5268] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:28:47.399896
2019-04-24 12:28:47,400 [salt.state       :1813][INFO    ][5268] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 12:28:47,402 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5268] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 12:28:49,224 [salt.state       :300 ][INFO    ][5268] {'pid': 5277, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 12:28:49,225 [salt.state       :1951][INFO    ][5268] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:28:49.225099 duration_in_ms=1825.202
2019-04-24 12:28:49,229 [salt.state       :1780][INFO    ][5268] Running state [maas.wait_for_machine_status] at time 12:28:49.229754
2019-04-24 12:28:49,230 [salt.state       :1813][INFO    ][5268] Executing state module.run for [maas.wait_for_machine_status]
2019-04-24 12:28:49,231 [salt.utils.decorators:613 ][WARNING ][5268] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 12:28:50,028 [salt.loaded.ext.module.maas:1023][INFO    ][5268] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.22052217s left)
2019-04-24 12:28:56,478 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424122856464164
2019-04-24 12:28:56,496 [salt.minion      :1432][INFO    ][5288] Starting a new job with PID 5288
2019-04-24 12:28:56,524 [salt.minion      :1711][INFO    ][5288] Returning information for job: 20190424122856464164
2019-04-24 12:29:21,105 [salt.loaded.ext.module.maas:1023][INFO    ][5268] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.143502s left)
2019-04-24 12:29:26,694 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424122926680629
2019-04-24 12:29:26,710 [salt.minion      :1432][INFO    ][5392] Starting a new job with PID 5392
2019-04-24 12:29:26,742 [salt.minion      :1711][INFO    ][5392] Returning information for job: 20190424122926680629
2019-04-24 12:29:51,919 [salt.loaded.ext.module.maas:1023][INFO    ][5268] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.32902002s left)
2019-04-24 12:29:56,884 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424122956873560
2019-04-24 12:29:56,900 [salt.minion      :1432][INFO    ][5428] Starting a new job with PID 5428
2019-04-24 12:29:56,926 [salt.minion      :1711][INFO    ][5428] Returning information for job: 20190424122956873560
2019-04-24 12:30:22,752 [salt.loaded.ext.module.maas:1023][INFO    ][5268] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.49633598s left)
2019-04-24 12:30:27,078 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424123027065767
2019-04-24 12:30:27,094 [salt.minion      :1432][INFO    ][5496] Starting a new job with PID 5496
2019-04-24 12:30:27,127 [salt.minion      :1711][INFO    ][5496] Returning information for job: 20190424123027065767
2019-04-24 12:30:53,773 [salt.loaded.ext.module.maas:1023][INFO    ][5268] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.47488999s left)
2019-04-24 12:30:57,290 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424123057280637
2019-04-24 12:30:57,310 [salt.minion      :1432][INFO    ][5555] Starting a new job with PID 5555
2019-04-24 12:30:57,341 [salt.minion      :1711][INFO    ][5555] Returning information for job: 20190424123057280637
2019-04-24 12:31:24,776 [salt.loaded.ext.module.maas:1023][INFO    ][5268] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.47202396s left)
2019-04-24 12:31:27,509 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424123127492421
2019-04-24 12:31:27,529 [salt.minion      :1432][INFO    ][5721] Starting a new job with PID 5721
2019-04-24 12:31:27,558 [salt.minion      :1711][INFO    ][5721] Returning information for job: 20190424123127492421
2019-04-24 12:31:55,906 [salt.loaded.ext.module.maas:1023][INFO    ][5268] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.34197807s left)
2019-04-24 12:31:57,599 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424123157586166
2019-04-24 12:31:57,614 [salt.minion      :1432][INFO    ][5855] Starting a new job with PID 5855
2019-04-24 12:31:57,644 [salt.minion      :1711][INFO    ][5855] Returning information for job: 20190424123157586166
2019-04-24 12:32:27,189 [salt.loaded.ext.module.maas:1023][INFO    ][5268] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.05928898s left)
2019-04-24 12:32:27,708 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424123227698354
2019-04-24 12:32:27,720 [salt.minion      :1432][INFO    ][6151] Starting a new job with PID 6151
2019-04-24 12:32:27,744 [salt.minion      :1711][INFO    ][6151] Returning information for job: 20190424123227698354
2019-04-24 12:32:57,770 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424123257762915
2019-04-24 12:32:57,788 [salt.minion      :1432][INFO    ][6300] Starting a new job with PID 6300
2019-04-24 12:32:57,816 [salt.minion      :1711][INFO    ][6300] Returning information for job: 20190424123257762915
2019-04-24 12:32:58,936 [salt.loaded.ext.module.maas:1023][INFO    ][5268] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.31377506s left)
2019-04-24 12:33:27,789 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424123327775445
2019-04-24 12:33:27,812 [salt.minion      :1432][INFO    ][6602] Starting a new job with PID 6602
2019-04-24 12:33:27,839 [salt.minion      :1711][INFO    ][6602] Returning information for job: 20190424123327775445
2019-04-24 12:33:31,028 [salt.loaded.ext.module.maas:1023][INFO    ][5268] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.22004318s left)
2019-04-24 12:33:57,808 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424123357794415
2019-04-24 12:33:57,828 [salt.minion      :1432][INFO    ][6846] Starting a new job with PID 6846
2019-04-24 12:33:57,863 [salt.minion      :1711][INFO    ][6846] Returning information for job: 20190424123357794415
2019-04-24 12:34:03,226 [salt.loaded.ext.module.maas:1023][INFO    ][5268] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1186.02261519s left)
2019-04-24 12:34:27,827 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424123427816095
2019-04-24 12:34:27,848 [salt.minion      :1432][INFO    ][7024] Starting a new job with PID 7024
2019-04-24 12:34:27,872 [salt.minion      :1711][INFO    ][7024] Returning information for job: 20190424123427816095
2019-04-24 12:34:35,411 [salt.loaded.ext.module.maas:1023][INFO    ][5268] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1153.83685899s left)
2019-04-24 12:34:57,849 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424123457839688
2019-04-24 12:34:57,873 [salt.minion      :1432][INFO    ][7186] Starting a new job with PID 7186
2019-04-24 12:34:57,899 [salt.minion      :1711][INFO    ][7186] Returning information for job: 20190424123457839688
2019-04-24 12:35:08,330 [salt.state       :300 ][INFO    ][5268] {'ret': True}
2019-04-24 12:35:08,333 [salt.state       :1951][INFO    ][5268] Completed state [maas.wait_for_machine_status] at time 12:35:08.330796 duration_in_ms=379101.041
2019-04-24 12:35:08,337 [salt.minion      :1711][INFO    ][5268] Returning information for job: 20190424122841386687
2019-04-24 12:35:09,037 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command state.apply with jid 20190424123509025898
2019-04-24 12:35:09,050 [salt.minion      :1432][INFO    ][7224] Starting a new job with PID 7224
2019-04-24 12:35:15,148 [salt.state       :915 ][INFO    ][7224] Loading fresh modules for state activity
2019-04-24 12:35:15,196 [salt.fileclient  :1219][INFO    ][7224] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-24 12:35:15,298 [salt.state       :1780][INFO    ][7224] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:35:15.297972
2019-04-24 12:35:15,298 [salt.state       :1813][INFO    ][7224] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 12:35:15,300 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7224] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 12:35:17,132 [salt.state       :300 ][INFO    ][7224] {'pid': 7302, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 12:35:17,133 [salt.state       :1951][INFO    ][7224] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:35:17.133516 duration_in_ms=1835.544
2019-04-24 12:35:17,137 [salt.state       :1780][INFO    ][7224] Running state [maas_machines_storage_cmp002_lvm] at time 12:35:17.137339
2019-04-24 12:35:17,138 [salt.state       :1813][INFO    ][7224] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-24 12:35:18,213 [salt.loaded.ext.module.maasng:610 ][INFO    ][7224] mpscrq
2019-04-24 12:35:18,213 [salt.loaded.ext.module.maasng:626 ][INFO    ][7224] sda
2019-04-24 12:35:18,792 [salt.loaded.ext.module.maasng:361 ][INFO    ][7224] mpscrq
2019-04-24 12:35:18,879 [salt.loaded.ext.module.maasng:367 ][INFO    ][7224] [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/mpscrq/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'mpscrq', u'block_size': 4096, u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'74701003-b65d-4c64-bd87-b12029715675', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mpscrq', u'device_id': 1, u'filesystem': {u'uuid': u'9d6b1129-45c7-4713-ad73-bedca48a88bb', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/mpscrq/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}], u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mpscrq/blockdevices/3/', u'uuid': u'fa41494c-a3a5-4a07-92f5-c8fba35ca6f2', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'filesystem': {u'uuid': u'ed4d5ef1-99c2-4b64-a532-664be0cbb415', 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'mpscrq', u'block_size': 4096, 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': 3, u'size': 800097042432}]
2019-04-24 12:35:18,879 [salt.loaded.ext.module.maasng:632 ][INFO    ][7224] vgroot
2019-04-24 12:35:18,879 [salt.loaded.ext.module.maasng:635 ][INFO    ][7224] lvroot
2019-04-24 12:35:18,880 [salt.loaded.ext.module.maasng:639 ][INFO    ][7224] 107374182400
2019-04-24 12:35:19,463 [salt.loaded.ext.module.maasng:645 ][INFO    ][7224] {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'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'status_action': u'', 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'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/mpscrq/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'mpscrq', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', 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'82c7915a-b16e-4d75-a044-06c5d10f9f7f', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'label': u'root', u'uuid': u'ad22b242-c967-42fb-9507-f827b992d50f', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id': 11, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'mpscrq', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'serial': None, u'size': 107374182400, u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mpscrq/blockdevices/11/'}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'd8bf76f1-ddc4-4648-9f3f-298e9c935a0f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mpscrq', u'filesystem': {u'label': None, u'uuid': u'b4eabb22-4e61-462c-a435-2140209488f4', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/mpscrq/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'mpscrq', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/mpscrq/blockdevices/1/', u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'uuid': u'82c7915a-b16e-4d75-a044-06c5d10f9f7f', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'label': u'root', u'uuid': u'ad22b242-c967-42fb-9507-f827b992d50f', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'mpscrq', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mpscrq/blockdevices/11/', u'type': u'virtual', u'id': 11, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'mpscrq', 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'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'd8bf76f1-ddc4-4648-9f3f-298e9c935a0f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mpscrq', u'filesystem': {u'label': None, u'uuid': u'b4eabb22-4e61-462c-a435-2140209488f4', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/mpscrq/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'filesystem': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'mpscrq', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'used_for': u'MBR partitioned with 1 partition', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/mpscrq/blockdevices/1/'}, 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'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'd8bf76f1-ddc4-4648-9f3f-298e9c935a0f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mpscrq', u'filesystem': {u'label': None, u'uuid': u'b4eabb22-4e61-462c-a435-2140209488f4', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/mpscrq/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'filesystem': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'mpscrq', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'used_for': u'MBR partitioned with 1 partition', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/mpscrq/blockdevices/1/'}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qw4tfr', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qw4tfr', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qw4tfr', 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'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'system_id': u'mpscrq', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/mpscrq/interfaces/4/'}, u'min_hwe_kernel': u'hwe-16.04', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qw4tfr', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qw4tfr', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qw4tfr', 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'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'system_id': u'mpscrq', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/mpscrq/interfaces/4/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'system_id': u'mpscrq', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/mpscrq/interfaces/11/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'system_id': u'mpscrq', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/mpscrq/interfaces/14/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qw4tfr', 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'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qw4tfr', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qw4tfr', 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.40'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'system_id': u'mpscrq', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/mpscrq/interfaces/15/'}, {u'name': u'ens2f0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'system_id': u'mpscrq', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/mpscrq/interfaces/12/'}, {u'name': u'ens2f1', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'system_id': u'mpscrq', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/mpscrq/interfaces/13/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-04-24 12:35:19,466 [salt.state       :300 ][INFO    ][7224] {'new': {'storage_layout': 'lvm'}}
2019-04-24 12:35:19,466 [salt.state       :1951][INFO    ][7224] Completed state [maas_machines_storage_cmp002_lvm] at time 12:35:19.466496 duration_in_ms=2329.155
2019-04-24 12:35:19,468 [salt.state       :1780][INFO    ][7224] Running state [maas_machines_storage_cmp001_lvm] at time 12:35:19.468710
2019-04-24 12:35:19,469 [salt.state       :1813][INFO    ][7224] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-24 12:35:20,604 [salt.loaded.ext.module.maasng:610 ][INFO    ][7224] e8mgwm
2019-04-24 12:35:20,604 [salt.loaded.ext.module.maasng:626 ][INFO    ][7224] sda
2019-04-24 12:35:21,101 [salt.loaded.ext.module.maasng:361 ][INFO    ][7224] e8mgwm
2019-04-24 12:35:21,174 [salt.loaded.ext.module.maasng:367 ][INFO    ][7224] [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'dfdc7f63-45ed-4fee-aafe-099348e6cf18', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'e8mgwm', u'filesystem': {u'label': None, u'uuid': u'210dd3a5-6f86-4e32-9fd3-17661ac640a0', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/e8mgwm/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'device_id': 2}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'e8mgwm', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/e8mgwm/blockdevices/2/', u'type': u'physical', u'id': 2, u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'uuid': u'4565070a-791a-4509-9bad-848a0e19ef83', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'label': u'root', u'uuid': u'b4188eca-b51d-45ec-a149-8454455063d7', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'e8mgwm', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/e8mgwm/blockdevices/4/', u'type': u'virtual', u'id': 4, u'size': 800097042432}]
2019-04-24 12:35:21,175 [salt.loaded.ext.module.maasng:632 ][INFO    ][7224] vgroot
2019-04-24 12:35:21,175 [salt.loaded.ext.module.maasng:635 ][INFO    ][7224] lvroot
2019-04-24 12:35:21,176 [salt.loaded.ext.module.maasng:639 ][INFO    ][7224] 107374182400
2019-04-24 12:35:21,775 [salt.loaded.ext.module.maasng:645 ][INFO    ][7224] {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'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'status_action': u'', 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'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/e8mgwm/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'e8mgwm', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', 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'd40b0f59-932b-4fe5-827b-4a13657f6d09', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'label': u'root', u'uuid': u'2d64f5cc-0f40-467a-ae2e-e06e4c4db87b', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id': 12, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'e8mgwm', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'serial': None, u'size': 107374182400, u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/e8mgwm/blockdevices/12/'}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'd3c8c05a-acb6-489d-8e59-57f616d1deb3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'e8mgwm', u'filesystem': {u'label': None, u'uuid': u'8558e8c4-5c2f-42bf-982b-f68a2b499c87', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/e8mgwm/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'e8mgwm', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/e8mgwm/blockdevices/2/', u'type': u'physical', u'id': 2, u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'uuid': u'd40b0f59-932b-4fe5-827b-4a13657f6d09', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'label': u'root', u'uuid': u'2d64f5cc-0f40-467a-ae2e-e06e4c4db87b', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'e8mgwm', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/e8mgwm/blockdevices/12/', u'type': u'virtual', u'id': 12, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'e8mgwm', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'd3c8c05a-acb6-489d-8e59-57f616d1deb3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'e8mgwm', u'filesystem': {u'label': None, u'uuid': u'8558e8c4-5c2f-42bf-982b-f68a2b499c87', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/e8mgwm/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}], u'filesystem': None, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'e8mgwm', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'used_for': u'MBR partitioned with 1 partition', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/e8mgwm/blockdevices/2/'}, u'current_testing_result_id': 5, 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'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'd3c8c05a-acb6-489d-8e59-57f616d1deb3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'e8mgwm', u'filesystem': {u'label': None, u'uuid': u'8558e8c4-5c2f-42bf-982b-f68a2b499c87', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/e8mgwm/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}], u'filesystem': None, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'e8mgwm', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'used_for': u'MBR partitioned with 1 partition', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/e8mgwm/blockdevices/2/'}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qw4tfr', 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.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qw4tfr', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qw4tfr', 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.39'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'system_id': u'e8mgwm', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/e8mgwm/interfaces/5/'}, u'min_hwe_kernel': u'hwe-16.04', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qw4tfr', 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.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qw4tfr', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qw4tfr', 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.39'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'system_id': u'e8mgwm', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/e8mgwm/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'system_id': u'e8mgwm', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/e8mgwm/interfaces/18/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'system_id': u'e8mgwm', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/e8mgwm/interfaces/19/'}, {u'name': u'ens2f1', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'system_id': u'e8mgwm', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/e8mgwm/interfaces/16/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qw4tfr', 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'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qw4tfr', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qw4tfr', 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.44'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'system_id': u'e8mgwm', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/e8mgwm/interfaces/17/'}, {u'name': u'ens2f0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'system_id': u'e8mgwm', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/e8mgwm/interfaces/20/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-04-24 12:35:21,778 [salt.state       :300 ][INFO    ][7224] {'new': {'storage_layout': 'lvm'}}
2019-04-24 12:35:21,778 [salt.state       :1951][INFO    ][7224] Completed state [maas_machines_storage_cmp001_lvm] at time 12:35:21.778274 duration_in_ms=2309.564
2019-04-24 12:35:21,781 [salt.minion      :1711][INFO    ][7224] Returning information for job: 20190424123509025898
2019-04-24 12:35:22,474 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command state.apply with jid 20190424123522460665
2019-04-24 12:35:22,494 [salt.minion      :1432][INFO    ][7332] Starting a new job with PID 7332
2019-04-24 12:35:23,464 [salt.state       :915 ][INFO    ][7332] Loading fresh modules for state activity
2019-04-24 12:35:23,519 [salt.fileclient  :1219][INFO    ][7332] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-24 12:35:23,561 [salt.state       :1780][INFO    ][7332] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:35:23.561735
2019-04-24 12:35:23,562 [salt.state       :1813][INFO    ][7332] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 12:35:23,564 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7332] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 12:35:25,387 [salt.state       :300 ][INFO    ][7332] {'pid': 7339, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 12:35:25,389 [salt.state       :1951][INFO    ][7332] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:35:25.388899 duration_in_ms=1827.162
2019-04-24 12:35:25,392 [salt.state       :1780][INFO    ][7332] Running state [maas.deploy_machines] at time 12:35:25.391958
2019-04-24 12:35:25,392 [salt.state       :1813][INFO    ][7332] Executing state module.run for [maas.deploy_machines]
2019-04-24 12:35:25,394 [salt.utils.decorators:613 ][WARNING ][7332] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 12:35:25,956 [salt.loaded.ext.module.maas:684 ][INFO    ][7332] deploymachines hwe_kernel=hwe-16.04 system_id=mpscrq distro_series=xenial
2019-04-24 12:35:28,321 [salt.loaded.ext.module.maas:684 ][INFO    ][7332] deploymachines hwe_kernel=hwe-16.04 system_id=e8mgwm distro_series=xenial
2019-04-24 12:35:30,647 [salt.loaded.ext.module.maas:684 ][INFO    ][7332] deploymachines hwe_kernel=hwe-16.04 system_id=86e3t3 distro_series=xenial
2019-04-24 12:35:33,059 [salt.loaded.ext.module.maas:684 ][INFO    ][7332] deploymachines hwe_kernel=hwe-16.04 system_id=4pdsy6 distro_series=xenial
2019-04-24 12:35:35,430 [salt.loaded.ext.module.maas:684 ][INFO    ][7332] deploymachines hwe_kernel=hwe-16.04 system_id=hxs4wc distro_series=xenial
2019-04-24 12:35:37,597 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424123537586658
2019-04-24 12:35:37,616 [salt.minion      :1432][INFO    ][7602] Starting a new job with PID 7602
2019-04-24 12:35:37,645 [salt.minion      :1711][INFO    ][7602] Returning information for job: 20190424123537586658
2019-04-24 12:35:37,805 [salt.state       :300 ][INFO    ][7332] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-24 12:35:37,806 [salt.state       :1951][INFO    ][7332] Completed state [maas.deploy_machines] at time 12:35:37.806257 duration_in_ms=12414.3
2019-04-24 12:35:37,809 [salt.minion      :1711][INFO    ][7332] Returning information for job: 20190424123522460665
2019-04-24 12:35:38,507 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command state.apply with jid 20190424123538493550
2019-04-24 12:35:38,527 [salt.minion      :1432][INFO    ][7617] Starting a new job with PID 7617
2019-04-24 12:35:44,477 [salt.state       :915 ][INFO    ][7617] Loading fresh modules for state activity
2019-04-24 12:35:44,526 [salt.fileclient  :1219][INFO    ][7617] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-24 12:35:44,576 [salt.state       :1780][INFO    ][7617] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:35:44.576761
2019-04-24 12:35:44,577 [salt.state       :1813][INFO    ][7617] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 12:35:44,579 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7617] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 12:35:46,410 [salt.state       :300 ][INFO    ][7617] {'pid': 7637, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 12:35:46,410 [salt.state       :1951][INFO    ][7617] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:35:46.410718 duration_in_ms=1833.956
2019-04-24 12:35:46,413 [salt.state       :1780][INFO    ][7617] Running state [maas.wait_for_machine_status] at time 12:35:46.413796
2019-04-24 12:35:46,414 [salt.state       :1813][INFO    ][7617] Executing state module.run for [maas.wait_for_machine_status]
2019-04-24 12:35:46,414 [salt.utils.decorators:613 ][WARNING ][7617] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 12:35:48,820 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.60670209s left)
2019-04-24 12:35:53,537 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424123553520969
2019-04-24 12:35:53,561 [salt.minion      :1432][INFO    ][7649] Starting a new job with PID 7649
2019-04-24 12:35:53,589 [salt.minion      :1711][INFO    ][7649] Returning information for job: 20190424123553520969
2019-04-24 12:36:21,876 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.54927707s left)
2019-04-24 12:36:23,732 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424123623720586
2019-04-24 12:36:23,750 [salt.minion      :1432][INFO    ][7728] Starting a new job with PID 7728
2019-04-24 12:36:23,778 [salt.minion      :1711][INFO    ][7728] Returning information for job: 20190424123623720586
2019-04-24 12:36:53,779 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424123653770236
2019-04-24 12:36:53,793 [salt.minion      :1432][INFO    ][7762] Starting a new job with PID 7762
2019-04-24 12:36:53,819 [salt.minion      :1711][INFO    ][7762] Returning information for job: 20190424123653770236
2019-04-24 12:36:54,408 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2182.0167551s left)
2019-04-24 12:37:23,970 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424123723962698
2019-04-24 12:37:23,992 [salt.minion      :1432][INFO    ][7853] Starting a new job with PID 7853
2019-04-24 12:37:24,020 [salt.minion      :1711][INFO    ][7853] Returning information for job: 20190424123723962698
2019-04-24 12:37:27,359 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2149.06564498s left)
2019-04-24 12:37:54,037 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424123754029372
2019-04-24 12:37:54,058 [salt.minion      :1432][INFO    ][7879] Starting a new job with PID 7879
2019-04-24 12:37:54,089 [salt.minion      :1711][INFO    ][7879] Returning information for job: 20190424123754029372
2019-04-24 12:37:59,774 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2116.65082097s left)
2019-04-24 12:38:24,094 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424123824080496
2019-04-24 12:38:24,110 [salt.minion      :1432][INFO    ][7993] Starting a new job with PID 7993
2019-04-24 12:38:24,143 [salt.minion      :1711][INFO    ][7993] Returning information for job: 20190424123824080496
2019-04-24 12:38:32,253 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2084.17253804s left)
2019-04-24 12:38:54,162 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424123854147477
2019-04-24 12:38:54,178 [salt.minion      :1432][INFO    ][8069] Starting a new job with PID 8069
2019-04-24 12:38:54,204 [salt.minion      :1711][INFO    ][8069] Returning information for job: 20190424123854147477
2019-04-24 12:39:04,666 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2051.75933504s left)
2019-04-24 12:39:24,262 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424123924247984
2019-04-24 12:39:24,282 [salt.minion      :1432][INFO    ][8348] Starting a new job with PID 8348
2019-04-24 12:39:24,308 [salt.minion      :1711][INFO    ][8348] Returning information for job: 20190424123924247984
2019-04-24 12:39:37,642 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2018.78349113s left)
2019-04-24 12:39:54,317 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424123954308652
2019-04-24 12:39:54,331 [salt.minion      :1432][INFO    ][8459] Starting a new job with PID 8459
2019-04-24 12:39:54,357 [salt.minion      :1711][INFO    ][8459] Returning information for job: 20190424123954308652
2019-04-24 12:40:10,184 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1986.24144316s left)
2019-04-24 12:40:24,408 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424124024393590
2019-04-24 12:40:24,429 [salt.minion      :1432][INFO    ][8665] Starting a new job with PID 8665
2019-04-24 12:40:24,457 [salt.minion      :1711][INFO    ][8665] Returning information for job: 20190424124024393590
2019-04-24 12:40:43,102 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1953.32344699s left)
2019-04-24 12:40:54,514 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424124054497629
2019-04-24 12:40:54,533 [salt.minion      :1432][INFO    ][8761] Starting a new job with PID 8761
2019-04-24 12:40:54,560 [salt.minion      :1711][INFO    ][8761] Returning information for job: 20190424124054497629
2019-04-24 12:41:16,134 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1920.29101706s left)
2019-04-24 12:41:24,685 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424124124665654
2019-04-24 12:41:24,707 [salt.minion      :1432][INFO    ][9119] Starting a new job with PID 9119
2019-04-24 12:41:24,737 [salt.minion      :1711][INFO    ][9119] Returning information for job: 20190424124124665654
2019-04-24 12:41:48,820 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1887.60482502s left)
2019-04-24 12:41:54,773 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424124154765687
2019-04-24 12:41:54,790 [salt.minion      :1432][INFO    ][9287] Starting a new job with PID 9287
2019-04-24 12:41:54,816 [salt.minion      :1711][INFO    ][9287] Returning information for job: 20190424124154765687
2019-04-24 12:42:21,276 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1855.14908195s left)
2019-04-24 12:42:24,860 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424124224853206
2019-04-24 12:42:24,882 [salt.minion      :1432][INFO    ][9666] Starting a new job with PID 9666
2019-04-24 12:42:24,909 [salt.minion      :1711][INFO    ][9666] Returning information for job: 20190424124224853206
2019-04-24 12:42:53,815 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1822.61033201s left)
2019-04-24 12:42:54,873 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424124254865029
2019-04-24 12:42:54,895 [salt.minion      :1432][INFO    ][9748] Starting a new job with PID 9748
2019-04-24 12:42:54,923 [salt.minion      :1711][INFO    ][9748] Returning information for job: 20190424124254865029
2019-04-24 12:43:24,929 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424124324919759
2019-04-24 12:43:24,944 [salt.minion      :1432][INFO    ][9995] Starting a new job with PID 9995
2019-04-24 12:43:24,974 [salt.minion      :1711][INFO    ][9995] Returning information for job: 20190424124324919759
2019-04-24 12:43:26,549 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1789.87649798s left)
2019-04-24 12:43:55,063 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424124355049702
2019-04-24 12:43:55,086 [salt.minion      :1432][INFO    ][10094] Starting a new job with PID 10094
2019-04-24 12:43:55,112 [salt.minion      :1711][INFO    ][10094] Returning information for job: 20190424124355049702
2019-04-24 12:43:59,473 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1756.95219517s left)
2019-04-24 12:44:25,230 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424124425217510
2019-04-24 12:44:25,250 [salt.minion      :1432][INFO    ][10308] Starting a new job with PID 10308
2019-04-24 12:44:25,280 [salt.minion      :1711][INFO    ][10308] Returning information for job: 20190424124425217510
2019-04-24 12:44:31,913 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1724.51202202s left)
2019-04-24 12:44:55,361 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424124455350913
2019-04-24 12:44:55,380 [salt.minion      :1432][INFO    ][10396] Starting a new job with PID 10396
2019-04-24 12:44:55,410 [salt.minion      :1711][INFO    ][10396] Returning information for job: 20190424124455350913
2019-04-24 12:45:04,804 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1691.62073612s left)
2019-04-24 12:45:25,461 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424124525449983
2019-04-24 12:45:25,480 [salt.minion      :1432][INFO    ][10563] Starting a new job with PID 10563
2019-04-24 12:45:25,508 [salt.minion      :1711][INFO    ][10563] Returning information for job: 20190424124525449983
2019-04-24 12:45:37,381 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1659.04397297s left)
2019-04-24 12:45:55,554 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424124555541395
2019-04-24 12:45:55,577 [salt.minion      :1432][INFO    ][10663] Starting a new job with PID 10663
2019-04-24 12:45:55,607 [salt.minion      :1711][INFO    ][10663] Returning information for job: 20190424124555541395
2019-04-24 12:46:10,134 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1626.29150009s left)
2019-04-24 12:46:25,724 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424124625712255
2019-04-24 12:46:25,746 [salt.minion      :1432][INFO    ][10828] Starting a new job with PID 10828
2019-04-24 12:46:25,773 [salt.minion      :1711][INFO    ][10828] Returning information for job: 20190424124625712255
2019-04-24 12:46:42,762 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1593.66281796s left)
2019-04-24 12:46:55,892 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424124655880331
2019-04-24 12:46:55,914 [salt.minion      :1432][INFO    ][10861] Starting a new job with PID 10861
2019-04-24 12:46:55,943 [salt.minion      :1711][INFO    ][10861] Returning information for job: 20190424124655880331
2019-04-24 12:47:15,513 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1560.91205215s left)
2019-04-24 12:47:26,101 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424124726084733
2019-04-24 12:47:26,123 [salt.minion      :1432][INFO    ][11085] Starting a new job with PID 11085
2019-04-24 12:47:26,153 [salt.minion      :1711][INFO    ][11085] Returning information for job: 20190424124726084733
2019-04-24 12:47:48,156 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1528.26903796s left)
2019-04-24 12:47:56,300 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424124756291872
2019-04-24 12:47:56,317 [salt.minion      :1432][INFO    ][11132] Starting a new job with PID 11132
2019-04-24 12:47:56,347 [salt.minion      :1711][INFO    ][11132] Returning information for job: 20190424124756291872
2019-04-24 12:48:21,178 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1495.246907s left)
2019-04-24 12:48:26,501 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424124826486468
2019-04-24 12:48:26,519 [salt.minion      :1432][INFO    ][11264] Starting a new job with PID 11264
2019-04-24 12:48:26,549 [salt.minion      :1711][INFO    ][11264] Returning information for job: 20190424124826486468
2019-04-24 12:48:53,635 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1462.79072094s left)
2019-04-24 12:48:56,589 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424124856582116
2019-04-24 12:48:56,602 [salt.minion      :1432][INFO    ][11316] Starting a new job with PID 11316
2019-04-24 12:48:56,628 [salt.minion      :1711][INFO    ][11316] Returning information for job: 20190424124856582116
2019-04-24 12:49:26,421 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1430.00485611s left)
2019-04-24 12:49:26,624 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424124926613348
2019-04-24 12:49:26,646 [salt.minion      :1432][INFO    ][11582] Starting a new job with PID 11582
2019-04-24 12:49:26,674 [salt.minion      :1711][INFO    ][11582] Returning information for job: 20190424124926613348
2019-04-24 12:49:56,660 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424124956651604
2019-04-24 12:49:56,674 [salt.minion      :1432][INFO    ][11609] Starting a new job with PID 11609
2019-04-24 12:49:56,705 [salt.minion      :1711][INFO    ][11609] Returning information for job: 20190424124956651604
2019-04-24 12:49:59,095 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1397.32981396s left)
2019-04-24 12:50:26,690 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424125026676521
2019-04-24 12:50:26,712 [salt.minion      :1432][INFO    ][11685] Starting a new job with PID 11685
2019-04-24 12:50:26,744 [salt.minion      :1711][INFO    ][11685] Returning information for job: 20190424125026676521
2019-04-24 12:50:32,038 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1364.38726902s left)
2019-04-24 12:50:56,769 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424125056753880
2019-04-24 12:50:56,793 [salt.minion      :1432][INFO    ][11721] Starting a new job with PID 11721
2019-04-24 12:50:56,830 [salt.minion      :1711][INFO    ][11721] Returning information for job: 20190424125056753880
2019-04-24 12:51:04,529 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1331.896631s left)
2019-04-24 12:51:26,833 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424125126820454
2019-04-24 12:51:26,852 [salt.minion      :1432][INFO    ][11799] Starting a new job with PID 11799
2019-04-24 12:51:26,886 [salt.minion      :1711][INFO    ][11799] Returning information for job: 20190424125126820454
2019-04-24 12:51:37,236 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1299.18925309s left)
2019-04-24 12:51:56,918 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424125156908153
2019-04-24 12:51:56,937 [salt.minion      :1432][INFO    ][11832] Starting a new job with PID 11832
2019-04-24 12:51:56,970 [salt.minion      :1711][INFO    ][11832] Returning information for job: 20190424125156908153
2019-04-24 12:52:09,781 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1266.64359903s left)
2019-04-24 12:52:26,996 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424125226984682
2019-04-24 12:52:27,018 [salt.minion      :1432][INFO    ][11903] Starting a new job with PID 11903
2019-04-24 12:52:27,046 [salt.minion      :1711][INFO    ][11903] Returning information for job: 20190424125226984682
2019-04-24 12:52:42,738 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1233.68723416s left)
2019-04-24 12:52:57,110 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424125257097619
2019-04-24 12:52:57,131 [salt.minion      :1432][INFO    ][11936] Starting a new job with PID 11936
2019-04-24 12:52:57,156 [salt.minion      :1711][INFO    ][11936] Returning information for job: 20190424125257097619
2019-04-24 12:53:15,161 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1201.26444316s left)
2019-04-24 12:53:27,212 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424125327199753
2019-04-24 12:53:27,228 [salt.minion      :1432][INFO    ][12014] Starting a new job with PID 12014
2019-04-24 12:53:27,259 [salt.minion      :1711][INFO    ][12014] Returning information for job: 20190424125327199753
2019-04-24 12:53:47,778 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1168.64706302s left)
2019-04-24 12:53:57,335 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424125357323025
2019-04-24 12:53:57,356 [salt.minion      :1432][INFO    ][12051] Starting a new job with PID 12051
2019-04-24 12:53:57,390 [salt.minion      :1711][INFO    ][12051] Returning information for job: 20190424125357323025
2019-04-24 12:54:20,268 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1136.15756202s left)
2019-04-24 12:54:27,455 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424125427444080
2019-04-24 12:54:27,475 [salt.minion      :1432][INFO    ][12122] Starting a new job with PID 12122
2019-04-24 12:54:27,503 [salt.minion      :1711][INFO    ][12122] Returning information for job: 20190424125427444080
2019-04-24 12:54:53,069 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1103.35640717s left)
2019-04-24 12:54:57,612 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424125457604032
2019-04-24 12:54:57,632 [salt.minion      :1432][INFO    ][12155] Starting a new job with PID 12155
2019-04-24 12:54:57,661 [salt.minion      :1711][INFO    ][12155] Returning information for job: 20190424125457604032
2019-04-24 12:55:25,829 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1070.59595394s left)
2019-04-24 12:55:27,756 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424125527749804
2019-04-24 12:55:27,769 [salt.minion      :1432][INFO    ][12223] Starting a new job with PID 12223
2019-04-24 12:55:27,801 [salt.minion      :1711][INFO    ][12223] Returning information for job: 20190424125527749804
2019-04-24 12:55:57,942 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424125557934260
2019-04-24 12:55:57,959 [salt.minion      :1432][INFO    ][12255] Starting a new job with PID 12255
2019-04-24 12:55:57,988 [salt.minion      :1711][INFO    ][12255] Returning information for job: 20190424125557934260
2019-04-24 12:55:58,677 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1037.74802017s left)
2019-04-24 12:56:28,144 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424125628135997
2019-04-24 12:56:28,158 [salt.minion      :1432][INFO    ][12319] Starting a new job with PID 12319
2019-04-24 12:56:28,186 [salt.minion      :1711][INFO    ][12319] Returning information for job: 20190424125628135997
2019-04-24 12:56:31,264 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1005.16122007s left)
2019-04-24 12:56:58,325 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424125658318201
2019-04-24 12:56:58,340 [salt.minion      :1432][INFO    ][12356] Starting a new job with PID 12356
2019-04-24 12:56:58,366 [salt.minion      :1711][INFO    ][12356] Returning information for job: 20190424125658318201
2019-04-24 12:57:03,925 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (972.499752045s left)
2019-04-24 12:57:28,339 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424125728329964
2019-04-24 12:57:28,354 [salt.minion      :1432][INFO    ][12445] Starting a new job with PID 12445
2019-04-24 12:57:28,387 [salt.minion      :1711][INFO    ][12445] Returning information for job: 20190424125728329964
2019-04-24 12:57:36,414 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (940.010921955s left)
2019-04-24 12:57:58,565 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424125758549582
2019-04-24 12:57:58,584 [salt.minion      :1432][INFO    ][12484] Starting a new job with PID 12484
2019-04-24 12:57:58,614 [salt.minion      :1711][INFO    ][12484] Returning information for job: 20190424125758549582
2019-04-24 12:58:09,048 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (907.376743078s left)
2019-04-24 12:58:28,625 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424125828610453
2019-04-24 12:58:28,644 [salt.minion      :1432][INFO    ][12552] Starting a new job with PID 12552
2019-04-24 12:58:28,676 [salt.minion      :1711][INFO    ][12552] Returning information for job: 20190424125828610453
2019-04-24 12:58:41,653 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (874.771944046s left)
2019-04-24 12:58:58,673 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424125858660832
2019-04-24 12:58:58,691 [salt.minion      :1432][INFO    ][12585] Starting a new job with PID 12585
2019-04-24 12:58:58,724 [salt.minion      :1711][INFO    ][12585] Returning information for job: 20190424125858660832
2019-04-24 12:59:14,409 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (842.015830994s left)
2019-04-24 12:59:28,772 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424125928757722
2019-04-24 12:59:28,793 [salt.minion      :1432][INFO    ][12653] Starting a new job with PID 12653
2019-04-24 12:59:28,826 [salt.minion      :1711][INFO    ][12653] Returning information for job: 20190424125928757722
2019-04-24 12:59:47,149 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (809.276278973s left)
2019-04-24 12:59:58,853 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424125958841343
2019-04-24 12:59:58,870 [salt.minion      :1432][INFO    ][12686] Starting a new job with PID 12686
2019-04-24 12:59:58,905 [salt.minion      :1711][INFO    ][12686] Returning information for job: 20190424125958841343
2019-04-24 13:00:19,774 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (776.651349068s left)
2019-04-24 13:00:28,989 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424130028979467
2019-04-24 13:00:29,011 [salt.minion      :1432][INFO    ][12757] Starting a new job with PID 12757
2019-04-24 13:00:29,042 [salt.minion      :1711][INFO    ][12757] Returning information for job: 20190424130028979467
2019-04-24 13:00:52,448 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (743.978745937s left)
2019-04-24 13:00:59,107 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424130059094204
2019-04-24 13:00:59,129 [salt.minion      :1432][INFO    ][12790] Starting a new job with PID 12790
2019-04-24 13:00:59,158 [salt.minion      :1711][INFO    ][12790] Returning information for job: 20190424130059094204
2019-04-24 13:01:25,232 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (711.193048s left)
2019-04-24 13:01:29,286 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424130129272720
2019-04-24 13:01:29,303 [salt.minion      :1432][INFO    ][12859] Starting a new job with PID 12859
2019-04-24 13:01:29,332 [salt.minion      :1711][INFO    ][12859] Returning information for job: 20190424130129272720
2019-04-24 13:01:57,906 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (678.519142151s left)
2019-04-24 13:01:59,460 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424130159451904
2019-04-24 13:01:59,473 [salt.minion      :1432][INFO    ][12893] Starting a new job with PID 12893
2019-04-24 13:01:59,506 [salt.minion      :1711][INFO    ][12893] Returning information for job: 20190424130159451904
2019-04-24 13:02:29,624 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424130229615201
2019-04-24 13:02:29,640 [salt.minion      :1432][INFO    ][12969] Starting a new job with PID 12969
2019-04-24 13:02:29,673 [salt.minion      :1711][INFO    ][12969] Returning information for job: 20190424130229615201
2019-04-24 13:02:30,702 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (645.723052025s left)
2019-04-24 13:02:59,660 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424130259650783
2019-04-24 13:02:59,681 [salt.minion      :1432][INFO    ][12998] Starting a new job with PID 12998
2019-04-24 13:02:59,710 [salt.minion      :1711][INFO    ][12998] Returning information for job: 20190424130259650783
2019-04-24 13:03:03,410 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (613.015136957s left)
2019-04-24 13:03:29,841 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424130329828726
2019-04-24 13:03:29,861 [salt.minion      :1432][INFO    ][13068] Starting a new job with PID 13068
2019-04-24 13:03:29,888 [salt.minion      :1711][INFO    ][13068] Returning information for job: 20190424130329828726
2019-04-24 13:03:36,056 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (580.368983984s left)
2019-04-24 13:03:59,889 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424130359881801
2019-04-24 13:03:59,908 [salt.minion      :1432][INFO    ][13101] Starting a new job with PID 13101
2019-04-24 13:03:59,936 [salt.minion      :1711][INFO    ][13101] Returning information for job: 20190424130359881801
2019-04-24 13:04:08,678 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (547.747315168s left)
2019-04-24 13:04:29,912 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424130429895758
2019-04-24 13:04:29,932 [salt.minion      :1432][INFO    ][13169] Starting a new job with PID 13169
2019-04-24 13:04:29,961 [salt.minion      :1711][INFO    ][13169] Returning information for job: 20190424130429895758
2019-04-24 13:04:41,573 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (514.852137089s left)
2019-04-24 13:05:00,023 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424130500009151
2019-04-24 13:05:00,044 [salt.minion      :1432][INFO    ][13202] Starting a new job with PID 13202
2019-04-24 13:05:00,073 [salt.minion      :1711][INFO    ][13202] Returning information for job: 20190424130500009151
2019-04-24 13:05:14,360 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (482.065165043s left)
2019-04-24 13:05:30,089 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424130530073357
2019-04-24 13:05:30,108 [salt.minion      :1432][INFO    ][13270] Starting a new job with PID 13270
2019-04-24 13:05:30,140 [salt.minion      :1711][INFO    ][13270] Returning information for job: 20190424130530073357
2019-04-24 13:05:47,072 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (449.353516102s left)
2019-04-24 13:06:00,260 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424130600245890
2019-04-24 13:06:00,282 [salt.minion      :1432][INFO    ][13303] Starting a new job with PID 13303
2019-04-24 13:06:00,312 [salt.minion      :1711][INFO    ][13303] Returning information for job: 20190424130600245890
2019-04-24 13:06:19,734 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (416.690936089s left)
2019-04-24 13:06:30,334 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424130630327517
2019-04-24 13:06:30,355 [salt.minion      :1432][INFO    ][13376] Starting a new job with PID 13376
2019-04-24 13:06:30,383 [salt.minion      :1711][INFO    ][13376] Returning information for job: 20190424130630327517
2019-04-24 13:06:52,516 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (383.908931971s left)
2019-04-24 13:07:00,540 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424130700525754
2019-04-24 13:07:00,557 [salt.minion      :1432][INFO    ][13547] Starting a new job with PID 13547
2019-04-24 13:07:00,586 [salt.minion      :1711][INFO    ][13547] Returning information for job: 20190424130700525754
2019-04-24 13:07:25,104 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (351.320753098s left)
2019-04-24 13:07:30,682 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424130730667023
2019-04-24 13:07:30,702 [salt.minion      :1432][INFO    ][13631] Starting a new job with PID 13631
2019-04-24 13:07:30,735 [salt.minion      :1711][INFO    ][13631] Returning information for job: 20190424130730667023
2019-04-24 13:07:57,830 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (318.595165014s left)
2019-04-24 13:08:00,720 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424130800705679
2019-04-24 13:08:00,740 [salt.minion      :1432][INFO    ][13664] Starting a new job with PID 13664
2019-04-24 13:08:00,769 [salt.minion      :1711][INFO    ][13664] Returning information for job: 20190424130800705679
2019-04-24 13:08:30,414 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (286.01126194s left)
2019-04-24 13:08:30,752 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424130830745545
2019-04-24 13:08:30,765 [salt.minion      :1432][INFO    ][13733] Starting a new job with PID 13733
2019-04-24 13:08:30,792 [salt.minion      :1711][INFO    ][13733] Returning information for job: 20190424130830745545
2019-04-24 13:09:00,974 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424130900967145
2019-04-24 13:09:00,992 [salt.minion      :1432][INFO    ][13762] Starting a new job with PID 13762
2019-04-24 13:09:01,027 [salt.minion      :1711][INFO    ][13762] Returning information for job: 20190424130900967145
2019-04-24 13:09:03,310 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (253.115405083s left)
2019-04-24 13:09:31,076 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424130931060671
2019-04-24 13:09:31,094 [salt.minion      :1432][INFO    ][13832] Starting a new job with PID 13832
2019-04-24 13:09:31,126 [salt.minion      :1711][INFO    ][13832] Returning information for job: 20190424130931060671
2019-04-24 13:09:35,952 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (220.473493099s left)
2019-04-24 13:10:01,116 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424131001100948
2019-04-24 13:10:01,135 [salt.minion      :1432][INFO    ][13865] Starting a new job with PID 13865
2019-04-24 13:10:01,163 [salt.minion      :1711][INFO    ][13865] Returning information for job: 20190424131001100948
2019-04-24 13:10:08,624 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (187.801388025s left)
2019-04-24 13:10:31,242 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424131031228586
2019-04-24 13:10:31,264 [salt.minion      :1432][INFO    ][13935] Starting a new job with PID 13935
2019-04-24 13:10:31,293 [salt.minion      :1711][INFO    ][13935] Returning information for job: 20190424131031228586
2019-04-24 13:10:41,250 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (155.175270081s left)
2019-04-24 13:11:01,318 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424131101310359
2019-04-24 13:11:01,337 [salt.minion      :1432][INFO    ][13970] Starting a new job with PID 13970
2019-04-24 13:11:01,365 [salt.minion      :1711][INFO    ][13970] Returning information for job: 20190424131101310359
2019-04-24 13:11:14,048 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (122.377104998s left)
2019-04-24 13:11:31,485 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424131131471487
2019-04-24 13:11:31,505 [salt.minion      :1432][INFO    ][14039] Starting a new job with PID 14039
2019-04-24 13:11:31,534 [salt.minion      :1711][INFO    ][14039] Returning information for job: 20190424131131471487
2019-04-24 13:11:46,699 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (89.7265100479s left)
2019-04-24 13:12:01,588 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424131201575659
2019-04-24 13:12:01,607 [salt.minion      :1432][INFO    ][14076] Starting a new job with PID 14076
2019-04-24 13:12:01,633 [salt.minion      :1711][INFO    ][14076] Returning information for job: 20190424131201575659
2019-04-24 13:12:19,307 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (57.1177101135s left)
2019-04-24 13:12:31,761 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424131231748447
2019-04-24 13:12:31,782 [salt.minion      :1432][INFO    ][14148] Starting a new job with PID 14148
2019-04-24 13:12:31,810 [salt.minion      :1711][INFO    ][14148] Returning information for job: 20190424131231748447
2019-04-24 13:12:51,994 [salt.loaded.ext.module.maas:1023][INFO    ][7617] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (24.4313290119s left)
2019-04-24 13:13:01,902 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424131301892533
2019-04-24 13:13:01,922 [salt.minion      :1432][INFO    ][14181] Starting a new job with PID 14181
2019-04-24 13:13:01,952 [salt.minion      :1711][INFO    ][14181] Returning information for job: 20190424131301892533
2019-04-24 13:13:24,782 [salt.state       :302 ][ERROR   ][7617] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-04-24 13:13:24,782 [salt.state       :1951][INFO    ][7617] Completed state [maas.wait_for_machine_status] at time 13:13:24.782665 duration_in_ms=2258368.864
2019-04-24 13:13:24,787 [salt.minion      :1711][INFO    ][7617] Returning information for job: 20190424123538493550
2019-04-24 13:13:35,665 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command pillar.get with jid 20190424131335658637
2019-04-24 13:13:35,684 [salt.minion      :1432][INFO    ][14251] Starting a new job with PID 14251
2019-04-24 13:13:35,696 [salt.minion      :1711][INFO    ][14251] Returning information for job: 20190424131335658637
2019-04-24 13:13:36,380 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command service.status with jid 20190424131336368425
2019-04-24 13:13:36,402 [salt.minion      :1432][INFO    ][14256] Starting a new job with PID 14256
2019-04-24 13:13:37,145 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14256] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-24 13:13:37,183 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14256] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-24 13:13:37,202 [salt.minion      :1711][INFO    ][14256] Returning information for job: 20190424131336368425
2019-04-24 13:13:37,886 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command state.apply with jid 20190424131337875564
2019-04-24 13:13:37,907 [salt.minion      :1432][INFO    ][14267] Starting a new job with PID 14267
2019-04-24 13:13:43,778 [salt.state       :915 ][INFO    ][14267] Loading fresh modules for state activity
2019-04-24 13:13:44,332 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14267] Executing command 'salt-minion --version' in directory '/root'
2019-04-24 13:13:44,661 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14267] Executing command 'salt-minion --version' in directory '/root'
2019-04-24 13:13:45,627 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14267] Executing command 'salt-minion --version' in directory '/root'
2019-04-24 13:13:45,952 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14267] Executing command 'salt-minion --version' in directory '/root'
2019-04-24 13:13:48,076 [salt.state       :1780][INFO    ][14267] Running state [salt-minion] at time 13:13:48.076891
2019-04-24 13:13:48,077 [salt.state       :1813][INFO    ][14267] Executing state pkg.installed for [salt-minion]
2019-04-24 13:13:48,078 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14267] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-24 13:13:48,188 [salt.state       :300 ][INFO    ][14267] All specified packages are already installed
2019-04-24 13:13:48,189 [salt.state       :1951][INFO    ][14267] Completed state [salt-minion] at time 13:13:48.189069 duration_in_ms=112.178
2019-04-24 13:13:48,189 [salt.state       :1780][INFO    ][14267] Running state [salt_minion_dependency_packages] at time 13:13:48.189430
2019-04-24 13:13:48,189 [salt.state       :1813][INFO    ][14267] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-24 13:13:48,199 [salt.state       :300 ][INFO    ][14267] All specified packages are already installed
2019-04-24 13:13:48,200 [salt.state       :1951][INFO    ][14267] Completed state [salt_minion_dependency_packages] at time 13:13:48.200044 duration_in_ms=10.614
2019-04-24 13:13:48,203 [salt.state       :1780][INFO    ][14267] Running state [/etc/salt/minion.d/minion.conf] at time 13:13:48.203179
2019-04-24 13:13:48,203 [salt.state       :1813][INFO    ][14267] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-24 13:13:48,479 [salt.state       :300 ][INFO    ][14267] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-24 13:13:48,479 [salt.state       :1951][INFO    ][14267] Completed state [/etc/salt/minion.d/minion.conf] at time 13:13:48.479802 duration_in_ms=276.623
2019-04-24 13:13:48,480 [salt.state       :1780][INFO    ][14267] Running state [python-netaddr] at time 13:13:48.480098
2019-04-24 13:13:48,480 [salt.state       :1813][INFO    ][14267] Executing state pkg.installed for [python-netaddr]
2019-04-24 13:13:48,489 [salt.state       :300 ][INFO    ][14267] All specified packages are already installed
2019-04-24 13:13:48,490 [salt.state       :1951][INFO    ][14267] Completed state [python-netaddr] at time 13:13:48.490178 duration_in_ms=10.08
2019-04-24 13:13:48,493 [salt.state       :1780][INFO    ][14267] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 13:13:48.493394
2019-04-24 13:13:48,493 [salt.state       :1813][INFO    ][14267] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-24 13:13:48,502 [salt.state       :300 ][INFO    ][14267] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-24 13:13:48,503 [salt.state       :1951][INFO    ][14267] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 13:13:48.503076 duration_in_ms=9.681
2019-04-24 13:13:48,504 [salt.state       :1780][INFO    ][14267] Running state [salt-minion] at time 13:13:48.504300
2019-04-24 13:13:48,504 [salt.state       :1813][INFO    ][14267] Executing state service.running for [salt-minion]
2019-04-24 13:13:48,505 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14267] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-24 13:13:48,548 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14267] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-24 13:13:48,569 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14267] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-24 13:13:48,586 [salt.state       :300 ][INFO    ][14267] The service salt-minion is already running
2019-04-24 13:13:48,587 [salt.state       :1951][INFO    ][14267] Completed state [salt-minion] at time 13:13:48.587355 duration_in_ms=83.054
2019-04-24 13:13:48,589 [salt.state       :1780][INFO    ][14267] Running state [/etc/salt/grains.d] at time 13:13:48.589584
2019-04-24 13:13:48,590 [salt.state       :1813][INFO    ][14267] Executing state file.directory for [/etc/salt/grains.d]
2019-04-24 13:13:48,593 [salt.state       :300 ][INFO    ][14267] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-24 13:13:48,593 [salt.state       :1951][INFO    ][14267] Completed state [/etc/salt/grains.d] at time 13:13:48.593561 duration_in_ms=3.977
2019-04-24 13:13:48,594 [salt.state       :1780][INFO    ][14267] Running state [/etc/salt/grains] at time 13:13:48.594481
2019-04-24 13:13:48,595 [salt.state       :1813][INFO    ][14267] Executing state file.managed for [/etc/salt/grains]
2019-04-24 13:13:48,595 [salt.state       :300 ][INFO    ][14267] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-24 13:13:48,596 [salt.state       :1951][INFO    ][14267] Completed state [/etc/salt/grains] at time 13:13:48.596223 duration_in_ms=1.742
2019-04-24 13:13:48,596 [salt.state       :1780][INFO    ][14267] Running state [/etc/salt/grains.d/placeholder] at time 13:13:48.596887
2019-04-24 13:13:48,597 [salt.state       :1813][INFO    ][14267] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-24 13:13:48,598 [salt.state       :300 ][INFO    ][14267] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-24 13:13:48,598 [salt.state       :1951][INFO    ][14267] Completed state [/etc/salt/grains.d/placeholder] at time 13:13:48.598316 duration_in_ms=1.429
2019-04-24 13:13:48,599 [salt.state       :1780][INFO    ][14267] Running state [/etc/salt/grains.d/sphinx] at time 13:13:48.599307
2019-04-24 13:13:48,599 [salt.state       :1813][INFO    ][14267] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-24 13:13:48,601 [salt.state       :300 ][INFO    ][14267] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-24 13:13:48,601 [salt.state       :1951][INFO    ][14267] Completed state [/etc/salt/grains.d/sphinx] at time 13:13:48.601597 duration_in_ms=2.291
2019-04-24 13:13:48,605 [salt.state       :1780][INFO    ][14267] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 13:13:48.605339
2019-04-24 13:13:48,605 [salt.state       :1813][INFO    ][14267] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-24 13:13:48,605 [salt.state       :300 ][INFO    ][14267] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-24 13:13:48,606 [salt.state       :1951][INFO    ][14267] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 13:13:48.606173 duration_in_ms=0.834
2019-04-24 13:13:48,606 [salt.state       :1780][INFO    ][14267] Running state [/etc/salt/grains.d/dns_records] at time 13:13:48.606692
2019-04-24 13:13:48,607 [salt.state       :1813][INFO    ][14267] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-24 13:13:48,608 [salt.state       :300 ][INFO    ][14267] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-24 13:13:48,608 [salt.state       :1951][INFO    ][14267] Completed state [/etc/salt/grains.d/dns_records] at time 13:13:48.608619 duration_in_ms=1.926
2019-04-24 13:13:48,609 [salt.state       :1780][INFO    ][14267] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 13:13:48.609570
2019-04-24 13:13:48,609 [salt.state       :1813][INFO    ][14267] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-04-24 13:13:48,610 [salt.state       :300 ][INFO    ][14267] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-24 13:13:48,610 [salt.state       :1951][INFO    ][14267] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 13:13:48.610391 duration_in_ms=0.821
2019-04-24 13:13:48,610 [salt.state       :1780][INFO    ][14267] Running state [/etc/salt/grains.d/salt] at time 13:13:48.610912
2019-04-24 13:13:48,611 [salt.state       :1813][INFO    ][14267] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-24 13:13:48,612 [salt.state       :300 ][INFO    ][14267] File /etc/salt/grains.d/salt is in the correct state
2019-04-24 13:13:48,612 [salt.state       :1951][INFO    ][14267] Completed state [/etc/salt/grains.d/salt] at time 13:13:48.612458 duration_in_ms=1.546
2019-04-24 13:13:48,613 [salt.state       :1780][INFO    ][14267] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 13:13:48.613367
2019-04-24 13:13:48,613 [salt.state       :1813][INFO    ][14267] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-24 13:13:48,613 [salt.state       :300 ][INFO    ][14267] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-24 13:13:48,614 [salt.state       :1951][INFO    ][14267] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 13:13:48.614176 duration_in_ms=0.809
2019-04-24 13:13:48,617 [salt.state       :1780][INFO    ][14267] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 13:13:48.617408
2019-04-24 13:13:48,617 [salt.state       :1813][INFO    ][14267] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-24 13:13:48,618 [salt.state       :300 ][INFO    ][14267] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-24 13:13:48,618 [salt.state       :1951][INFO    ][14267] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 13:13:48.618234 duration_in_ms=0.826
2019-04-24 13:13:48,619 [salt.state       :1780][INFO    ][14267] Running state [mine.update] at time 13:13:48.619223
2019-04-24 13:13:48,619 [salt.state       :1813][INFO    ][14267] Executing state module.wait for [mine.update]
2019-04-24 13:13:48,619 [salt.state       :300 ][INFO    ][14267] No changes made for mine.update
2019-04-24 13:13:48,620 [salt.state       :1951][INFO    ][14267] Completed state [mine.update] at time 13:13:48.619998 duration_in_ms=0.775
2019-04-24 13:13:48,620 [salt.state       :1780][INFO    ][14267] Running state [ca-certificates] at time 13:13:48.620268
2019-04-24 13:13:48,620 [salt.state       :1813][INFO    ][14267] Executing state pkg.installed for [ca-certificates]
2019-04-24 13:13:48,630 [salt.state       :300 ][INFO    ][14267] All specified packages are already installed
2019-04-24 13:13:48,631 [salt.state       :1951][INFO    ][14267] Completed state [ca-certificates] at time 13:13:48.631333 duration_in_ms=11.065
2019-04-24 13:13:48,632 [salt.state       :1780][INFO    ][14267] Running state [update-ca-certificates] at time 13:13:48.632053
2019-04-24 13:13:48,632 [salt.state       :1813][INFO    ][14267] Executing state cmd.wait for [update-ca-certificates]
2019-04-24 13:13:48,632 [salt.state       :300 ][INFO    ][14267] No changes made for update-ca-certificates
2019-04-24 13:13:48,632 [salt.state       :1951][INFO    ][14267] Completed state [update-ca-certificates] at time 13:13:48.632854 duration_in_ms=0.801
2019-04-24 13:13:48,633 [salt.state       :1780][INFO    ][14267] Running state [iptables] at time 13:13:48.633123
2019-04-24 13:13:48,633 [salt.state       :1813][INFO    ][14267] Executing state pkg.installed for [iptables]
2019-04-24 13:13:48,642 [salt.state       :300 ][INFO    ][14267] All specified packages are already installed
2019-04-24 13:13:48,642 [salt.state       :1951][INFO    ][14267] Completed state [iptables] at time 13:13:48.642910 duration_in_ms=9.787
2019-04-24 13:13:48,643 [salt.state       :1780][INFO    ][14267] Running state [iptables-persistent] at time 13:13:48.643191
2019-04-24 13:13:48,643 [salt.state       :1813][INFO    ][14267] Executing state pkg.installed for [iptables-persistent]
2019-04-24 13:13:48,652 [salt.state       :300 ][INFO    ][14267] All specified packages are already installed
2019-04-24 13:13:48,653 [salt.state       :1951][INFO    ][14267] Completed state [iptables-persistent] at time 13:13:48.653086 duration_in_ms=9.895
2019-04-24 13:13:48,654 [salt.state       :1780][INFO    ][14267] Running state [iptables_modules_v4_load] at time 13:13:48.654111
2019-04-24 13:13:48,654 [salt.state       :1813][INFO    ][14267] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-24 13:13:48,655 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14267] Executing command 'lsmod' in directory '/root'
2019-04-24 13:13:48,678 [salt.state       :300 ][INFO    ][14267] Kernel modules iptable_filter, ip_tables are already present
2019-04-24 13:13:48,678 [salt.state       :1951][INFO    ][14267] Completed state [iptables_modules_v4_load] at time 13:13:48.678818 duration_in_ms=24.706
2019-04-24 13:13:48,680 [salt.state       :1780][INFO    ][14267] Running state [/etc/iptables/rules.v4] at time 13:13:48.680068
2019-04-24 13:13:48,680 [salt.state       :1813][INFO    ][14267] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-24 13:13:48,780 [salt.state       :300 ][INFO    ][14267] File /etc/iptables/rules.v4 is in the correct state
2019-04-24 13:13:48,780 [salt.state       :1951][INFO    ][14267] Completed state [/etc/iptables/rules.v4] at time 13:13:48.780514 duration_in_ms=100.446
2019-04-24 13:13:48,783 [salt.state       :1780][INFO    ][14267] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 13:13:48.782763
2019-04-24 13:13:48,783 [salt.state       :1813][INFO    ][14267] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-24 13:13:48,784 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14267] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-24 13:13:48,801 [salt.state       :300 ][INFO    ][14267] onlyif execution failed
2019-04-24 13:13:48,802 [salt.state       :1951][INFO    ][14267] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 13:13:48.802363 duration_in_ms=19.6
2019-04-24 13:13:48,804 [salt.state       :1780][INFO    ][14267] Running state [netfilter-persistent] at time 13:13:48.804351
2019-04-24 13:13:48,804 [salt.state       :1813][INFO    ][14267] Executing state service.running for [netfilter-persistent]
2019-04-24 13:13:48,806 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14267] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-24 13:13:48,828 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14267] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-24 13:13:48,846 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14267] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-24 13:13:48,863 [salt.state       :300 ][INFO    ][14267] The service netfilter-persistent is already running
2019-04-24 13:13:48,863 [salt.state       :1951][INFO    ][14267] Completed state [netfilter-persistent] at time 13:13:48.863868 duration_in_ms=59.517
2019-04-24 13:13:48,865 [salt.state       :1780][INFO    ][14267] Running state [iptables_extra.remove_stale_tables] at time 13:13:48.865396
2019-04-24 13:13:48,865 [salt.state       :1813][INFO    ][14267] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-24 13:13:48,866 [salt.state       :300 ][INFO    ][14267] No changes made for iptables_extra.remove_stale_tables
2019-04-24 13:13:48,866 [salt.state       :1951][INFO    ][14267] Completed state [iptables_extra.remove_stale_tables] at time 13:13:48.866722 duration_in_ms=1.326
2019-04-24 13:13:48,868 [salt.state       :1780][INFO    ][14267] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 13:13:48.868890
2019-04-24 13:13:48,869 [salt.state       :1813][INFO    ][14267] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-24 13:13:48,870 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14267] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-24 13:13:48,886 [salt.state       :300 ][INFO    ][14267] onlyif execution failed
2019-04-24 13:13:48,886 [salt.state       :1951][INFO    ][14267] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 13:13:48.886466 duration_in_ms=17.576
2019-04-24 13:13:48,888 [salt.state       :1780][INFO    ][14267] Running state [/etc/iptables/rules.v6] at time 13:13:48.887931
2019-04-24 13:13:48,888 [salt.state       :1813][INFO    ][14267] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-24 13:13:48,889 [salt.state       :300 ][INFO    ][14267] File /etc/iptables/rules.v6 is not present
2019-04-24 13:13:48,889 [salt.state       :1951][INFO    ][14267] Completed state [/etc/iptables/rules.v6] at time 13:13:48.889314 duration_in_ms=1.383
2019-04-24 13:13:48,890 [salt.state       :1780][INFO    ][14267] Running state [iptables_extra.flush_all] at time 13:13:48.890319
2019-04-24 13:13:48,890 [salt.state       :1813][INFO    ][14267] Executing state module.wait for [iptables_extra.flush_all]
2019-04-24 13:13:48,892 [salt.state       :300 ][INFO    ][14267] No changes made for iptables_extra.flush_all
2019-04-24 13:13:48,893 [salt.state       :1951][INFO    ][14267] Completed state [iptables_extra.flush_all] at time 13:13:48.893164 duration_in_ms=2.845
2019-04-24 13:13:48,897 [salt.minion      :1711][INFO    ][14267] Returning information for job: 20190424131337875564
2019-04-24 13:13:49,590 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command state.apply with jid 20190424131349578308
2019-04-24 13:13:49,608 [salt.minion      :1432][INFO    ][14352] Starting a new job with PID 14352
2019-04-24 13:13:50,540 [salt.state       :915 ][INFO    ][14352] Loading fresh modules for state activity
2019-04-24 13:13:51,765 [salt.state       :1780][INFO    ][14352] Running state [maas-rack-controller] at time 13:13:51.765374
2019-04-24 13:13:51,765 [salt.state       :1813][INFO    ][14352] Executing state pkg.installed for [maas-rack-controller]
2019-04-24 13:13:51,766 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14352] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-24 13:13:51,872 [salt.state       :300 ][INFO    ][14352] All specified packages are already installed
2019-04-24 13:13:51,873 [salt.state       :1951][INFO    ][14352] Completed state [maas-rack-controller] at time 13:13:51.873126 duration_in_ms=107.752
2019-04-24 13:13:51,873 [salt.state       :1780][INFO    ][14352] Running state [ipmitool] at time 13:13:51.873482
2019-04-24 13:13:51,873 [salt.state       :1813][INFO    ][14352] Executing state pkg.installed for [ipmitool]
2019-04-24 13:13:51,883 [salt.state       :300 ][INFO    ][14352] All specified packages are already installed
2019-04-24 13:13:51,884 [salt.state       :1951][INFO    ][14352] Completed state [ipmitool] at time 13:13:51.884138 duration_in_ms=10.656
2019-04-24 13:13:51,887 [salt.state       :1780][INFO    ][14352] Running state [/etc/maas/rackd.conf] at time 13:13:51.886974
2019-04-24 13:13:51,887 [salt.state       :1813][INFO    ][14352] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-24 13:13:51,888 [salt.state       :300 ][INFO    ][14352] No changes needed to be made
2019-04-24 13:13:51,888 [salt.state       :1951][INFO    ][14352] Completed state [/etc/maas/rackd.conf] at time 13:13:51.888446 duration_in_ms=1.472
2019-04-24 13:13:51,888 [salt.state       :1780][INFO    ][14352] Running state [/etc/maas/rackd.conf] at time 13:13:51.888726
2019-04-24 13:13:51,889 [salt.state       :1813][INFO    ][14352] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-24 13:13:51,889 [salt.loaded.int.states.file:2298][WARNING ][14352] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-24 13:13:51,890 [salt.state       :300 ][INFO    ][14352] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-24 13:13:51,890 [salt.state       :1951][INFO    ][14352] Completed state [/etc/maas/rackd.conf] at time 13:13:51.890341 duration_in_ms=1.614
2019-04-24 13:13:51,892 [salt.state       :1780][INFO    ][14352] Running state [maas-rackd] at time 13:13:51.892042
2019-04-24 13:13:51,892 [salt.state       :1813][INFO    ][14352] Executing state service.running for [maas-rackd]
2019-04-24 13:13:51,893 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14352] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-24 13:13:51,931 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14352] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-24 13:13:51,947 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14352] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-24 13:13:51,968 [salt.state       :300 ][INFO    ][14352] The service maas-rackd is already running
2019-04-24 13:13:51,968 [salt.state       :1951][INFO    ][14352] Completed state [maas-rackd] at time 13:13:51.968823 duration_in_ms=76.781
2019-04-24 13:13:51,970 [salt.minion      :1711][INFO    ][14352] Returning information for job: 20190424131349578308
2019-04-24 13:13:52,641 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command state.apply with jid 20190424131352632175
2019-04-24 13:13:52,662 [salt.minion      :1432][INFO    ][14375] Starting a new job with PID 14375
2019-04-24 13:13:53,620 [salt.state       :915 ][INFO    ][14375] Loading fresh modules for state activity
2019-04-24 13:13:54,978 [salt.state       :1780][INFO    ][14375] Running state [maas-region-controller] at time 13:13:54.978641
2019-04-24 13:13:54,979 [salt.state       :1813][INFO    ][14375] Executing state pkg.installed for [maas-region-controller]
2019-04-24 13:13:54,980 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14375] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-24 13:13:55,091 [salt.state       :300 ][INFO    ][14375] All specified packages are already installed
2019-04-24 13:13:55,092 [salt.state       :1951][INFO    ][14375] Completed state [maas-region-controller] at time 13:13:55.091847 duration_in_ms=113.206
2019-04-24 13:13:55,092 [salt.state       :1780][INFO    ][14375] Running state [python-oauth] at time 13:13:55.092523
2019-04-24 13:13:55,093 [salt.state       :1813][INFO    ][14375] Executing state pkg.installed for [python-oauth]
2019-04-24 13:13:55,103 [salt.state       :300 ][INFO    ][14375] All specified packages are already installed
2019-04-24 13:13:55,104 [salt.state       :1951][INFO    ][14375] Completed state [python-oauth] at time 13:13:55.104246 duration_in_ms=11.724
2019-04-24 13:13:55,108 [salt.state       :1780][INFO    ][14375] Running state [/etc/maas/regiond.conf] at time 13:13:55.108539
2019-04-24 13:13:55,108 [salt.state       :1813][INFO    ][14375] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-24 13:13:55,113 [salt.state       :300 ][INFO    ][14375] No changes needed to be made
2019-04-24 13:13:55,113 [salt.state       :1951][INFO    ][14375] Completed state [/etc/maas/regiond.conf] at time 13:13:55.113736 duration_in_ms=5.197
2019-04-24 13:13:55,114 [salt.state       :1780][INFO    ][14375] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 13:13:55.114270
2019-04-24 13:13:55,114 [salt.state       :1813][INFO    ][14375] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-24 13:13:55,172 [salt.state       :300 ][INFO    ][14375] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-24 13:13:55,173 [salt.state       :1951][INFO    ][14375] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 13:13:55.173024 duration_in_ms=58.754
2019-04-24 13:13:55,173 [salt.state       :1780][INFO    ][14375] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 13:13:55.173577
2019-04-24 13:13:55,173 [salt.state       :1813][INFO    ][14375] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-24 13:13:55,178 [salt.state       :300 ][INFO    ][14375] No changes needed to be made
2019-04-24 13:13:55,178 [salt.state       :1951][INFO    ][14375] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 13:13:55.178422 duration_in_ms=4.845
2019-04-24 13:13:55,179 [salt.state       :1780][INFO    ][14375] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 13:13:55.179271
2019-04-24 13:13:55,179 [salt.state       :1813][INFO    ][14375] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-24 13:13:55,183 [salt.state       :300 ][INFO    ][14375] No changes needed to be made
2019-04-24 13:13:55,184 [salt.state       :1951][INFO    ][14375] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 13:13:55.184007 duration_in_ms=4.736
2019-04-24 13:13:55,184 [salt.state       :1780][INFO    ][14375] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 13:13:55.184534
2019-04-24 13:13:55,184 [salt.state       :1813][INFO    ][14375] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-24 13:13:55,209 [salt.state       :300 ][INFO    ][14375] No changes needed to be made
2019-04-24 13:13:55,209 [salt.state       :1951][INFO    ][14375] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 13:13:55.209906 duration_in_ms=25.371
2019-04-24 13:13:55,210 [salt.state       :1780][INFO    ][14375] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 13:13:55.210437
2019-04-24 13:13:55,210 [salt.state       :1813][INFO    ][14375] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-24 13:13:55,225 [salt.state       :300 ][INFO    ][14375] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-24 13:13:55,225 [salt.state       :1951][INFO    ][14375] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 13:13:55.225896 duration_in_ms=15.459
2019-04-24 13:13:55,229 [salt.state       :1780][INFO    ][14375] Running state [a2enmod headers] at time 13:13:55.228804
2019-04-24 13:13:55,229 [salt.state       :1813][INFO    ][14375] Executing state cmd.run for [a2enmod headers]
2019-04-24 13:13:55,230 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14375] Executing command 'a2enmod headers' in directory '/root'
2019-04-24 13:13:55,304 [salt.state       :300 ][INFO    ][14375] {'pid': 14394, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-24 13:13:55,305 [salt.state       :1951][INFO    ][14375] Completed state [a2enmod headers] at time 13:13:55.304999 duration_in_ms=76.196
2019-04-24 13:13:55,305 [salt.state       :1780][INFO    ][14375] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 13:13:55.305396
2019-04-24 13:13:55,305 [salt.state       :1813][INFO    ][14375] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-24 13:13:55,321 [salt.state       :300 ][INFO    ][14375] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-24 13:13:55,322 [salt.state       :1951][INFO    ][14375] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 13:13:55.321965 duration_in_ms=16.569
2019-04-24 13:13:55,322 [salt.state       :1780][INFO    ][14375] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 13:13:55.322612
2019-04-24 13:13:55,326 [salt.state       :1813][INFO    ][14375] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-24 13:13:55,377 [salt.state       :300 ][INFO    ][14375] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-24 13:13:55,377 [salt.state       :1951][INFO    ][14375] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 13:13:55.377878 duration_in_ms=55.266
2019-04-24 13:13:55,378 [salt.state       :1780][INFO    ][14375] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 13:13:55.378423
2019-04-24 13:13:55,378 [salt.state       :1813][INFO    ][14375] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-24 13:13:55,435 [salt.state       :300 ][INFO    ][14375] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-24 13:13:55,435 [salt.state       :1951][INFO    ][14375] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 13:13:55.435716 duration_in_ms=57.291
2019-04-24 13:13:55,436 [salt.state       :1780][INFO    ][14375] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 13:13:55.436271
2019-04-24 13:13:55,436 [salt.state       :1813][INFO    ][14375] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-24 13:13:55,503 [salt.state       :300 ][INFO    ][14375] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-24 13:13:55,503 [salt.state       :1951][INFO    ][14375] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 13:13:55.503847 duration_in_ms=67.576
2019-04-24 13:13:55,504 [salt.state       :1780][INFO    ][14375] Running state [/root/.pgpass] at time 13:13:55.504137
2019-04-24 13:13:55,504 [salt.state       :1813][INFO    ][14375] Executing state file.managed for [/root/.pgpass]
2019-04-24 13:13:55,549 [salt.state       :300 ][INFO    ][14375] File /root/.pgpass is in the correct state
2019-04-24 13:13:55,549 [salt.state       :1951][INFO    ][14375] Completed state [/root/.pgpass] at time 13:13:55.549507 duration_in_ms=45.37
2019-04-24 13:13:55,555 [salt.state       :1780][INFO    ][14375] Running state [maas-region syncdb --noinput] at time 13:13:55.555648
2019-04-24 13:13:55,555 [salt.state       :1813][INFO    ][14375] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-24 13:13:55,556 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14375] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-24 13:13:58,160 [salt.state       :300 ][INFO    ][14375] {'pid': 14407, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: auth, sites, contenttypes, sessions, piston3, metadataserver, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-24 13:13:58,161 [salt.state       :1951][INFO    ][14375] Completed state [maas-region syncdb --noinput] at time 13:13:58.160964 duration_in_ms=2605.316
2019-04-24 13:13:58,161 [salt.state       :2022][WARNING ][14375] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-24 13:13:58,163 [salt.state       :1780][INFO    ][14375] Running state [maas-regiond] at time 13:13:58.163338
2019-04-24 13:13:58,163 [salt.state       :1813][INFO    ][14375] Executing state service.running for [maas-regiond]
2019-04-24 13:13:58,164 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14375] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-24 13:13:58,187 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14375] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-24 13:13:58,198 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14375] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-24 13:13:58,209 [salt.state       :300 ][INFO    ][14375] The service maas-regiond is already running
2019-04-24 13:13:58,210 [salt.state       :1951][INFO    ][14375] Completed state [maas-regiond] at time 13:13:58.209919 duration_in_ms=46.581
2019-04-24 13:13:58,211 [salt.state       :1780][INFO    ][14375] Running state [bind9] at time 13:13:58.211668
2019-04-24 13:13:58,212 [salt.state       :1813][INFO    ][14375] Executing state service.running for [bind9]
2019-04-24 13:13:58,212 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14375] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-24 13:13:58,225 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14375] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-24 13:13:58,237 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14375] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-24 13:13:58,250 [salt.state       :300 ][INFO    ][14375] The service bind9 is already running
2019-04-24 13:13:58,250 [salt.state       :1951][INFO    ][14375] Completed state [bind9] at time 13:13:58.250468 duration_in_ms=38.8
2019-04-24 13:13:58,254 [salt.state       :1780][INFO    ][14375] Running state [apache2] at time 13:13:58.254238
2019-04-24 13:13:58,254 [salt.state       :1813][INFO    ][14375] Executing state service.running for [apache2]
2019-04-24 13:13:58,255 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14375] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-24 13:13:58,270 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14375] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-24 13:13:58,284 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14375] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-24 13:13:58,302 [salt.state       :300 ][INFO    ][14375] The service apache2 is already running
2019-04-24 13:13:58,303 [salt.state       :1951][INFO    ][14375] Completed state [apache2] at time 13:13:58.303386 duration_in_ms=49.148
2019-04-24 13:13:58,304 [salt.state       :1780][INFO    ][14375] Running state [maasng.wait_for_http_code] at time 13:13:58.304602
2019-04-24 13:13:58,305 [salt.state       :1813][INFO    ][14375] Executing state module.run for [maasng.wait_for_http_code]
2019-04-24 13:13:58,305 [salt.utils.decorators:613 ][WARNING ][14375] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 13:13:58,426 [salt.state       :300 ][INFO    ][14375] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-24 13:13:58,426 [salt.state       :1951][INFO    ][14375] Completed state [maasng.wait_for_http_code] at time 13:13:58.426353 duration_in_ms=121.75
2019-04-24 13:13:58,428 [salt.state       :1780][INFO    ][14375] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 13:13:58.428079
2019-04-24 13:13:58,428 [salt.state       :1813][INFO    ][14375] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-24 13:13:58,428 [salt.state       :300 ][INFO    ][14375] /var/lib/maas/.setup_admin exists
2019-04-24 13:13:58,429 [salt.state       :1951][INFO    ][14375] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 13:13:58.429127 duration_in_ms=1.049
2019-04-24 13:13:58,430 [salt.state       :1780][INFO    ][14375] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:13:58.430165
2019-04-24 13:13:58,430 [salt.state       :1813][INFO    ][14375] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 13:13:58,432 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14375] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 13:14:00,239 [salt.state       :300 ][INFO    ][14375] {'pid': 14441, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 13:14:00,240 [salt.state       :1951][INFO    ][14375] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:14:00.240264 duration_in_ms=1810.098
2019-04-24 13:14:00,246 [salt.state       :1780][INFO    ][14375] Running state [maas_region_boot_source_resources_mirror] at time 13:14:00.246469
2019-04-24 13:14:00,246 [salt.state       :1813][INFO    ][14375] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-24 13:14:00,307 [salt.state       :300 ][INFO    ][14375] {'changes': {}}
2019-04-24 13:14:00,307 [salt.state       :1951][INFO    ][14375] Completed state [maas_region_boot_source_resources_mirror] at time 13:14:00.307532 duration_in_ms=61.063
2019-04-24 13:14:00,308 [salt.state       :1780][INFO    ][14375] Running state [maasng.boot_resources_import] at time 13:14:00.308232
2019-04-24 13:14:00,308 [salt.state       :1813][INFO    ][14375] Executing state module.run for [maasng.boot_resources_import]
2019-04-24 13:14:00,308 [salt.utils.decorators:613 ][WARNING ][14375] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 13:14:00,371 [salt.loaded.ext.module.maasng:1600][INFO    ][14375] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-24 13:14:05,404 [salt.loaded.ext.module.maasng:1600][INFO    ][14375] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-24 13:14:07,670 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424131407661569
2019-04-24 13:14:07,690 [salt.minion      :1432][INFO    ][14598] Starting a new job with PID 14598
2019-04-24 13:14:07,720 [salt.minion      :1711][INFO    ][14598] Returning information for job: 20190424131407661569
2019-04-24 13:14:10,505 [salt.state       :300 ][INFO    ][14375] {'ret': True}
2019-04-24 13:14:10,505 [salt.state       :1951][INFO    ][14375] Completed state [maasng.boot_resources_import] at time 13:14:10.505692 duration_in_ms=10197.458
2019-04-24 13:14:10,507 [salt.state       :1780][INFO    ][14375] Running state [maas_region_boot_sources_selection_xenial] at time 13:14:10.507329
2019-04-24 13:14:10,507 [salt.state       :1813][INFO    ][14375] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-24 13:14:10,670 [salt.state       :300 ][INFO    ][14375] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-24 13:14:10,670 [salt.state       :1951][INFO    ][14375] Completed state [maas_region_boot_sources_selection_xenial] at time 13:14:10.670589 duration_in_ms=163.26
2019-04-24 13:14:10,673 [salt.state       :1780][INFO    ][14375] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 13:14:10.673257
2019-04-24 13:14:10,673 [salt.state       :1813][INFO    ][14375] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-24 13:14:10,674 [salt.utils.decorators:613 ][WARNING ][14375] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 13:14:10,675 [salt.loaded.ext.module.maasng:1771][INFO    ][14375] boot-sources sync initiated for ALL Rack's
2019-04-24 13:14:11,692 [salt.state       :300 ][INFO    ][14375] {'ret': True}
2019-04-24 13:14:11,692 [salt.state       :1951][INFO    ][14375] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 13:14:11.692805 duration_in_ms=1019.548
2019-04-24 13:14:11,694 [salt.state       :1780][INFO    ][14375] Running state [maas.process_maas_config] at time 13:14:11.694654
2019-04-24 13:14:11,695 [salt.state       :1813][INFO    ][14375] Executing state module.run for [maas.process_maas_config]
2019-04-24 13:14:11,695 [salt.utils.decorators:613 ][WARNING ][14375] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 13:14:11,696 [salt.loaded.ext.module.maas:92  ][INFO    ][14375] maasconfig name=enable_http_proxy value=True
2019-04-24 13:14:11,734 [salt.loaded.ext.module.maas:92  ][INFO    ][14375] maasconfig name=upstream_dns value=8.8.8.8
2019-04-24 13:14:11,786 [salt.loaded.ext.module.maas:92  ][INFO    ][14375] maasconfig name=commissioning_distro_series value=xenial
2019-04-24 13:14:11,838 [salt.loaded.ext.module.maas:92  ][INFO    ][14375] maasconfig name=default_osystem value=ubuntu
2019-04-24 13:14:11,878 [salt.loaded.ext.module.maas:92  ][INFO    ][14375] maasconfig name=active_discovery_interval value=600
2019-04-24 13:14:11,918 [salt.loaded.ext.module.maas:92  ][INFO    ][14375] maasconfig name=dnssec_validation value=no
2019-04-24 13:14:11,957 [salt.loaded.ext.module.maas:92  ][INFO    ][14375] maasconfig name=maas_name value=mas01
2019-04-24 13:14:11,999 [salt.loaded.ext.module.maas:92  ][INFO    ][14375] maasconfig name=network_discovery value=enabled
2019-04-24 13:14:12,076 [salt.loaded.ext.module.maas:92  ][INFO    ][14375] maasconfig name=enable_third_party_drivers value=True
2019-04-24 13:14:12,121 [salt.loaded.ext.module.maas:92  ][INFO    ][14375] maasconfig name=default_storage_layout value=lvm
2019-04-24 13:14:12,175 [salt.loaded.ext.module.maas:92  ][INFO    ][14375] maasconfig name=ntp_external_only value=True
2019-04-24 13:14:13,308 [salt.loaded.ext.module.maas:92  ][INFO    ][14375] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-24 13:14:13,365 [salt.loaded.ext.module.maas:92  ][INFO    ][14375] maasconfig name=default_distro_series value=xenial
2019-04-24 13:14:13,420 [salt.loaded.ext.module.maas:92  ][INFO    ][14375] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-04-24 13:14:13,530 [salt.state       :300 ][INFO    ][14375] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-04-24 13:14:13,530 [salt.state       :1951][INFO    ][14375] Completed state [maas.process_maas_config] at time 13:14:13.530471 duration_in_ms=1835.816
2019-04-24 13:14:13,531 [salt.state       :1780][INFO    ][14375] Running state [pxe_admin] at time 13:14:13.531878
2019-04-24 13:14:13,532 [salt.state       :1813][INFO    ][14375] Executing state maasng.fabric_present for [pxe_admin]
2019-04-24 13:14:13,583 [salt.loaded.ext.module.maasng:945 ][INFO    ][14375] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qw4tfr', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-04-24 13:14:13,641 [salt.loaded.ext.module.maasng:1008][WARNING ][14375] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-24 13:14:13,641 [salt.loaded.ext.module.maasng:1011][WARNING ][14375] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-24 13:14:13,701 [salt.state       :300 ][INFO    ][14375] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-24 13:14:13,701 [salt.state       :1951][INFO    ][14375] Completed state [pxe_admin] at time 13:14:13.701693 duration_in_ms=169.815
2019-04-24 13:14:13,702 [salt.state       :1780][INFO    ][14375] Running state [vlan 0] at time 13:14:13.702052
2019-04-24 13:14:13,702 [salt.state       :1813][INFO    ][14375] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-24 13:14:13,764 [salt.loaded.ext.module.maasng:945 ][INFO    ][14375] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qw4tfr', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-04-24 13:14:13,864 [salt.loaded.ext.module.maasng:945 ][INFO    ][14375] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qw4tfr', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-04-24 13:14:14,092 [salt.loaded.ext.module.maasng:945 ][INFO    ][14375] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qw4tfr', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-04-24 13:14:14,168 [salt.state       :300 ][INFO    ][14375] {'new': 'Vlan untagged was updated'}
2019-04-24 13:14:14,168 [salt.state       :1951][INFO    ][14375] Completed state [vlan 0] at time 13:14:14.168444 duration_in_ms=466.391
2019-04-24 13:14:14,169 [salt.state       :1780][INFO    ][14375] Running state [192.168.11.0/24] at time 13:14:14.169873
2019-04-24 13:14:14,170 [salt.state       :1813][INFO    ][14375] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-24 13:14:14,338 [salt.loaded.ext.module.maasng:945 ][INFO    ][14375] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'qw4tfr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-04-24 13:14:14,342 [salt.loaded.ext.module.maasng:1235][WARNING ][14375] Ignoring parameter vlan:0
2019-04-24 13:14:14,412 [salt.state       :300 ][INFO    ][14375] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-24 13:14:14,412 [salt.state       :1951][INFO    ][14375] Completed state [192.168.11.0/24] at time 13:14:14.412291 duration_in_ms=242.417
2019-04-24 13:14:14,413 [salt.state       :1780][INFO    ][14375] Running state [maas_create_iprange_1] at time 13:14:14.413353
2019-04-24 13:14:14,413 [salt.state       :1813][INFO    ][14375] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-24 13:14:14,460 [salt.state       :300 ][INFO    ][14375] Iprange maas_create_iprange_1 already exist.
2019-04-24 13:14:14,460 [salt.state       :1951][INFO    ][14375] Completed state [maas_create_iprange_1] at time 13:14:14.460623 duration_in_ms=47.27
2019-04-24 13:14:14,461 [salt.state       :1780][INFO    ][14375] Running state [vlan 0] at time 13:14:14.461085
2019-04-24 13:14:14,461 [salt.state       :1813][INFO    ][14375] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-24 13:14:14,507 [salt.loaded.ext.module.maasng:945 ][INFO    ][14375] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'qw4tfr', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-04-24 13:14:14,594 [salt.loaded.ext.module.maasng:945 ][INFO    ][14375] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'qw4tfr', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-04-24 13:14:14,816 [salt.loaded.ext.module.maasng:945 ][INFO    ][14375] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'qw4tfr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-04-24 13:14:14,894 [salt.state       :300 ][INFO    ][14375] {'new': 'Vlan untagged was updated'}
2019-04-24 13:14:14,894 [salt.state       :1951][INFO    ][14375] Completed state [vlan 0] at time 13:14:14.894392 duration_in_ms=433.306
2019-04-24 13:14:14,898 [salt.state       :1780][INFO    ][14375] Running state [opnfv] at time 13:14:14.898705
2019-04-24 13:14:14,899 [salt.state       :1813][INFO    ][14375] Executing state maasng.sshkey_present for [opnfv]
2019-04-24 13:14:14,953 [salt.loaded.ext.module.maasng:1903][INFO    ][14375] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-04-24 13:14:14,953 [salt.state       :300 ][INFO    ][14375] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-04-24 13:14:14,953 [salt.state       :1951][INFO    ][14375] Completed state [opnfv] at time 13:14:14.953833 duration_in_ms=55.127
2019-04-24 13:14:14,958 [salt.minion      :1711][INFO    ][14375] Returning information for job: 20190424131352632175
2019-04-24 13:14:15,730 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command state.apply with jid 20190424131415720892
2019-04-24 13:14:15,750 [salt.minion      :1432][INFO    ][14865] Starting a new job with PID 14865
2019-04-24 13:14:21,775 [salt.state       :915 ][INFO    ][14865] Loading fresh modules for state activity
2019-04-24 13:14:21,882 [salt.state       :1780][INFO    ][14865] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:14:21.882015
2019-04-24 13:14:21,882 [salt.state       :1813][INFO    ][14865] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 13:14:21,884 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14865] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 13:14:23,734 [salt.state       :300 ][INFO    ][14865] {'pid': 14900, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 13:14:23,736 [salt.state       :1951][INFO    ][14865] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:14:23.735895 duration_in_ms=1853.88
2019-04-24 13:14:23,740 [salt.state       :1780][INFO    ][14865] Running state [maas.process_machines] at time 13:14:23.738788
2019-04-24 13:14:23,740 [salt.state       :1813][INFO    ][14865] Executing state module.run for [maas.process_machines]
2019-04-24 13:14:23,741 [salt.utils.decorators:613 ][WARNING ][14865] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 13:14:24,299 [salt.loaded.ext.module.maas:412 ][WARNING ][14865] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 13:14:24,300 [salt.loaded.ext.module.maas:92  ][INFO    ][14865] machine hostname=cmp002 power_type=ipmi mac_addresses=['9c:b6:54:8a:10:18'] power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 system_id=mpscrq architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-24 13:14:25,460 [salt.loaded.ext.module.maas:412 ][WARNING ][14865] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 13:14:25,461 [salt.loaded.ext.module.maas:92  ][INFO    ][14865] machine hostname=cmp001 power_type=ipmi mac_addresses=['9c:b6:54:8a:95:a0'] power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 system_id=e8mgwm architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-24 13:14:26,556 [salt.loaded.ext.module.maas:412 ][WARNING ][14865] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 13:14:26,557 [salt.loaded.ext.module.maas:92  ][INFO    ][14865] machine hostname=kvm01 power_type=ipmi mac_addresses=['14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=86e3t3 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-24 13:14:27,792 [salt.loaded.ext.module.maas:412 ][WARNING ][14865] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 13:14:27,793 [salt.loaded.ext.module.maas:92  ][INFO    ][14865] machine hostname=kvm03 power_type=ipmi mac_addresses=['14:58:d0:54:7a:28'] power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 system_id=4pdsy6 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-24 13:14:28,921 [salt.loaded.ext.module.maas:412 ][WARNING ][14865] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-24 13:14:28,922 [salt.loaded.ext.module.maas:92  ][INFO    ][14865] machine hostname=kvm02 power_type=ipmi mac_addresses=['14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=hxs4wc architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-24 13:14:29,983 [salt.state       :300 ][INFO    ][14865] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-24 13:14:29,984 [salt.state       :1951][INFO    ][14865] Completed state [maas.process_machines] at time 13:14:29.984116 duration_in_ms=6245.328
2019-04-24 13:14:29,988 [salt.minion      :1711][INFO    ][14865] Returning information for job: 20190424131415720892
2019-04-24 13:15:03,785 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command state.apply with jid 20190424131503776172
2019-04-24 13:15:03,798 [salt.minion      :1432][INFO    ][15144] Starting a new job with PID 15144
2019-04-24 13:15:09,726 [salt.state       :915 ][INFO    ][15144] Loading fresh modules for state activity
2019-04-24 13:15:09,819 [salt.state       :1780][INFO    ][15144] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:15:09.819799
2019-04-24 13:15:09,820 [salt.state       :1813][INFO    ][15144] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 13:15:09,822 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15144] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 13:15:11,796 [salt.state       :300 ][INFO    ][15144] {'pid': 15152, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 13:15:11,797 [salt.state       :1951][INFO    ][15144] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:15:11.797324 duration_in_ms=1977.526
2019-04-24 13:15:11,800 [salt.state       :1780][INFO    ][15144] Running state [maas.wait_for_machine_status] at time 13:15:11.800688
2019-04-24 13:15:11,801 [salt.state       :1813][INFO    ][15144] Executing state module.run for [maas.wait_for_machine_status]
2019-04-24 13:15:11,801 [salt.utils.decorators:613 ][WARNING ][15144] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 13:15:14,617 [salt.state       :300 ][INFO    ][15144] {'ret': True}
2019-04-24 13:15:14,618 [salt.state       :1951][INFO    ][15144] Completed state [maas.wait_for_machine_status] at time 13:15:14.618170 duration_in_ms=2817.48
2019-04-24 13:15:14,622 [salt.minion      :1711][INFO    ][15144] Returning information for job: 20190424131503776172
2019-04-24 13:15:15,331 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command state.apply with jid 20190424131515286835
2019-04-24 13:15:15,356 [salt.minion      :1432][INFO    ][15210] Starting a new job with PID 15210
2019-04-24 13:15:16,336 [salt.state       :915 ][INFO    ][15210] Loading fresh modules for state activity
2019-04-24 13:15:16,491 [salt.state       :1780][INFO    ][15210] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:15:16.491916
2019-04-24 13:15:16,492 [salt.state       :1813][INFO    ][15210] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 13:15:16,494 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15210] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 13:15:18,433 [salt.state       :300 ][INFO    ][15210] {'pid': 15217, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 13:15:18,434 [salt.state       :1951][INFO    ][15210] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:15:18.434383 duration_in_ms=1942.465
2019-04-24 13:15:18,438 [salt.state       :1780][INFO    ][15210] Running state [maas_machines_storage_cmp002_lvm] at time 13:15:18.438509
2019-04-24 13:15:18,439 [salt.state       :1813][INFO    ][15210] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-24 13:15:18,981 [salt.state       :300 ][INFO    ][15210] Machine cmp002 is not in Ready state.
2019-04-24 13:15:18,982 [salt.state       :1951][INFO    ][15210] Completed state [maas_machines_storage_cmp002_lvm] at time 13:15:18.981992 duration_in_ms=543.483
2019-04-24 13:15:18,982 [salt.state       :1780][INFO    ][15210] Running state [maas_machines_storage_cmp001_lvm] at time 13:15:18.982383
2019-04-24 13:15:18,982 [salt.state       :1813][INFO    ][15210] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-24 13:15:19,507 [salt.state       :300 ][INFO    ][15210] Machine cmp001 is not in Ready state.
2019-04-24 13:15:19,508 [salt.state       :1951][INFO    ][15210] Completed state [maas_machines_storage_cmp001_lvm] at time 13:15:19.508327 duration_in_ms=525.943
2019-04-24 13:15:19,512 [salt.minion      :1711][INFO    ][15210] Returning information for job: 20190424131515286835
2019-04-24 13:15:20,194 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command state.apply with jid 20190424131520181645
2019-04-24 13:15:20,214 [salt.minion      :1432][INFO    ][15239] Starting a new job with PID 15239
2019-04-24 13:15:21,144 [salt.state       :915 ][INFO    ][15239] Loading fresh modules for state activity
2019-04-24 13:15:21,243 [salt.state       :1780][INFO    ][15239] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:15:21.242835
2019-04-24 13:15:21,243 [salt.state       :1813][INFO    ][15239] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 13:15:21,245 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15239] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 13:15:23,080 [salt.state       :300 ][INFO    ][15239] {'pid': 15246, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 13:15:23,082 [salt.state       :1951][INFO    ][15239] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:15:23.081898 duration_in_ms=1839.062
2019-04-24 13:15:23,085 [salt.state       :1780][INFO    ][15239] Running state [maas.deploy_machines] at time 13:15:23.085725
2019-04-24 13:15:23,086 [salt.state       :1813][INFO    ][15239] Executing state module.run for [maas.deploy_machines]
2019-04-24 13:15:23,087 [salt.utils.decorators:613 ][WARNING ][15239] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 13:15:23,623 [salt.loaded.ext.module.maas:684 ][INFO    ][15239] deploymachines hwe_kernel=hwe-16.04 system_id=hxs4wc distro_series=xenial
2019-04-24 13:15:25,945 [salt.state       :300 ][INFO    ][15239] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-04-24 13:15:25,946 [salt.state       :1951][INFO    ][15239] Completed state [maas.deploy_machines] at time 13:15:25.946023 duration_in_ms=2860.297
2019-04-24 13:15:25,951 [salt.minion      :1711][INFO    ][15239] Returning information for job: 20190424131520181645
2019-04-24 13:15:26,657 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command state.apply with jid 20190424131526646676
2019-04-24 13:15:26,673 [salt.minion      :1432][INFO    ][15307] Starting a new job with PID 15307
2019-04-24 13:15:32,608 [salt.state       :915 ][INFO    ][15307] Loading fresh modules for state activity
2019-04-24 13:15:32,695 [salt.state       :1780][INFO    ][15307] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:15:32.695488
2019-04-24 13:15:32,696 [salt.state       :1813][INFO    ][15307] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-24 13:15:32,700 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15307] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-24 13:15:34,542 [salt.state       :300 ][INFO    ][15307] {'pid': 15323, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-24 13:15:34,543 [salt.state       :1951][INFO    ][15307] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:15:34.543609 duration_in_ms=1848.121
2019-04-24 13:15:34,546 [salt.state       :1780][INFO    ][15307] Running state [maas.wait_for_machine_status] at time 13:15:34.546301
2019-04-24 13:15:34,547 [salt.state       :1813][INFO    ][15307] Executing state module.run for [maas.wait_for_machine_status]
2019-04-24 13:15:34,547 [salt.utils.decorators:613 ][WARNING ][15307] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-24 13:15:37,197 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.36803699s left)
2019-04-24 13:15:41,770 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424131541761363
2019-04-24 13:15:41,784 [salt.minion      :1432][INFO    ][15343] Starting a new job with PID 15343
2019-04-24 13:15:41,822 [salt.minion      :1711][INFO    ][15343] Returning information for job: 20190424131541761363
2019-04-24 13:16:09,547 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2215.0175271s left)
2019-04-24 13:16:11,836 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424131611827531
2019-04-24 13:16:11,850 [salt.minion      :1432][INFO    ][15399] Starting a new job with PID 15399
2019-04-24 13:16:11,880 [salt.minion      :1711][INFO    ][15399] Returning information for job: 20190424131611827531
2019-04-24 13:16:41,890 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424131641882630
2019-04-24 13:16:41,905 [salt.minion      :1432][INFO    ][15444] Starting a new job with PID 15444
2019-04-24 13:16:41,936 [salt.minion      :1711][INFO    ][15444] Returning information for job: 20190424131641882630
2019-04-24 13:16:42,589 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.97582507s left)
2019-04-24 13:17:11,964 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424131711953549
2019-04-24 13:17:11,985 [salt.minion      :1432][INFO    ][15518] Starting a new job with PID 15518
2019-04-24 13:17:12,018 [salt.minion      :1711][INFO    ][15518] Returning information for job: 20190424131711953549
2019-04-24 13:17:15,038 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.5267911s left)
2019-04-24 13:17:42,041 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424131742027815
2019-04-24 13:17:42,057 [salt.minion      :1432][INFO    ][15566] Starting a new job with PID 15566
2019-04-24 13:17:42,085 [salt.minion      :1711][INFO    ][15566] Returning information for job: 20190424131742027815
2019-04-24 13:17:47,764 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.80055714s left)
2019-04-24 13:18:12,113 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424131812100284
2019-04-24 13:18:12,130 [salt.minion      :1432][INFO    ][15629] Starting a new job with PID 15629
2019-04-24 13:18:12,162 [salt.minion      :1711][INFO    ][15629] Returning information for job: 20190424131812100284
2019-04-24 13:18:20,160 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2084.40501404s left)
2019-04-24 13:18:42,178 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424131842165650
2019-04-24 13:18:42,201 [salt.minion      :1432][INFO    ][15669] Starting a new job with PID 15669
2019-04-24 13:18:42,230 [salt.minion      :1711][INFO    ][15669] Returning information for job: 20190424131842165650
2019-04-24 13:18:52,812 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2051.75283098s left)
2019-04-24 13:19:12,261 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424131912251222
2019-04-24 13:19:12,282 [salt.minion      :1432][INFO    ][15732] Starting a new job with PID 15732
2019-04-24 13:19:12,313 [salt.minion      :1711][INFO    ][15732] Returning information for job: 20190424131912251222
2019-04-24 13:19:25,369 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2019.19554305s left)
2019-04-24 13:19:42,319 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424131942311475
2019-04-24 13:19:42,334 [salt.minion      :1432][INFO    ][15797] Starting a new job with PID 15797
2019-04-24 13:19:42,364 [salt.minion      :1711][INFO    ][15797] Returning information for job: 20190424131942311475
2019-04-24 13:19:58,121 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1986.44369912s left)
2019-04-24 13:20:12,383 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424132012372897
2019-04-24 13:20:12,404 [salt.minion      :1432][INFO    ][15872] Starting a new job with PID 15872
2019-04-24 13:20:12,438 [salt.minion      :1711][INFO    ][15872] Returning information for job: 20190424132012372897
2019-04-24 13:20:31,040 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1953.52526116s left)
2019-04-24 13:20:42,482 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424132042471387
2019-04-24 13:20:42,503 [salt.minion      :1432][INFO    ][15964] Starting a new job with PID 15964
2019-04-24 13:20:42,532 [salt.minion      :1711][INFO    ][15964] Returning information for job: 20190424132042471387
2019-04-24 13:21:03,763 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1920.80161905s left)
2019-04-24 13:21:12,550 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424132112541284
2019-04-24 13:21:12,572 [salt.minion      :1432][INFO    ][16146] Starting a new job with PID 16146
2019-04-24 13:21:12,604 [salt.minion      :1711][INFO    ][16146] Returning information for job: 20190424132112541284
2019-04-24 13:21:36,427 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1888.13780713s left)
2019-04-24 13:21:42,647 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424132142633515
2019-04-24 13:21:42,670 [salt.minion      :1432][INFO    ][16199] Starting a new job with PID 16199
2019-04-24 13:21:42,699 [salt.minion      :1711][INFO    ][16199] Returning information for job: 20190424132142633515
2019-04-24 13:22:09,177 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1855.38834095s left)
2019-04-24 13:22:12,766 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424132212756902
2019-04-24 13:22:12,786 [salt.minion      :1432][INFO    ][16281] Starting a new job with PID 16281
2019-04-24 13:22:12,814 [salt.minion      :1711][INFO    ][16281] Returning information for job: 20190424132212756902
2019-04-24 13:22:42,062 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1822.50333595s left)
2019-04-24 13:22:42,882 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424132242874141
2019-04-24 13:22:42,896 [salt.minion      :1432][INFO    ][16341] Starting a new job with PID 16341
2019-04-24 13:22:42,922 [salt.minion      :1711][INFO    ][16341] Returning information for job: 20190424132242874141
2019-04-24 13:23:13,001 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424132312990234
2019-04-24 13:23:13,015 [salt.minion      :1432][INFO    ][16532] Starting a new job with PID 16532
2019-04-24 13:23:13,044 [salt.minion      :1711][INFO    ][16532] Returning information for job: 20190424132312990234
2019-04-24 13:23:15,056 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1789.50893712s left)
2019-04-24 13:23:43,223 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424132343208045
2019-04-24 13:23:43,244 [salt.minion      :1432][INFO    ][16591] Starting a new job with PID 16591
2019-04-24 13:23:43,273 [salt.minion      :1711][INFO    ][16591] Returning information for job: 20190424132343208045
2019-04-24 13:23:47,726 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1756.83868504s left)
2019-04-24 13:24:13,361 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424132413348556
2019-04-24 13:24:13,380 [salt.minion      :1432][INFO    ][16658] Starting a new job with PID 16658
2019-04-24 13:24:13,411 [salt.minion      :1711][INFO    ][16658] Returning information for job: 20190424132413348556
2019-04-24 13:24:20,476 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1724.08842802s left)
2019-04-24 13:24:43,510 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424132443500499
2019-04-24 13:24:43,531 [salt.minion      :1432][INFO    ][16700] Starting a new job with PID 16700
2019-04-24 13:24:43,562 [salt.minion      :1711][INFO    ][16700] Returning information for job: 20190424132443500499
2019-04-24 13:24:53,143 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1691.42250896s left)
2019-04-24 13:25:13,642 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424132513634626
2019-04-24 13:25:13,662 [salt.minion      :1432][INFO    ][16765] Starting a new job with PID 16765
2019-04-24 13:25:13,697 [salt.minion      :1711][INFO    ][16765] Returning information for job: 20190424132513634626
2019-04-24 13:25:25,889 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1658.6756041s left)
2019-04-24 13:25:43,774 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424132543764664
2019-04-24 13:25:43,794 [salt.minion      :1432][INFO    ][16801] Starting a new job with PID 16801
2019-04-24 13:25:43,823 [salt.minion      :1711][INFO    ][16801] Returning information for job: 20190424132543764664
2019-04-24 13:25:58,675 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1625.88952899s left)
2019-04-24 13:26:13,902 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424132613890517
2019-04-24 13:26:13,926 [salt.minion      :1432][INFO    ][16866] Starting a new job with PID 16866
2019-04-24 13:26:13,955 [salt.minion      :1711][INFO    ][16866] Returning information for job: 20190424132613890517
2019-04-24 13:26:31,535 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1593.02954197s left)
2019-04-24 13:26:44,073 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424132644064772
2019-04-24 13:26:44,094 [salt.minion      :1432][INFO    ][16902] Starting a new job with PID 16902
2019-04-24 13:26:44,124 [salt.minion      :1711][INFO    ][16902] Returning information for job: 20190424132644064772
2019-04-24 13:27:04,196 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1560.36854315s left)
2019-04-24 13:27:14,233 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424132714224186
2019-04-24 13:27:14,251 [salt.minion      :1432][INFO    ][16993] Starting a new job with PID 16993
2019-04-24 13:27:14,281 [salt.minion      :1711][INFO    ][16993] Returning information for job: 20190424132714224186
2019-04-24 13:27:37,042 [salt.loaded.ext.module.maas:1023][INFO    ][15307] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1527.52294302s left)
2019-04-24 13:27:44,314 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command saltutil.find_job with jid 20190424132744301461
2019-04-24 13:27:44,336 [salt.minion      :1432][INFO    ][17219] Starting a new job with PID 17219
2019-04-24 13:27:44,369 [salt.minion      :1711][INFO    ][17219] Returning information for job: 20190424132744301461
2019-04-24 13:28:03,819 [salt.utils.schedule:1377][INFO    ][4810] Running scheduled job: __mine_interval
2019-04-24 13:28:09,659 [salt.state       :300 ][INFO    ][15307] {'ret': True}
2019-04-24 13:28:09,659 [salt.state       :1951][INFO    ][15307] Completed state [maas.wait_for_machine_status] at time 13:28:09.659870 duration_in_ms=755113.567
2019-04-24 13:28:09,665 [salt.minion      :1711][INFO    ][15307] Returning information for job: 20190424131526646676
2019-04-24 14:28:03,820 [salt.utils.schedule:1377][INFO    ][4810] Running scheduled job: __mine_interval
2019-04-24 14:52:20,885 [salt.minion      :1308][INFO    ][4810] User sudo_ubuntu Executing command cp.push_dir with jid 20190424145220875947
2019-04-24 14:52:20,901 [salt.minion      :1432][INFO    ][26077] Starting a new job with PID 26077
