2019-03-05 02:08:35,594 [salt.utils.decorators:613 ][WARNING ][2062] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-05 02:08:36,120 [salt.utils.decorators:613 ][WARNING ][2062] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-05 02:08:38,124 [salt.loaded.int.states.file:2298][WARNING ][2497] 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-03-05 02:09:05,798 [salt.state       :2022][WARNING ][3187] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-05 02:09:08,337 [salt.utils.decorators:613 ][WARNING ][3187] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-05 02:10:04,517 [salt.utils.decorators:613 ][WARNING ][3187] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-05 02:10:41,676 [salt.utils.decorators:613 ][WARNING ][3187] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-05 02:10:43,860 [salt.utils.decorators:613 ][WARNING ][3187] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-05 02:10:47,306 [salt.loaded.ext.module.maasng:1008][WARNING ][3187] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-03-05 02:10:47,307 [salt.loaded.ext.module.maasng:1011][WARNING ][3187] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-03-05 02:10:48,571 [salt.loaded.ext.module.maasng:1235][WARNING ][3187] Ignoring parameter vlan:0
2019-03-05 02:10:51,423 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7520] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-05 02:10:51,459 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7520] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-03-05 02:10:51,483 [salt.utils.parsers:1051][WARNING ][363] Minion received a SIGTERM. Exiting.
2019-03-05 02:10:52,510 [salt.cli.daemons :293 ][INFO    ][7590] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-03-05 02:10:52,593 [salt.cli.daemons :82  ][INFO    ][7590] Starting up the Salt Minion
2019-03-05 02:10:52,593 [salt.utils.event :1017][INFO    ][7590] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-03-05 02:10:53,386 [salt.minion      :976 ][INFO    ][7590] Creating minion process manager
2019-03-05 02:10:54,519 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][7590] Executing command ['date', '+%z'] in directory '/root'
2019-03-05 02:10:54,538 [salt.utils.schedule:568 ][INFO    ][7590] Updating job settings for scheduled job: __mine_interval
2019-03-05 02:10:54,576 [salt.minion      :1108][INFO    ][7590] Added mine.update to scheduler
2019-03-05 02:10:54,585 [salt.minion      :1975][INFO    ][7590] Minion is starting as user 'root'
2019-03-05 02:10:54,601 [salt.minion      :2336][INFO    ][7590] Minion is ready to receive requests!
2019-03-05 02:10:55,050 [salt.utils.decorators:613 ][WARNING ][7526] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-05 02:10:55,116 [salt.loaded.ext.module.maas:412 ][WARNING ][7526] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-05 02:10:56,536 [salt.loaded.ext.module.maas:412 ][WARNING ][7526] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-05 02:10:58,058 [salt.loaded.ext.module.maas:412 ][WARNING ][7526] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-05 02:10:59,495 [salt.loaded.ext.module.maas:412 ][WARNING ][7526] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-05 02:11:00,627 [salt.loaded.ext.module.maas:412 ][WARNING ][7526] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-05 02:11:33,138 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command state.apply with jid 20190305021133124936
2019-03-05 02:11:33,159 [salt.minion      :1432][INFO    ][7993] Starting a new job with PID 7993
2019-03-05 02:11:36,657 [salt.state       :915 ][INFO    ][7993] Loading fresh modules for state activity
2019-03-05 02:11:36,705 [salt.fileclient  :1219][INFO    ][7993] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-03-05 02:11:36,740 [salt.state       :1780][INFO    ][7993] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:11:36.740326
2019-03-05 02:11:36,740 [salt.state       :1813][INFO    ][7993] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-05 02:11:36,742 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7993] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-05 02:11:38,165 [salt.state       :300 ][INFO    ][7993] {'pid': 8014, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-05 02:11:38,166 [salt.state       :1951][INFO    ][7993] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:11:38.166296 duration_in_ms=1425.97
2019-03-05 02:11:38,167 [salt.state       :1780][INFO    ][7993] Running state [maas.wait_for_machine_status] at time 02:11:38.167372
2019-03-05 02:11:38,167 [salt.state       :1813][INFO    ][7993] Executing state module.run for [maas.wait_for_machine_status]
2019-03-05 02:11:38,167 [salt.utils.decorators:613 ][WARNING ][7993] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-05 02:11:39,133 [salt.loaded.ext.module.maas:1023][INFO    ][7993] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.03939414s left)
2019-03-05 02:11:48,163 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305021148150448
2019-03-05 02:11:48,185 [salt.minion      :1432][INFO    ][8028] Starting a new job with PID 8028
2019-03-05 02:11:48,208 [salt.minion      :1711][INFO    ][8028] Returning information for job: 20190305021148150448
2019-03-05 02:12:10,063 [salt.loaded.ext.module.maas:1023][INFO    ][7993] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.10912204s left)
2019-03-05 02:12:18,219 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305021218204208
2019-03-05 02:12:18,240 [salt.minion      :1432][INFO    ][8067] Starting a new job with PID 8067
2019-03-05 02:12:18,263 [salt.minion      :1711][INFO    ][8067] Returning information for job: 20190305021218204208
2019-03-05 02:12:41,359 [salt.loaded.ext.module.maas:1023][INFO    ][7993] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.81331015s left)
2019-03-05 02:12:48,308 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305021248295385
2019-03-05 02:12:48,330 [salt.minion      :1432][INFO    ][8210] Starting a new job with PID 8210
2019-03-05 02:12:48,353 [salt.minion      :1711][INFO    ][8210] Returning information for job: 20190305021248295385
2019-03-05 02:13:12,747 [salt.loaded.ext.module.maas:1023][INFO    ][7993] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.42489219s left)
2019-03-05 02:13:18,361 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305021318350955
2019-03-05 02:13:18,380 [salt.minion      :1432][INFO    ][8604] Starting a new job with PID 8604
2019-03-05 02:13:18,403 [salt.minion      :1711][INFO    ][8604] Returning information for job: 20190305021318350955
2019-03-05 02:13:44,124 [salt.loaded.ext.module.maas:1023][INFO    ][7993] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.0483501s left)
2019-03-05 02:13:48,417 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305021348402138
2019-03-05 02:13:48,438 [salt.minion      :1432][INFO    ][8827] Starting a new job with PID 8827
2019-03-05 02:13:48,461 [salt.minion      :1711][INFO    ][8827] Returning information for job: 20190305021348402138
2019-03-05 02:14:15,975 [salt.loaded.ext.module.maas:1023][INFO    ][7993] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.19710898s left)
2019-03-05 02:14:18,475 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305021418461040
2019-03-05 02:14:18,497 [salt.minion      :1432][INFO    ][9084] Starting a new job with PID 9084
2019-03-05 02:14:18,519 [salt.minion      :1711][INFO    ][9084] Returning information for job: 20190305021418461040
2019-03-05 02:14:47,761 [salt.loaded.ext.module.maas:1023][INFO    ][7993] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1310.41096902s left)
2019-03-05 02:14:48,529 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305021448514343
2019-03-05 02:14:48,552 [salt.minion      :1432][INFO    ][9165] Starting a new job with PID 9165
2019-03-05 02:14:48,576 [salt.minion      :1711][INFO    ][9165] Returning information for job: 20190305021448514343
2019-03-05 02:15:18,594 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305021518583151
2019-03-05 02:15:18,614 [salt.minion      :1432][INFO    ][9419] Starting a new job with PID 9419
2019-03-05 02:15:18,637 [salt.minion      :1711][INFO    ][9419] Returning information for job: 20190305021518583151
2019-03-05 02:15:20,975 [salt.state       :300 ][INFO    ][7993] {'ret': True}
2019-03-05 02:15:20,975 [salt.state       :1951][INFO    ][7993] Completed state [maas.wait_for_machine_status] at time 02:15:20.975404 duration_in_ms=222808.03
2019-03-05 02:15:20,979 [salt.minion      :1711][INFO    ][7993] Returning information for job: 20190305021133124936
2019-03-05 02:15:21,633 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command state.apply with jid 20190305021521617454
2019-03-05 02:15:21,654 [salt.minion      :1432][INFO    ][9443] Starting a new job with PID 9443
2019-03-05 02:15:25,153 [salt.state       :915 ][INFO    ][9443] Loading fresh modules for state activity
2019-03-05 02:15:25,209 [salt.fileclient  :1219][INFO    ][9443] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-03-05 02:15:25,306 [salt.state       :1780][INFO    ][9443] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:25.306188
2019-03-05 02:15:25,306 [salt.state       :1813][INFO    ][9443] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-05 02:15:25,308 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9443] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-05 02:15:26,724 [salt.state       :300 ][INFO    ][9443] {'pid': 9450, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-05 02:15:26,724 [salt.state       :1951][INFO    ][9443] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:26.724661 duration_in_ms=1418.474
2019-03-05 02:15:26,726 [salt.state       :1780][INFO    ][9443] Running state [maas_machines_storage_cmp002_lvm] at time 02:15:26.726025
2019-03-05 02:15:26,726 [salt.state       :1813][INFO    ][9443] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-05 02:15:27,659 [salt.loaded.ext.module.maasng:610 ][INFO    ][9443] fxn8ck
2019-03-05 02:15:27,659 [salt.loaded.ext.module.maasng:626 ][INFO    ][9443] sda
2019-03-05 02:15:28,339 [salt.loaded.ext.module.maasng:361 ][INFO    ][9443] fxn8ck
2019-03-05 02:15:28,430 [salt.loaded.ext.module.maasng:367 ][INFO    ][9443] [{u'size': 2397998940160, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'system_id': u'fxn8ck', u'type': u'physical', u'partitions': [{u'uuid': u'53376a51-a280-4e67-8b5d-3e282f4e8c75', u'resource_uri': u'/MAAS/api/2.0/nodes/fxn8ck/blockdevices/4/partition/4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fxn8ck', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'uuid': u'd49ed165-482c-467e-ade6-d1bda7a62c4a'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 4, u'device_id': 4}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'id': 4, u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/fxn8ck/blockdevices/4/'}, {u'size': 2397988454400, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'system_id': u'fxn8ck', u'type': u'virtual', u'partitions': [], u'uuid': u'6cac01ae-b769-436e-8410-aecef2f8769e', u'used_for': u'ext4 formatted filesystem mounted at /', u'id': 9, u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'uuid': u'27fd8152-cec4-4539-93ed-b42602ffe3d8'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fxn8ck/blockdevices/9/'}]
2019-03-05 02:15:28,431 [salt.loaded.ext.module.maasng:632 ][INFO    ][9443] vgroot
2019-03-05 02:15:28,431 [salt.loaded.ext.module.maasng:635 ][INFO    ][9443] lvroot
2019-03-05 02:15:28,432 [salt.loaded.ext.module.maasng:639 ][INFO    ][9443] 107374182400
2019-03-05 02:15:29,093 [salt.loaded.ext.module.maasng:645 ][INFO    ][9443] {u'hwe_kernel': u'', u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 16, u'owner_data': {}, u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'kwwxpn', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 31, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'kwwxpn', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'kwwxpn', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'system_id': u'fxn8ck', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/fxn8ck/interfaces/4/'}, u'min_hwe_kernel': u'ga-16.04', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/fxn8ck/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'fxn8ck', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.42'], u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fxn8ck/blockdevices/4/', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'90fc95d8-5161-4cf8-9c0b-b71f62a1e84b', u'resource_uri': u'/MAAS/api/2.0/nodes/fxn8ck/blockdevices/4/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fxn8ck', u'filesystem': {u'uuid': u'1175c2e5-c7e6-4141-886d-c8cfded6d168', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 8, u'device_id': 4}], u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'fxn8ck', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160, u'type': u'physical', u'id': 4, u'name': u'sda'}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'b41bd0a8-214a-48cc-bfb1-6c9d837181d0', u'resource_uri': u'/MAAS/api/2.0/nodes/fxn8ck/blockdevices/13/', u'used_size': 107374182400, u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fxn8ck', u'partition_table_type': None, u'filesystem': {u'uuid': u'6cfa880f-1aec-4c6d-9f43-02e6a8bb06c3', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 107374182400, u'type': u'virtual', u'id': 13, u'name': u'vgroot-lvroot'}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fxn8ck/blockdevices/4/', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'90fc95d8-5161-4cf8-9c0b-b71f62a1e84b', u'resource_uri': u'/MAAS/api/2.0/nodes/fxn8ck/blockdevices/4/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fxn8ck', u'filesystem': {u'uuid': u'1175c2e5-c7e6-4141-886d-c8cfded6d168', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 8, u'device_id': 4}], u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'fxn8ck', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'name': u'sda'}], u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'fxn8ck', u'id': 8}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'kwwxpn', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 31, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'kwwxpn', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'kwwxpn', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'system_id': u'fxn8ck', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/fxn8ck/interfaces/4/'}, {u'name': u'enp9s0', u'links': [{u'id': 37, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'fxn8ck', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/fxn8ck/interfaces/23/'}, {u'name': u'enp7s0', u'links': [{u'id': 32, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'fxn8ck', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/fxn8ck/interfaces/21/'}, {u'name': u'enp8s0', u'links': [{u'id': 34, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'fxn8ck', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/fxn8ck/interfaces/22/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': u'b41bd0a8-214a-48cc-bfb1-6c9d837181d0', u'resource_uri': u'/MAAS/api/2.0/nodes/fxn8ck/blockdevices/13/', u'used_size': 107374182400, u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fxn8ck', u'partition_table_type': None, u'filesystem': {u'uuid': u'6cfa880f-1aec-4c6d-9f43-02e6a8bb06c3', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'size': 107374182400, u'type': u'virtual', u'id': 13, u'serial': None, u'name': u'vgroot-lvroot'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'boot_disk': {u'block_size': 4096, u'available_size': 0, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fxn8ck/blockdevices/4/', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'90fc95d8-5161-4cf8-9c0b-b71f62a1e84b', u'resource_uri': u'/MAAS/api/2.0/nodes/fxn8ck/blockdevices/4/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fxn8ck', u'filesystem': {u'uuid': u'1175c2e5-c7e6-4141-886d-c8cfded6d168', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 8, u'device_id': 4}], u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'fxn8ck', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'name': u'sda'}}
2019-03-05 02:15:29,096 [salt.state       :300 ][INFO    ][9443] {'new': {'storage_layout': 'lvm'}}
2019-03-05 02:15:29,096 [salt.state       :1951][INFO    ][9443] Completed state [maas_machines_storage_cmp002_lvm] at time 02:15:29.096784 duration_in_ms=2370.757
2019-03-05 02:15:29,097 [salt.state       :1780][INFO    ][9443] Running state [maas_machines_storage_cmp001_lvm] at time 02:15:29.097361
2019-03-05 02:15:29,097 [salt.state       :1813][INFO    ][9443] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-05 02:15:30,553 [salt.loaded.ext.module.maasng:610 ][INFO    ][9443] sg8f7w
2019-03-05 02:15:30,553 [salt.loaded.ext.module.maasng:626 ][INFO    ][9443] sda
2019-03-05 02:15:31,156 [salt.loaded.ext.module.maasng:361 ][INFO    ][9443] sg8f7w
2019-03-05 02:15:31,285 [salt.loaded.ext.module.maasng:367 ][INFO    ][9443] [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sg8f7w/blockdevices/5/', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'158cee77-8cca-4652-96a1-63de4dcd9ed7', u'resource_uri': u'/MAAS/api/2.0/nodes/sg8f7w/blockdevices/5/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sg8f7w', u'filesystem': {u'uuid': u'89d3c84a-4ced-4b1d-ad28-c61a6da4b9a6', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'device_id': 5}], u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'sg8f7w', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160, u'type': u'physical', u'id': 5, u'name': u'sda'}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'3f0f0a5e-2cc9-4b72-b695-95fe88a33ecc', u'resource_uri': u'/MAAS/api/2.0/nodes/sg8f7w/blockdevices/11/', u'used_size': 2397988454400, u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sg8f7w', u'partition_table_type': None, u'filesystem': {u'uuid': u'd99b402b-a0ac-4be8-b36d-ba3695de7b39', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 2397988454400, u'type': u'virtual', u'id': 11, u'name': u'vgroot-lvroot'}]
2019-03-05 02:15:31,286 [salt.loaded.ext.module.maasng:632 ][INFO    ][9443] vgroot
2019-03-05 02:15:31,286 [salt.loaded.ext.module.maasng:635 ][INFO    ][9443] lvroot
2019-03-05 02:15:31,286 [salt.loaded.ext.module.maasng:639 ][INFO    ][9443] 107374182400
2019-03-05 02:15:32,023 [salt.loaded.ext.module.maasng:645 ][INFO    ][9443] {u'hwe_kernel': u'', u'swap_size': None, u'ip_addresses': [u'192.168.11.38'], u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'kwwxpn', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 35}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'kwwxpn', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'kwwxpn', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'sg8f7w', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sg8f7w/interfaces/5/'}, 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'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/sg8f7w/blockdevices/5/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'3b85bbe8-ec3d-4913-aaed-1cca320d289a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sg8f7w', u'device_id': 5, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'246b3408-1e36-4e1d-affb-50444b33b750', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/sg8f7w/blockdevices/5/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'sg8f7w', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 5, u'size': 2397998940160}, u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/sg8f7w/blockdevices/5/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'sg8f7w', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'3b85bbe8-ec3d-4913-aaed-1cca320d289a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sg8f7w', u'device_id': 5, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'246b3408-1e36-4e1d-affb-50444b33b750', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/sg8f7w/blockdevices/5/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'type': u'physical', u'id': 5, u'size': 2397998940160}, {u'model': None, u'block_size': 4096, u'uuid': u'aec18b10-0567-4e34-b1d8-3fb38dcb7fef', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/sg8f7w/blockdevices/14/', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'sg8f7w', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'5d2cb62f-7487-41b1-a609-05fe38a16a47', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 14, u'size': 107374182400}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/sg8f7w/', u'disable_ipv4': False, u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'owner_data': {}, u'system_id': u'sg8f7w', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sg8f7w/blockdevices/14/', u'uuid': u'aec18b10-0567-4e34-b1d8-3fb38dcb7fef', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'sg8f7w', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'5d2cb62f-7487-41b1-a609-05fe38a16a47', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 14, u'size': 107374182400}], u'min_hwe_kernel': u'ga-16.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/sg8f7w/blockdevices/5/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'3b85bbe8-ec3d-4913-aaed-1cca320d289a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sg8f7w', u'device_id': 5, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'246b3408-1e36-4e1d-affb-50444b33b750', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/sg8f7w/blockdevices/5/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'sg8f7w', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 5, u'size': 2397998940160}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'sg8f7w', u'id': 9}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'kwwxpn', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 35}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'kwwxpn', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'kwwxpn', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'sg8f7w', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sg8f7w/interfaces/5/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 38}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 18, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'sg8f7w', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sg8f7w/interfaces/18/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 41}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 19, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'sg8f7w', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sg8f7w/interfaces/19/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 42}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 20, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'sg8f7w', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sg8f7w/interfaces/20/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 4, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-03-05 02:15:32,026 [salt.state       :300 ][INFO    ][9443] {'new': {'storage_layout': 'lvm'}}
2019-03-05 02:15:32,026 [salt.state       :1951][INFO    ][9443] Completed state [maas_machines_storage_cmp001_lvm] at time 02:15:32.026306 duration_in_ms=2928.945
2019-03-05 02:15:32,030 [salt.minion      :1711][INFO    ][9443] Returning information for job: 20190305021521617454
2019-03-05 02:15:32,676 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command state.apply with jid 20190305021532661275
2019-03-05 02:15:32,697 [salt.minion      :1432][INFO    ][9473] Starting a new job with PID 9473
2019-03-05 02:15:33,331 [salt.state       :915 ][INFO    ][9473] Loading fresh modules for state activity
2019-03-05 02:15:33,386 [salt.fileclient  :1219][INFO    ][9473] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-03-05 02:15:33,427 [salt.state       :1780][INFO    ][9473] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:33.427887
2019-03-05 02:15:33,428 [salt.state       :1813][INFO    ][9473] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-05 02:15:33,430 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9473] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-05 02:15:34,856 [salt.state       :300 ][INFO    ][9473] {'pid': 9480, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-05 02:15:34,856 [salt.state       :1951][INFO    ][9473] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:34.856669 duration_in_ms=1428.783
2019-03-05 02:15:34,857 [salt.state       :1780][INFO    ][9473] Running state [maas.deploy_machines] at time 02:15:34.857772
2019-03-05 02:15:34,858 [salt.state       :1813][INFO    ][9473] Executing state module.run for [maas.deploy_machines]
2019-03-05 02:15:34,858 [salt.utils.decorators:613 ][WARNING ][9473] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-05 02:15:35,600 [salt.loaded.ext.module.maas:684 ][INFO    ][9473] deploymachines hwe_kernel=ga-16.04 system_id=fxn8ck distro_series=xenial
2019-03-05 02:15:38,309 [salt.loaded.ext.module.maas:684 ][INFO    ][9473] deploymachines hwe_kernel=ga-16.04 system_id=sg8f7w distro_series=xenial
2019-03-05 02:15:40,943 [salt.loaded.ext.module.maas:684 ][INFO    ][9473] deploymachines hwe_kernel=ga-16.04 system_id=w4bhwc distro_series=xenial
2019-03-05 02:15:43,289 [salt.loaded.ext.module.maas:684 ][INFO    ][9473] deploymachines hwe_kernel=ga-16.04 system_id=me4yge distro_series=xenial
2019-03-05 02:15:45,750 [salt.loaded.ext.module.maas:684 ][INFO    ][9473] deploymachines hwe_kernel=ga-16.04 system_id=xetx4t distro_series=xenial
2019-03-05 02:15:47,715 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305021547704913
2019-03-05 02:15:47,735 [salt.minion      :1432][INFO    ][9775] Starting a new job with PID 9775
2019-03-05 02:15:47,760 [salt.minion      :1711][INFO    ][9775] Returning information for job: 20190305021547704913
2019-03-05 02:15:48,189 [salt.state       :300 ][INFO    ][9473] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-05 02:15:48,189 [salt.state       :1951][INFO    ][9473] Completed state [maas.deploy_machines] at time 02:15:48.189848 duration_in_ms=13332.073
2019-03-05 02:15:48,193 [salt.minion      :1711][INFO    ][9473] Returning information for job: 20190305021532661275
2019-03-05 02:15:48,853 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command state.apply with jid 20190305021548841339
2019-03-05 02:15:48,875 [salt.minion      :1432][INFO    ][9790] Starting a new job with PID 9790
2019-03-05 02:15:52,461 [salt.state       :915 ][INFO    ][9790] Loading fresh modules for state activity
2019-03-05 02:15:52,506 [salt.fileclient  :1219][INFO    ][9790] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-03-05 02:15:52,556 [salt.state       :1780][INFO    ][9790] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:52.556598
2019-03-05 02:15:52,557 [salt.state       :1813][INFO    ][9790] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-05 02:15:52,559 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9790] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-05 02:15:54,011 [salt.state       :300 ][INFO    ][9790] {'pid': 9804, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-05 02:15:54,012 [salt.state       :1951][INFO    ][9790] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:54.012034 duration_in_ms=1455.437
2019-03-05 02:15:54,013 [salt.state       :1780][INFO    ][9790] Running state [maas.wait_for_machine_status] at time 02:15:54.013211
2019-03-05 02:15:54,013 [salt.state       :1813][INFO    ][9790] Executing state module.run for [maas.wait_for_machine_status]
2019-03-05 02:15:54,013 [salt.utils.decorators:613 ][WARNING ][9790] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-05 02:15:57,338 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.68034005s left)
2019-03-05 02:16:03,970 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305021603957648
2019-03-05 02:16:03,993 [salt.minion      :1432][INFO    ][9827] Starting a new job with PID 9827
2019-03-05 02:16:04,016 [salt.minion      :1711][INFO    ][9827] Returning information for job: 20190305021603957648
2019-03-05 02:16:30,853 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.16466117s left)
2019-03-05 02:16:34,021 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305021634008969
2019-03-05 02:16:34,041 [salt.minion      :1432][INFO    ][9857] Starting a new job with PID 9857
2019-03-05 02:16:34,064 [salt.minion      :1711][INFO    ][9857] Returning information for job: 20190305021634008969
2019-03-05 02:17:04,115 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305021704102561
2019-03-05 02:17:04,137 [salt.minion      :1432][INFO    ][9903] Starting a new job with PID 9903
2019-03-05 02:17:04,161 [salt.minion      :1711][INFO    ][9903] Returning information for job: 20190305021704102561
2019-03-05 02:17:04,342 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.67591906s left)
2019-03-05 02:17:34,162 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305021734149465
2019-03-05 02:17:34,184 [salt.minion      :1432][INFO    ][10018] Starting a new job with PID 10018
2019-03-05 02:17:34,208 [salt.minion      :1711][INFO    ][10018] Returning information for job: 20190305021734149465
2019-03-05 02:17:37,333 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.68493199s left)
2019-03-05 02:18:04,217 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305021804204573
2019-03-05 02:18:04,239 [salt.minion      :1432][INFO    ][10199] Starting a new job with PID 10199
2019-03-05 02:18:04,263 [salt.minion      :1711][INFO    ][10199] Returning information for job: 20190305021804204573
2019-03-05 02:18:10,360 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.65757298s left)
2019-03-05 02:18:34,271 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305021834258625
2019-03-05 02:18:34,293 [salt.minion      :1432][INFO    ][10521] Starting a new job with PID 10521
2019-03-05 02:18:34,316 [salt.minion      :1711][INFO    ][10521] Returning information for job: 20190305021834258625
2019-03-05 02:18:43,697 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.3208971s left)
2019-03-05 02:19:04,332 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305021904320040
2019-03-05 02:19:04,355 [salt.minion      :1432][INFO    ][10749] Starting a new job with PID 10749
2019-03-05 02:19:04,378 [salt.minion      :1711][INFO    ][10749] Returning information for job: 20190305021904320040
2019-03-05 02:19:16,864 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2047.15449405s left)
2019-03-05 02:19:34,392 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305021934379826
2019-03-05 02:19:34,414 [salt.minion      :1432][INFO    ][11114] Starting a new job with PID 11114
2019-03-05 02:19:34,437 [salt.minion      :1711][INFO    ][11114] Returning information for job: 20190305021934379826
2019-03-05 02:19:50,442 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.57557917s left)
2019-03-05 02:20:04,454 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305022004441570
2019-03-05 02:20:04,476 [salt.minion      :1432][INFO    ][11197] Starting a new job with PID 11197
2019-03-05 02:20:04,500 [salt.minion      :1711][INFO    ][11197] Returning information for job: 20190305022004441570
2019-03-05 02:20:24,100 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.91798306s left)
2019-03-05 02:20:34,530 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305022034512325
2019-03-05 02:20:34,551 [salt.minion      :1432][INFO    ][11246] Starting a new job with PID 11246
2019-03-05 02:20:34,574 [salt.minion      :1711][INFO    ][11246] Returning information for job: 20190305022034512325
2019-03-05 02:20:57,656 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.36191916s left)
2019-03-05 02:21:04,597 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305022104584189
2019-03-05 02:21:04,619 [salt.minion      :1432][INFO    ][11298] Starting a new job with PID 11298
2019-03-05 02:21:04,640 [salt.minion      :1711][INFO    ][11298] Returning information for job: 20190305022104584189
2019-03-05 02:21:31,130 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1912.88778305s left)
2019-03-05 02:21:34,674 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305022134662612
2019-03-05 02:21:34,696 [salt.minion      :1432][INFO    ][11450] Starting a new job with PID 11450
2019-03-05 02:21:34,718 [salt.minion      :1711][INFO    ][11450] Returning information for job: 20190305022134662612
2019-03-05 02:22:04,663 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.35458112s left)
2019-03-05 02:22:04,751 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305022204738578
2019-03-05 02:22:04,773 [salt.minion      :1432][INFO    ][11560] Starting a new job with PID 11560
2019-03-05 02:22:04,795 [salt.minion      :1711][INFO    ][11560] Returning information for job: 20190305022204738578
2019-03-05 02:22:34,833 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305022234820196
2019-03-05 02:22:34,855 [salt.minion      :1432][INFO    ][11717] Starting a new job with PID 11717
2019-03-05 02:22:34,877 [salt.minion      :1711][INFO    ][11717] Returning information for job: 20190305022234820196
2019-03-05 02:22:37,408 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.61011004s left)
2019-03-05 02:23:04,918 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305022304905150
2019-03-05 02:23:04,941 [salt.minion      :1432][INFO    ][11850] Starting a new job with PID 11850
2019-03-05 02:23:04,963 [salt.minion      :1711][INFO    ][11850] Returning information for job: 20190305022304905150
2019-03-05 02:23:10,739 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1813.27894521s left)
2019-03-05 02:23:35,012 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305022334999136
2019-03-05 02:23:35,034 [salt.minion      :1432][INFO    ][12067] Starting a new job with PID 12067
2019-03-05 02:23:35,058 [salt.minion      :1711][INFO    ][12067] Returning information for job: 20190305022334999136
2019-03-05 02:23:44,319 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1779.69939709s left)
2019-03-05 02:24:05,118 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305022405105188
2019-03-05 02:24:05,139 [salt.minion      :1432][INFO    ][12137] Starting a new job with PID 12137
2019-03-05 02:24:05,162 [salt.minion      :1711][INFO    ][12137] Returning information for job: 20190305022405105188
2019-03-05 02:24:17,766 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1746.2522912s left)
2019-03-05 02:24:35,273 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305022435259658
2019-03-05 02:24:35,294 [salt.minion      :1432][INFO    ][12313] Starting a new job with PID 12313
2019-03-05 02:24:35,318 [salt.minion      :1711][INFO    ][12313] Returning information for job: 20190305022435259658
2019-03-05 02:24:51,420 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1712.5984242s left)
2019-03-05 02:25:05,328 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305022505320627
2019-03-05 02:25:05,350 [salt.minion      :1432][INFO    ][12472] Starting a new job with PID 12472
2019-03-05 02:25:05,371 [salt.minion      :1711][INFO    ][12472] Returning information for job: 20190305022505320627
2019-03-05 02:25:24,869 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1679.148803s left)
2019-03-05 02:25:35,451 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305022535437007
2019-03-05 02:25:35,470 [salt.minion      :1432][INFO    ][12858] Starting a new job with PID 12858
2019-03-05 02:25:35,489 [salt.minion      :1711][INFO    ][12858] Returning information for job: 20190305022535437007
2019-03-05 02:25:58,343 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1645.67477417s left)
2019-03-05 02:26:05,575 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305022605562591
2019-03-05 02:26:05,598 [salt.minion      :1432][INFO    ][12927] Starting a new job with PID 12927
2019-03-05 02:26:05,621 [salt.minion      :1711][INFO    ][12927] Returning information for job: 20190305022605562591
2019-03-05 02:26:31,738 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1612.27984118s left)
2019-03-05 02:26:35,719 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305022635706698
2019-03-05 02:26:35,740 [salt.minion      :1432][INFO    ][12972] Starting a new job with PID 12972
2019-03-05 02:26:35,762 [salt.minion      :1711][INFO    ][12972] Returning information for job: 20190305022635706698
2019-03-05 02:27:05,355 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1578.66326404s left)
2019-03-05 02:27:05,870 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305022705853813
2019-03-05 02:27:05,893 [salt.minion      :1432][INFO    ][13001] Starting a new job with PID 13001
2019-03-05 02:27:05,916 [salt.minion      :1711][INFO    ][13001] Returning information for job: 20190305022705853813
2019-03-05 02:27:36,026 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305022736013605
2019-03-05 02:27:36,045 [salt.minion      :1432][INFO    ][13046] Starting a new job with PID 13046
2019-03-05 02:27:36,068 [salt.minion      :1711][INFO    ][13046] Returning information for job: 20190305022736013605
2019-03-05 02:27:38,739 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1545.27986503s left)
2019-03-05 02:28:06,186 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305022806173933
2019-03-05 02:28:06,210 [salt.minion      :1432][INFO    ][13074] Starting a new job with PID 13074
2019-03-05 02:28:06,235 [salt.minion      :1711][INFO    ][13074] Returning information for job: 20190305022806173933
2019-03-05 02:28:12,480 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1511.53760099s left)
2019-03-05 02:28:36,368 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305022836354618
2019-03-05 02:28:36,390 [salt.minion      :1432][INFO    ][13125] Starting a new job with PID 13125
2019-03-05 02:28:36,414 [salt.minion      :1711][INFO    ][13125] Returning information for job: 20190305022836354618
2019-03-05 02:28:46,080 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1477.9385252s left)
2019-03-05 02:29:06,554 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305022906540427
2019-03-05 02:29:06,577 [salt.minion      :1432][INFO    ][13286] Starting a new job with PID 13286
2019-03-05 02:29:06,601 [salt.minion      :1711][INFO    ][13286] Returning information for job: 20190305022906540427
2019-03-05 02:29:19,678 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1444.33983397s left)
2019-03-05 02:29:36,753 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305022936740049
2019-03-05 02:29:36,775 [salt.minion      :1432][INFO    ][13360] Starting a new job with PID 13360
2019-03-05 02:29:36,797 [salt.minion      :1711][INFO    ][13360] Returning information for job: 20190305022936740049
2019-03-05 02:29:53,306 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1410.71182704s left)
2019-03-05 02:30:06,959 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305023006947459
2019-03-05 02:30:06,983 [salt.minion      :1432][INFO    ][13393] Starting a new job with PID 13393
2019-03-05 02:30:07,007 [salt.minion      :1711][INFO    ][13393] Returning information for job: 20190305023006947459
2019-03-05 02:30:26,258 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1377.76021719s left)
2019-03-05 02:30:37,178 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305023037165382
2019-03-05 02:30:37,200 [salt.minion      :1432][INFO    ][13442] Starting a new job with PID 13442
2019-03-05 02:30:37,223 [salt.minion      :1711][INFO    ][13442] Returning information for job: 20190305023037165382
2019-03-05 02:30:59,809 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1344.20946407s left)
2019-03-05 02:31:07,410 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305023107394386
2019-03-05 02:31:07,432 [salt.minion      :1432][INFO    ][13471] Starting a new job with PID 13471
2019-03-05 02:31:07,457 [salt.minion      :1711][INFO    ][13471] Returning information for job: 20190305023107394386
2019-03-05 02:31:32,767 [salt.loaded.ext.module.maas:1023][INFO    ][9790] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1311.25078607s left)
2019-03-05 02:31:37,438 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305023137425938
2019-03-05 02:31:37,460 [salt.minion      :1432][INFO    ][13521] Starting a new job with PID 13521
2019-03-05 02:31:37,483 [salt.minion      :1711][INFO    ][13521] Returning information for job: 20190305023137425938
2019-03-05 02:32:05,618 [salt.loaded.ext.module.maas:993 ][INFO    ][9790] Machine me4yge mark broken
2019-03-05 02:32:06,409 [salt.loaded.ext.module.maas:996 ][INFO    ][9790] Machine me4yge mark fixed
2019-03-05 02:32:07,421 [salt.loaded.ext.module.maas:684 ][INFO    ][9790] deploymachines hwe_kernel=ga-16.04 system_id=me4yge distro_series=xenial
2019-03-05 02:32:07,479 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305023207466605
2019-03-05 02:32:07,502 [salt.minion      :1432][INFO    ][13569] Starting a new job with PID 13569
2019-03-05 02:32:07,525 [salt.minion      :1711][INFO    ][13569] Returning information for job: 20190305023207466605
2019-03-05 02:32:09,848 [salt.loaded.ext.module.maas:160 ][ERROR   ][9790] Failed for object kvm03 reason Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node.
2019-03-05 02:32:09,850 [salt.state       :302 ][ERROR   ][9790] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm02'], 'errors': {'kvm03': "Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node."}, 'success': []}
2019-03-05 02:32:09,850 [salt.state       :1951][INFO    ][9790] Completed state [maas.wait_for_machine_status] at time 02:32:09.850646 duration_in_ms=975837.429
2019-03-05 02:32:09,855 [salt.minion      :1711][INFO    ][9790] Returning information for job: 20190305021548841339
2019-03-05 02:32:20,609 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command pillar.get with jid 20190305023220597455
2019-03-05 02:32:20,630 [salt.minion      :1432][INFO    ][13645] Starting a new job with PID 13645
2019-03-05 02:32:20,638 [salt.minion      :1711][INFO    ][13645] Returning information for job: 20190305023220597455
2019-03-05 02:32:21,141 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command service.status with jid 20190305023221127772
2019-03-05 02:32:21,161 [salt.minion      :1432][INFO    ][13650] Starting a new job with PID 13650
2019-03-05 02:32:21,544 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13650] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-03-05 02:32:21,578 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13650] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-03-05 02:32:21,594 [salt.minion      :1711][INFO    ][13650] Returning information for job: 20190305023221127772
2019-03-05 02:32:22,136 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command state.apply with jid 20190305023222129861
2019-03-05 02:32:22,155 [salt.minion      :1432][INFO    ][13661] Starting a new job with PID 13661
2019-03-05 02:32:25,706 [salt.state       :915 ][INFO    ][13661] Loading fresh modules for state activity
2019-03-05 02:32:26,124 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13661] Executing command 'salt-minion --version' in directory '/root'
2019-03-05 02:32:26,444 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13661] Executing command 'salt-minion --version' in directory '/root'
2019-03-05 02:32:27,318 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13661] Executing command 'salt-minion --version' in directory '/root'
2019-03-05 02:32:27,673 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13661] Executing command 'salt-minion --version' in directory '/root'
2019-03-05 02:32:29,288 [salt.state       :1780][INFO    ][13661] Running state [salt-minion] at time 02:32:29.288495
2019-03-05 02:32:29,288 [salt.state       :1813][INFO    ][13661] Executing state pkg.installed for [salt-minion]
2019-03-05 02:32:29,289 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13661] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-05 02:32:29,371 [salt.state       :300 ][INFO    ][13661] All specified packages are already installed
2019-03-05 02:32:29,372 [salt.state       :1951][INFO    ][13661] Completed state [salt-minion] at time 02:32:29.372208 duration_in_ms=83.708
2019-03-05 02:32:29,372 [salt.state       :1780][INFO    ][13661] Running state [salt_minion_dependency_packages] at time 02:32:29.372538
2019-03-05 02:32:29,372 [salt.state       :1813][INFO    ][13661] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-03-05 02:32:29,379 [salt.state       :300 ][INFO    ][13661] All specified packages are already installed
2019-03-05 02:32:29,379 [salt.state       :1951][INFO    ][13661] Completed state [salt_minion_dependency_packages] at time 02:32:29.379303 duration_in_ms=6.764
2019-03-05 02:32:29,382 [salt.state       :1780][INFO    ][13661] Running state [/etc/salt/minion.d/minion.conf] at time 02:32:29.382237
2019-03-05 02:32:29,382 [salt.state       :1813][INFO    ][13661] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-03-05 02:32:29,576 [salt.state       :300 ][INFO    ][13661] File /etc/salt/minion.d/minion.conf is in the correct state
2019-03-05 02:32:29,576 [salt.state       :1951][INFO    ][13661] Completed state [/etc/salt/minion.d/minion.conf] at time 02:32:29.576627 duration_in_ms=194.388
2019-03-05 02:32:29,577 [salt.state       :1780][INFO    ][13661] Running state [python-netaddr] at time 02:32:29.577061
2019-03-05 02:32:29,577 [salt.state       :1813][INFO    ][13661] Executing state pkg.installed for [python-netaddr]
2019-03-05 02:32:29,588 [salt.state       :300 ][INFO    ][13661] All specified packages are already installed
2019-03-05 02:32:29,588 [salt.state       :1951][INFO    ][13661] Completed state [python-netaddr] at time 02:32:29.588871 duration_in_ms=11.81
2019-03-05 02:32:29,593 [salt.state       :1780][INFO    ][13661] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:32:29.593176
2019-03-05 02:32:29,593 [salt.state       :1813][INFO    ][13661] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-03-05 02:32:29,609 [salt.state       :300 ][INFO    ][13661] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-03-05 02:32:29,610 [salt.state       :1951][INFO    ][13661] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:32:29.610166 duration_in_ms=16.99
2019-03-05 02:32:29,611 [salt.state       :1780][INFO    ][13661] Running state [salt-minion] at time 02:32:29.611892
2019-03-05 02:32:29,612 [salt.state       :1813][INFO    ][13661] Executing state service.running for [salt-minion]
2019-03-05 02:32:29,613 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13661] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-05 02:32:29,652 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13661] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-03-05 02:32:29,672 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13661] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-03-05 02:32:29,693 [salt.state       :300 ][INFO    ][13661] The service salt-minion is already running
2019-03-05 02:32:29,694 [salt.state       :1951][INFO    ][13661] Completed state [salt-minion] at time 02:32:29.694529 duration_in_ms=82.637
2019-03-05 02:32:29,697 [salt.state       :1780][INFO    ][13661] Running state [/etc/salt/grains.d] at time 02:32:29.697359
2019-03-05 02:32:29,698 [salt.state       :1813][INFO    ][13661] Executing state file.directory for [/etc/salt/grains.d]
2019-03-05 02:32:29,699 [salt.state       :300 ][INFO    ][13661] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-03-05 02:32:29,700 [salt.state       :1951][INFO    ][13661] Completed state [/etc/salt/grains.d] at time 02:32:29.700035 duration_in_ms=2.676
2019-03-05 02:32:29,701 [salt.state       :1780][INFO    ][13661] Running state [/etc/salt/grains] at time 02:32:29.701242
2019-03-05 02:32:29,701 [salt.state       :1813][INFO    ][13661] Executing state file.managed for [/etc/salt/grains]
2019-03-05 02:32:29,702 [salt.state       :300 ][INFO    ][13661] File /etc/salt/grains exists with proper permissions. No changes made.
2019-03-05 02:32:29,703 [salt.state       :1951][INFO    ][13661] Completed state [/etc/salt/grains] at time 02:32:29.703057 duration_in_ms=1.814
2019-03-05 02:32:29,703 [salt.state       :1780][INFO    ][13661] Running state [/etc/salt/grains.d/placeholder] at time 02:32:29.703858
2019-03-05 02:32:29,704 [salt.state       :1813][INFO    ][13661] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-03-05 02:32:29,705 [salt.state       :300 ][INFO    ][13661] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-03-05 02:32:29,705 [salt.state       :1951][INFO    ][13661] Completed state [/etc/salt/grains.d/placeholder] at time 02:32:29.705594 duration_in_ms=1.736
2019-03-05 02:32:29,706 [salt.state       :1780][INFO    ][13661] Running state [/etc/salt/grains.d/sphinx] at time 02:32:29.706337
2019-03-05 02:32:29,706 [salt.state       :1813][INFO    ][13661] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-03-05 02:32:29,719 [salt.state       :300 ][INFO    ][13661] File /etc/salt/grains.d/sphinx is in the correct state
2019-03-05 02:32:29,719 [salt.state       :1951][INFO    ][13661] Completed state [/etc/salt/grains.d/sphinx] at time 02:32:29.719871 duration_in_ms=13.534
2019-03-05 02:32:29,723 [salt.state       :1780][INFO    ][13661] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:32:29.723238
2019-03-05 02:32:29,723 [salt.state       :1813][INFO    ][13661] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-03-05 02:32:29,724 [salt.state       :300 ][INFO    ][13661] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-03-05 02:32:29,724 [salt.state       :1951][INFO    ][13661] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:32:29.724413 duration_in_ms=1.175
2019-03-05 02:32:29,725 [salt.state       :1780][INFO    ][13661] Running state [/etc/salt/grains.d/dns_records] at time 02:32:29.725054
2019-03-05 02:32:29,725 [salt.state       :1813][INFO    ][13661] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-03-05 02:32:29,737 [salt.state       :300 ][INFO    ][13661] File /etc/salt/grains.d/dns_records is in the correct state
2019-03-05 02:32:29,737 [salt.state       :1951][INFO    ][13661] Completed state [/etc/salt/grains.d/dns_records] at time 02:32:29.737559 duration_in_ms=12.504
2019-03-05 02:32:29,738 [salt.state       :1780][INFO    ][13661] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:32:29.738770
2019-03-05 02:32:29,739 [salt.state       :1813][INFO    ][13661] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-03-05 02:32:29,739 [salt.state       :300 ][INFO    ][13661] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-03-05 02:32:29,739 [salt.state       :1951][INFO    ][13661] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:32:29.739870 duration_in_ms=1.099
2019-03-05 02:32:29,740 [salt.state       :1780][INFO    ][13661] Running state [/etc/salt/grains.d/salt] at time 02:32:29.740461
2019-03-05 02:32:29,740 [salt.state       :1813][INFO    ][13661] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-03-05 02:32:29,755 [salt.state       :300 ][INFO    ][13661] File /etc/salt/grains.d/salt is in the correct state
2019-03-05 02:32:29,755 [salt.state       :1951][INFO    ][13661] Completed state [/etc/salt/grains.d/salt] at time 02:32:29.755464 duration_in_ms=15.003
2019-03-05 02:32:29,756 [salt.state       :1780][INFO    ][13661] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:32:29.756610
2019-03-05 02:32:29,757 [salt.state       :1813][INFO    ][13661] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-03-05 02:32:29,757 [salt.state       :300 ][INFO    ][13661] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-03-05 02:32:29,757 [salt.state       :1951][INFO    ][13661] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:32:29.757664 duration_in_ms=1.054
2019-03-05 02:32:29,760 [salt.state       :1780][INFO    ][13661] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:32:29.760055
2019-03-05 02:32:29,760 [salt.state       :1813][INFO    ][13661] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-03-05 02:32:29,760 [salt.state       :300 ][INFO    ][13661] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-03-05 02:32:29,761 [salt.state       :1951][INFO    ][13661] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:32:29.761115 duration_in_ms=1.061
2019-03-05 02:32:29,762 [salt.state       :1780][INFO    ][13661] Running state [mine.update] at time 02:32:29.761952
2019-03-05 02:32:29,762 [salt.state       :1813][INFO    ][13661] Executing state module.wait for [mine.update]
2019-03-05 02:32:29,762 [salt.state       :300 ][INFO    ][13661] No changes made for mine.update
2019-03-05 02:32:29,762 [salt.state       :1951][INFO    ][13661] Completed state [mine.update] at time 02:32:29.762929 duration_in_ms=0.976
2019-03-05 02:32:29,763 [salt.state       :1780][INFO    ][13661] Running state [ca-certificates] at time 02:32:29.763236
2019-03-05 02:32:29,763 [salt.state       :1813][INFO    ][13661] Executing state pkg.installed for [ca-certificates]
2019-03-05 02:32:29,772 [salt.state       :300 ][INFO    ][13661] All specified packages are already installed
2019-03-05 02:32:29,772 [salt.state       :1951][INFO    ][13661] Completed state [ca-certificates] at time 02:32:29.772939 duration_in_ms=9.703
2019-03-05 02:32:29,773 [salt.state       :1780][INFO    ][13661] Running state [update-ca-certificates] at time 02:32:29.773769
2019-03-05 02:32:29,774 [salt.state       :1813][INFO    ][13661] Executing state cmd.wait for [update-ca-certificates]
2019-03-05 02:32:29,774 [salt.state       :300 ][INFO    ][13661] No changes made for update-ca-certificates
2019-03-05 02:32:29,774 [salt.state       :1951][INFO    ][13661] Completed state [update-ca-certificates] at time 02:32:29.774728 duration_in_ms=0.959
2019-03-05 02:32:29,775 [salt.state       :1780][INFO    ][13661] Running state [iptables] at time 02:32:29.775016
2019-03-05 02:32:29,775 [salt.state       :1813][INFO    ][13661] Executing state pkg.installed for [iptables]
2019-03-05 02:32:29,783 [salt.state       :300 ][INFO    ][13661] All specified packages are already installed
2019-03-05 02:32:29,783 [salt.state       :1951][INFO    ][13661] Completed state [iptables] at time 02:32:29.783801 duration_in_ms=8.785
2019-03-05 02:32:29,784 [salt.state       :1780][INFO    ][13661] Running state [iptables-persistent] at time 02:32:29.784081
2019-03-05 02:32:29,784 [salt.state       :1813][INFO    ][13661] Executing state pkg.installed for [iptables-persistent]
2019-03-05 02:32:29,792 [salt.state       :300 ][INFO    ][13661] All specified packages are already installed
2019-03-05 02:32:29,792 [salt.state       :1951][INFO    ][13661] Completed state [iptables-persistent] at time 02:32:29.792425 duration_in_ms=8.343
2019-03-05 02:32:29,793 [salt.state       :1780][INFO    ][13661] Running state [iptables_modules_v4_load] at time 02:32:29.793615
2019-03-05 02:32:29,793 [salt.state       :1813][INFO    ][13661] Executing state kmod.present for [iptables_modules_v4_load]
2019-03-05 02:32:29,794 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13661] Executing command 'lsmod' in directory '/root'
2019-03-05 02:32:29,818 [salt.state       :300 ][INFO    ][13661] Kernel modules iptable_filter, ip_tables are already present
2019-03-05 02:32:29,819 [salt.state       :1951][INFO    ][13661] Completed state [iptables_modules_v4_load] at time 02:32:29.818928 duration_in_ms=25.313
2019-03-05 02:32:29,819 [salt.state       :1780][INFO    ][13661] Running state [/etc/iptables/rules.v4] at time 02:32:29.819728
2019-03-05 02:32:29,820 [salt.state       :1813][INFO    ][13661] Executing state file.managed for [/etc/iptables/rules.v4]
2019-03-05 02:32:29,929 [salt.state       :300 ][INFO    ][13661] File /etc/iptables/rules.v4 is in the correct state
2019-03-05 02:32:29,929 [salt.state       :1951][INFO    ][13661] Completed state [/etc/iptables/rules.v4] at time 02:32:29.929309 duration_in_ms=109.581
2019-03-05 02:32:29,930 [salt.state       :1780][INFO    ][13661] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:32:29.930366
2019-03-05 02:32:29,930 [salt.state       :1813][INFO    ][13661] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-03-05 02:32:29,931 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13661] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-03-05 02:32:29,950 [salt.state       :300 ][INFO    ][13661] onlyif execution failed
2019-03-05 02:32:29,951 [salt.state       :1951][INFO    ][13661] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:32:29.950984 duration_in_ms=20.618
2019-03-05 02:32:29,952 [salt.state       :1780][INFO    ][13661] Running state [netfilter-persistent] at time 02:32:29.952016
2019-03-05 02:32:29,952 [salt.state       :1813][INFO    ][13661] Executing state service.running for [netfilter-persistent]
2019-03-05 02:32:29,953 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13661] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-03-05 02:32:29,971 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13661] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-03-05 02:32:29,987 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13661] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-03-05 02:32:30,003 [salt.state       :300 ][INFO    ][13661] The service netfilter-persistent is already running
2019-03-05 02:32:30,003 [salt.state       :1951][INFO    ][13661] Completed state [netfilter-persistent] at time 02:32:30.003754 duration_in_ms=51.738
2019-03-05 02:32:30,004 [salt.state       :1780][INFO    ][13661] Running state [iptables_extra.remove_stale_tables] at time 02:32:30.004617
2019-03-05 02:32:30,004 [salt.state       :1813][INFO    ][13661] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-03-05 02:32:30,005 [salt.state       :300 ][INFO    ][13661] No changes made for iptables_extra.remove_stale_tables
2019-03-05 02:32:30,005 [salt.state       :1951][INFO    ][13661] Completed state [iptables_extra.remove_stale_tables] at time 02:32:30.005565 duration_in_ms=0.948
2019-03-05 02:32:30,005 [salt.state       :1780][INFO    ][13661] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:32:30.005833
2019-03-05 02:32:30,006 [salt.state       :1813][INFO    ][13661] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-03-05 02:32:30,006 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13661] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-03-05 02:32:30,020 [salt.state       :300 ][INFO    ][13661] onlyif execution failed
2019-03-05 02:32:30,020 [salt.state       :1951][INFO    ][13661] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:32:30.020510 duration_in_ms=14.677
2019-03-05 02:32:30,021 [salt.state       :1780][INFO    ][13661] Running state [/etc/iptables/rules.v6] at time 02:32:30.021520
2019-03-05 02:32:30,021 [salt.state       :1813][INFO    ][13661] Executing state file.absent for [/etc/iptables/rules.v6]
2019-03-05 02:32:30,022 [salt.state       :300 ][INFO    ][13661] File /etc/iptables/rules.v6 is not present
2019-03-05 02:32:30,022 [salt.state       :1951][INFO    ][13661] Completed state [/etc/iptables/rules.v6] at time 02:32:30.022601 duration_in_ms=1.082
2019-03-05 02:32:30,023 [salt.state       :1780][INFO    ][13661] Running state [iptables_extra.flush_all] at time 02:32:30.023301
2019-03-05 02:32:30,023 [salt.state       :1813][INFO    ][13661] Executing state module.wait for [iptables_extra.flush_all]
2019-03-05 02:32:30,023 [salt.state       :300 ][INFO    ][13661] No changes made for iptables_extra.flush_all
2019-03-05 02:32:30,024 [salt.state       :1951][INFO    ][13661] Completed state [iptables_extra.flush_all] at time 02:32:30.024134 duration_in_ms=0.833
2019-03-05 02:32:30,027 [salt.minion      :1711][INFO    ][13661] Returning information for job: 20190305023222129861
2019-03-05 02:32:30,658 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command state.apply with jid 20190305023230644428
2019-03-05 02:32:30,680 [salt.minion      :1432][INFO    ][13742] Starting a new job with PID 13742
2019-03-05 02:32:31,339 [salt.state       :915 ][INFO    ][13742] Loading fresh modules for state activity
2019-03-05 02:32:31,962 [salt.state       :1780][INFO    ][13742] Running state [maas-rack-controller] at time 02:32:31.962811
2019-03-05 02:32:31,963 [salt.state       :1813][INFO    ][13742] Executing state pkg.installed for [maas-rack-controller]
2019-03-05 02:32:31,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13742] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-05 02:32:32,088 [salt.state       :300 ][INFO    ][13742] All specified packages are already installed
2019-03-05 02:32:32,088 [salt.state       :1951][INFO    ][13742] Completed state [maas-rack-controller] at time 02:32:32.088383 duration_in_ms=125.572
2019-03-05 02:32:32,088 [salt.state       :1780][INFO    ][13742] Running state [ipmitool] at time 02:32:32.088640
2019-03-05 02:32:32,088 [salt.state       :1813][INFO    ][13742] Executing state pkg.installed for [ipmitool]
2019-03-05 02:32:32,093 [salt.state       :300 ][INFO    ][13742] All specified packages are already installed
2019-03-05 02:32:32,093 [salt.state       :1951][INFO    ][13742] Completed state [ipmitool] at time 02:32:32.093806 duration_in_ms=5.167
2019-03-05 02:32:32,095 [salt.state       :1780][INFO    ][13742] Running state [/etc/maas/rackd.conf] at time 02:32:32.095839
2019-03-05 02:32:32,096 [salt.state       :1813][INFO    ][13742] Executing state file.line for [/etc/maas/rackd.conf]
2019-03-05 02:32:32,096 [salt.state       :300 ][INFO    ][13742] No changes needed to be made
2019-03-05 02:32:32,097 [salt.state       :1951][INFO    ][13742] Completed state [/etc/maas/rackd.conf] at time 02:32:32.096980 duration_in_ms=1.14
2019-03-05 02:32:32,097 [salt.state       :1780][INFO    ][13742] Running state [/etc/maas/rackd.conf] at time 02:32:32.097154
2019-03-05 02:32:32,097 [salt.state       :1813][INFO    ][13742] Executing state file.managed for [/etc/maas/rackd.conf]
2019-03-05 02:32:32,097 [salt.loaded.int.states.file:2298][WARNING ][13742] 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-03-05 02:32:32,097 [salt.state       :300 ][INFO    ][13742] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-03-05 02:32:32,098 [salt.state       :1951][INFO    ][13742] Completed state [/etc/maas/rackd.conf] at time 02:32:32.098060 duration_in_ms=0.906
2019-03-05 02:32:32,098 [salt.state       :1780][INFO    ][13742] Running state [maas-rackd] at time 02:32:32.098792
2019-03-05 02:32:32,099 [salt.state       :1813][INFO    ][13742] Executing state service.running for [maas-rackd]
2019-03-05 02:32:32,099 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13742] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-03-05 02:32:32,131 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13742] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-03-05 02:32:32,146 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13742] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-03-05 02:32:32,161 [salt.state       :300 ][INFO    ][13742] The service maas-rackd is already running
2019-03-05 02:32:32,162 [salt.state       :1951][INFO    ][13742] Completed state [maas-rackd] at time 02:32:32.162236 duration_in_ms=63.444
2019-03-05 02:32:32,163 [salt.minion      :1711][INFO    ][13742] Returning information for job: 20190305023230644428
2019-03-05 02:32:32,740 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command state.apply with jid 20190305023232731049
2019-03-05 02:32:32,760 [salt.minion      :1432][INFO    ][13766] Starting a new job with PID 13766
2019-03-05 02:32:33,351 [salt.state       :915 ][INFO    ][13766] Loading fresh modules for state activity
2019-03-05 02:32:33,985 [salt.state       :1780][INFO    ][13766] Running state [maas-region-controller] at time 02:32:33.985522
2019-03-05 02:32:33,985 [salt.state       :1813][INFO    ][13766] Executing state pkg.installed for [maas-region-controller]
2019-03-05 02:32:33,986 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13766] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-05 02:32:34,056 [salt.state       :300 ][INFO    ][13766] All specified packages are already installed
2019-03-05 02:32:34,056 [salt.state       :1951][INFO    ][13766] Completed state [maas-region-controller] at time 02:32:34.056937 duration_in_ms=71.415
2019-03-05 02:32:34,057 [salt.state       :1780][INFO    ][13766] Running state [python-oauth] at time 02:32:34.057175
2019-03-05 02:32:34,057 [salt.state       :1813][INFO    ][13766] Executing state pkg.installed for [python-oauth]
2019-03-05 02:32:34,063 [salt.state       :300 ][INFO    ][13766] All specified packages are already installed
2019-03-05 02:32:34,063 [salt.state       :1951][INFO    ][13766] Completed state [python-oauth] at time 02:32:34.063166 duration_in_ms=5.992
2019-03-05 02:32:34,065 [salt.state       :1780][INFO    ][13766] Running state [/etc/maas/regiond.conf] at time 02:32:34.065383
2019-03-05 02:32:34,065 [salt.state       :1813][INFO    ][13766] Executing state file.replace for [/etc/maas/regiond.conf]
2019-03-05 02:32:34,097 [salt.state       :300 ][INFO    ][13766] No changes needed to be made
2019-03-05 02:32:34,097 [salt.state       :1951][INFO    ][13766] Completed state [/etc/maas/regiond.conf] at time 02:32:34.097239 duration_in_ms=31.855
2019-03-05 02:32:34,097 [salt.state       :1780][INFO    ][13766] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:32:34.097617
2019-03-05 02:32:34,097 [salt.state       :1813][INFO    ][13766] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-03-05 02:32:34,156 [salt.state       :300 ][INFO    ][13766] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-03-05 02:32:34,156 [salt.state       :1951][INFO    ][13766] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:32:34.156570 duration_in_ms=58.953
2019-03-05 02:32:34,157 [salt.state       :1780][INFO    ][13766] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:32:34.156979
2019-03-05 02:32:34,157 [salt.state       :1813][INFO    ][13766] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-05 02:32:34,181 [salt.state       :300 ][INFO    ][13766] No changes needed to be made
2019-03-05 02:32:34,181 [salt.state       :1951][INFO    ][13766] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:32:34.181607 duration_in_ms=24.628
2019-03-05 02:32:34,182 [salt.state       :1780][INFO    ][13766] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:32:34.181992
2019-03-05 02:32:34,182 [salt.state       :1813][INFO    ][13766] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-05 02:32:34,205 [salt.state       :300 ][INFO    ][13766] No changes needed to be made
2019-03-05 02:32:34,205 [salt.state       :1951][INFO    ][13766] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:32:34.205591 duration_in_ms=23.599
2019-03-05 02:32:34,206 [salt.state       :1780][INFO    ][13766] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:32:34.205991
2019-03-05 02:32:34,206 [salt.state       :1813][INFO    ][13766] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-03-05 02:32:34,224 [salt.state       :300 ][INFO    ][13766] No changes needed to be made
2019-03-05 02:32:34,225 [salt.state       :1951][INFO    ][13766] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:32:34.225060 duration_in_ms=19.069
2019-03-05 02:32:34,225 [salt.state       :1780][INFO    ][13766] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:32:34.225428
2019-03-05 02:32:34,225 [salt.state       :1813][INFO    ][13766] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-03-05 02:32:34,235 [salt.state       :300 ][INFO    ][13766] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-03-05 02:32:34,236 [salt.state       :1951][INFO    ][13766] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:32:34.236069 duration_in_ms=10.641
2019-03-05 02:32:34,237 [salt.state       :1780][INFO    ][13766] Running state [a2enmod headers] at time 02:32:34.237043
2019-03-05 02:32:34,237 [salt.state       :1813][INFO    ][13766] Executing state cmd.run for [a2enmod headers]
2019-03-05 02:32:34,237 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13766] Executing command 'a2enmod headers' in directory '/root'
2019-03-05 02:32:34,312 [salt.state       :300 ][INFO    ][13766] {'pid': 13785, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-03-05 02:32:34,312 [salt.state       :1951][INFO    ][13766] Completed state [a2enmod headers] at time 02:32:34.312666 duration_in_ms=75.621
2019-03-05 02:32:34,313 [salt.state       :1780][INFO    ][13766] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:32:34.313217
2019-03-05 02:32:34,313 [salt.state       :1813][INFO    ][13766] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-03-05 02:32:34,328 [salt.state       :300 ][INFO    ][13766] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-03-05 02:32:34,328 [salt.state       :1951][INFO    ][13766] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:32:34.328788 duration_in_ms=15.571
2019-03-05 02:32:34,329 [salt.state       :1780][INFO    ][13766] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:32:34.329355
2019-03-05 02:32:34,329 [salt.state       :1813][INFO    ][13766] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-03-05 02:32:34,390 [salt.state       :300 ][INFO    ][13766] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-03-05 02:32:34,390 [salt.state       :1951][INFO    ][13766] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:32:34.390537 duration_in_ms=61.182
2019-03-05 02:32:34,391 [salt.state       :1780][INFO    ][13766] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:32:34.390971
2019-03-05 02:32:34,391 [salt.state       :1813][INFO    ][13766] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-03-05 02:32:34,437 [salt.state       :300 ][INFO    ][13766] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-03-05 02:32:34,438 [salt.state       :1951][INFO    ][13766] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:32:34.438028 duration_in_ms=47.058
2019-03-05 02:32:34,438 [salt.state       :1780][INFO    ][13766] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:32:34.438360
2019-03-05 02:32:34,438 [salt.state       :1813][INFO    ][13766] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-03-05 02:32:34,491 [salt.state       :300 ][INFO    ][13766] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-03-05 02:32:34,491 [salt.state       :1951][INFO    ][13766] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:32:34.491851 duration_in_ms=53.49
2019-03-05 02:32:34,492 [salt.state       :1780][INFO    ][13766] Running state [/root/.pgpass] at time 02:32:34.492016
2019-03-05 02:32:34,492 [salt.state       :1813][INFO    ][13766] Executing state file.managed for [/root/.pgpass]
2019-03-05 02:32:34,527 [salt.state       :300 ][INFO    ][13766] File /root/.pgpass is in the correct state
2019-03-05 02:32:34,528 [salt.state       :1951][INFO    ][13766] Completed state [/root/.pgpass] at time 02:32:34.527967 duration_in_ms=35.951
2019-03-05 02:32:34,531 [salt.state       :1780][INFO    ][13766] Running state [maas-region syncdb --noinput] at time 02:32:34.531000
2019-03-05 02:32:34,531 [salt.state       :1813][INFO    ][13766] Executing state cmd.run for [maas-region syncdb --noinput]
2019-03-05 02:32:34,531 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13766] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-03-05 02:32:36,611 [salt.state       :300 ][INFO    ][13766] {'pid': 13798, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sites, auth, sessions, contenttypes, maasserver, piston3, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-03-05 02:32:36,611 [salt.state       :1951][INFO    ][13766] Completed state [maas-region syncdb --noinput] at time 02:32:36.611767 duration_in_ms=2080.766
2019-03-05 02:32:36,612 [salt.state       :2022][WARNING ][13766] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-05 02:32:36,615 [salt.state       :1780][INFO    ][13766] Running state [maas-regiond] at time 02:32:36.614987
2019-03-05 02:32:36,615 [salt.state       :1813][INFO    ][13766] Executing state service.running for [maas-regiond]
2019-03-05 02:32:36,616 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13766] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-03-05 02:32:36,646 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13766] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-03-05 02:32:36,667 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13766] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-03-05 02:32:36,683 [salt.state       :300 ][INFO    ][13766] The service maas-regiond is already running
2019-03-05 02:32:36,684 [salt.state       :1951][INFO    ][13766] Completed state [maas-regiond] at time 02:32:36.684136 duration_in_ms=69.147
2019-03-05 02:32:36,687 [salt.state       :1780][INFO    ][13766] Running state [bind9] at time 02:32:36.687100
2019-03-05 02:32:36,687 [salt.state       :1813][INFO    ][13766] Executing state service.running for [bind9]
2019-03-05 02:32:36,689 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13766] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-03-05 02:32:36,707 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13766] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-03-05 02:32:36,723 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13766] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-03-05 02:32:36,737 [salt.state       :300 ][INFO    ][13766] The service bind9 is already running
2019-03-05 02:32:36,738 [salt.state       :1951][INFO    ][13766] Completed state [bind9] at time 02:32:36.738267 duration_in_ms=51.167
2019-03-05 02:32:36,740 [salt.state       :1780][INFO    ][13766] Running state [apache2] at time 02:32:36.740401
2019-03-05 02:32:36,740 [salt.state       :1813][INFO    ][13766] Executing state service.running for [apache2]
2019-03-05 02:32:36,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13766] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-03-05 02:32:36,756 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13766] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-03-05 02:32:36,769 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13766] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-03-05 02:32:36,783 [salt.state       :300 ][INFO    ][13766] The service apache2 is already running
2019-03-05 02:32:36,784 [salt.state       :1951][INFO    ][13766] Completed state [apache2] at time 02:32:36.784265 duration_in_ms=43.863
2019-03-05 02:32:36,786 [salt.state       :1780][INFO    ][13766] Running state [maasng.wait_for_http_code] at time 02:32:36.786040
2019-03-05 02:32:36,786 [salt.state       :1813][INFO    ][13766] Executing state module.run for [maasng.wait_for_http_code]
2019-03-05 02:32:36,786 [salt.utils.decorators:613 ][WARNING ][13766] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-05 02:32:36,909 [salt.state       :300 ][INFO    ][13766] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-03-05 02:32:36,909 [salt.state       :1951][INFO    ][13766] Completed state [maasng.wait_for_http_code] at time 02:32:36.909416 duration_in_ms=123.374
2019-03-05 02:32:36,910 [salt.state       :1780][INFO    ][13766] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:32:36.910768
2019-03-05 02:32:36,911 [salt.state       :1813][INFO    ][13766] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-03-05 02:32:36,912 [salt.state       :300 ][INFO    ][13766] /var/lib/maas/.setup_admin exists
2019-03-05 02:32:36,912 [salt.state       :1951][INFO    ][13766] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:32:36.912428 duration_in_ms=1.659
2019-03-05 02:32:36,913 [salt.state       :1780][INFO    ][13766] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:32:36.913632
2019-03-05 02:32:36,914 [salt.state       :1813][INFO    ][13766] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-05 02:32:36,915 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13766] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-05 02:32:38,245 [salt.state       :300 ][INFO    ][13766] {'pid': 13835, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-05 02:32:38,245 [salt.state       :1951][INFO    ][13766] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:32:38.245400 duration_in_ms=1331.769
2019-03-05 02:32:38,248 [salt.state       :1780][INFO    ][13766] Running state [maas_region_boot_source_resources_mirror] at time 02:32:38.248921
2019-03-05 02:32:38,249 [salt.state       :1813][INFO    ][13766] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-03-05 02:32:38,355 [salt.state       :300 ][INFO    ][13766] {'changes': {}}
2019-03-05 02:32:38,355 [salt.state       :1951][INFO    ][13766] Completed state [maas_region_boot_source_resources_mirror] at time 02:32:38.355428 duration_in_ms=106.506
2019-03-05 02:32:38,356 [salt.state       :1780][INFO    ][13766] Running state [maasng.boot_resources_import] at time 02:32:38.356368
2019-03-05 02:32:38,356 [salt.state       :1813][INFO    ][13766] Executing state module.run for [maasng.boot_resources_import]
2019-03-05 02:32:38,357 [salt.utils.decorators:613 ][WARNING ][13766] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-05 02:32:38,469 [salt.loaded.ext.module.maasng:1600][INFO    ][13766] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-03-05 02:32:43,526 [salt.loaded.ext.module.maasng:1600][INFO    ][13766] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-03-05 02:32:47,848 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305023247835241
2019-03-05 02:32:47,870 [salt.minion      :1432][INFO    ][13865] Starting a new job with PID 13865
2019-03-05 02:32:47,895 [salt.minion      :1711][INFO    ][13865] Returning information for job: 20190305023247835241
2019-03-05 02:32:48,585 [salt.loaded.ext.module.maasng:1600][INFO    ][13766] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-03-05 02:32:53,699 [salt.state       :300 ][INFO    ][13766] {'ret': True}
2019-03-05 02:32:53,699 [salt.state       :1951][INFO    ][13766] Completed state [maasng.boot_resources_import] at time 02:32:53.699524 duration_in_ms=15343.154
2019-03-05 02:32:53,700 [salt.state       :1780][INFO    ][13766] Running state [maas_region_boot_sources_selection_xenial] at time 02:32:53.700695
2019-03-05 02:32:53,701 [salt.state       :1813][INFO    ][13766] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-03-05 02:32:53,908 [salt.state       :300 ][INFO    ][13766] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-03-05 02:32:53,909 [salt.state       :1951][INFO    ][13766] Completed state [maas_region_boot_sources_selection_xenial] at time 02:32:53.909204 duration_in_ms=208.507
2019-03-05 02:32:53,910 [salt.state       :1780][INFO    ][13766] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:32:53.910620
2019-03-05 02:32:53,911 [salt.state       :1813][INFO    ][13766] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-03-05 02:32:53,911 [salt.utils.decorators:613 ][WARNING ][13766] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-05 02:32:53,912 [salt.loaded.ext.module.maasng:1771][INFO    ][13766] boot-sources sync initiated for ALL Rack's
2019-03-05 02:32:54,961 [salt.state       :300 ][INFO    ][13766] {'ret': True}
2019-03-05 02:32:54,962 [salt.state       :1951][INFO    ][13766] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:32:54.962350 duration_in_ms=1051.728
2019-03-05 02:32:54,965 [salt.state       :1780][INFO    ][13766] Running state [maas.process_maas_config] at time 02:32:54.965035
2019-03-05 02:32:54,965 [salt.state       :1813][INFO    ][13766] Executing state module.run for [maas.process_maas_config]
2019-03-05 02:32:54,966 [salt.utils.decorators:613 ][WARNING ][13766] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-05 02:32:54,967 [salt.loaded.ext.module.maas:92  ][INFO    ][13766] maasconfig name=enable_http_proxy value=True
2019-03-05 02:32:55,029 [salt.loaded.ext.module.maas:92  ][INFO    ][13766] maasconfig name=upstream_dns value=8.8.8.8
2019-03-05 02:32:55,088 [salt.loaded.ext.module.maas:92  ][INFO    ][13766] maasconfig name=commissioning_distro_series value=xenial
2019-03-05 02:32:58,320 [salt.loaded.ext.module.maas:92  ][INFO    ][13766] maasconfig name=default_osystem value=ubuntu
2019-03-05 02:32:58,389 [salt.loaded.ext.module.maas:92  ][INFO    ][13766] maasconfig name=active_discovery_interval value=600
2019-03-05 02:32:58,449 [salt.loaded.ext.module.maas:92  ][INFO    ][13766] maasconfig name=dnssec_validation value=no
2019-03-05 02:32:58,516 [salt.loaded.ext.module.maas:92  ][INFO    ][13766] maasconfig name=maas_name value=mas01
2019-03-05 02:32:58,569 [salt.loaded.ext.module.maas:92  ][INFO    ][13766] maasconfig name=network_discovery value=enabled
2019-03-05 02:32:58,706 [salt.loaded.ext.module.maas:92  ][INFO    ][13766] maasconfig name=enable_third_party_drivers value=True
2019-03-05 02:32:58,764 [salt.loaded.ext.module.maas:92  ][INFO    ][13766] maasconfig name=default_storage_layout value=lvm
2019-03-05 02:32:58,818 [salt.loaded.ext.module.maas:92  ][INFO    ][13766] maasconfig name=ntp_external_only value=True
2019-03-05 02:32:58,872 [salt.loaded.ext.module.maas:92  ][INFO    ][13766] maasconfig name=disk_erase_with_secure_erase value=False
2019-03-05 02:32:58,920 [salt.loaded.ext.module.maas:92  ][INFO    ][13766] maasconfig name=default_distro_series value=xenial
2019-03-05 02:32:58,974 [salt.loaded.ext.module.maas:92  ][INFO    ][13766] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-03-05 02:32:59,119 [salt.state       :300 ][INFO    ][13766] {'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-03-05 02:32:59,120 [salt.state       :1951][INFO    ][13766] Completed state [maas.process_maas_config] at time 02:32:59.120218 duration_in_ms=4155.182
2019-03-05 02:32:59,121 [salt.state       :1780][INFO    ][13766] Running state [pxe_admin] at time 02:32:59.121360
2019-03-05 02:32:59,122 [salt.state       :1813][INFO    ][13766] Executing state maasng.fabric_present for [pxe_admin]
2019-03-05 02:32:59,207 [salt.loaded.ext.module.maasng:945 ][INFO    ][13766] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'kwwxpn', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-03-05 02:32:59,281 [salt.loaded.ext.module.maasng:1008][WARNING ][13766] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-03-05 02:32:59,281 [salt.loaded.ext.module.maasng:1011][WARNING ][13766] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-03-05 02:32:59,366 [salt.state       :300 ][INFO    ][13766] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-03-05 02:32:59,367 [salt.state       :1951][INFO    ][13766] Completed state [pxe_admin] at time 02:32:59.367101 duration_in_ms=245.741
2019-03-05 02:32:59,367 [salt.state       :1780][INFO    ][13766] Running state [vlan 0] at time 02:32:59.367457
2019-03-05 02:32:59,367 [salt.state       :1813][INFO    ][13766] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-05 02:32:59,436 [salt.loaded.ext.module.maasng:945 ][INFO    ][13766] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'kwwxpn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-03-05 02:32:59,581 [salt.loaded.ext.module.maasng:945 ][INFO    ][13766] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'kwwxpn', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-03-05 02:32:59,960 [salt.loaded.ext.module.maasng:945 ][INFO    ][13766] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'kwwxpn', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-03-05 02:33:00,052 [salt.state       :300 ][INFO    ][13766] {'new': 'Vlan untagged was updated'}
2019-03-05 02:33:00,053 [salt.state       :1951][INFO    ][13766] Completed state [vlan 0] at time 02:33:00.052916 duration_in_ms=685.457
2019-03-05 02:33:00,054 [salt.state       :1780][INFO    ][13766] Running state [192.168.11.0/24] at time 02:33:00.054413
2019-03-05 02:33:00,054 [salt.state       :1813][INFO    ][13766] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-03-05 02:33:00,292 [salt.loaded.ext.module.maasng:945 ][INFO    ][13766] [{u'name': u'fabric-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'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-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'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', 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'kwwxpn', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-03-05 02:33:00,292 [salt.loaded.ext.module.maasng:1235][WARNING ][13766] Ignoring parameter vlan:0
2019-03-05 02:33:00,389 [salt.state       :300 ][INFO    ][13766] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-03-05 02:33:00,390 [salt.state       :1951][INFO    ][13766] Completed state [192.168.11.0/24] at time 02:33:00.390133 duration_in_ms=335.719
2019-03-05 02:33:00,391 [salt.state       :1780][INFO    ][13766] Running state [maas_create_iprange_1] at time 02:33:00.391765
2019-03-05 02:33:00,392 [salt.state       :1813][INFO    ][13766] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-03-05 02:33:00,453 [salt.state       :300 ][INFO    ][13766] Iprange maas_create_iprange_1 already exist.
2019-03-05 02:33:00,454 [salt.state       :1951][INFO    ][13766] Completed state [maas_create_iprange_1] at time 02:33:00.454041 duration_in_ms=62.276
2019-03-05 02:33:00,454 [salt.state       :1780][INFO    ][13766] Running state [vlan 0] at time 02:33:00.454386
2019-03-05 02:33:00,454 [salt.state       :1813][INFO    ][13766] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-05 02:33:00,508 [salt.loaded.ext.module.maasng:945 ][INFO    ][13766] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'kwwxpn', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-03-05 02:33:00,599 [salt.loaded.ext.module.maasng:945 ][INFO    ][13766] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'kwwxpn', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-03-05 02:33:00,840 [salt.loaded.ext.module.maasng:945 ][INFO    ][13766] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'kwwxpn', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-03-05 02:33:00,934 [salt.state       :300 ][INFO    ][13766] {'new': 'Vlan untagged was updated'}
2019-03-05 02:33:00,935 [salt.state       :1951][INFO    ][13766] Completed state [vlan 0] at time 02:33:00.935207 duration_in_ms=480.819
2019-03-05 02:33:00,936 [salt.state       :1780][INFO    ][13766] Running state [opnfv] at time 02:33:00.935990
2019-03-05 02:33:00,936 [salt.state       :1813][INFO    ][13766] Executing state maasng.sshkey_present for [opnfv]
2019-03-05 02:33:00,982 [salt.loaded.ext.module.maasng:1903][INFO    ][13766] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-03-05 02:33:00,982 [salt.state       :300 ][INFO    ][13766] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-03-05 02:33:00,983 [salt.state       :1951][INFO    ][13766] Completed state [opnfv] at time 02:33:00.982997 duration_in_ms=47.007
2019-03-05 02:33:00,986 [salt.minion      :1711][INFO    ][13766] Returning information for job: 20190305023232731049
2019-03-05 02:33:01,502 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command state.apply with jid 20190305023301490117
2019-03-05 02:33:01,516 [salt.minion      :1432][INFO    ][14232] Starting a new job with PID 14232
2019-03-05 02:33:05,031 [salt.state       :915 ][INFO    ][14232] Loading fresh modules for state activity
2019-03-05 02:33:05,085 [salt.state       :1780][INFO    ][14232] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:33:05.085319
2019-03-05 02:33:05,085 [salt.state       :1813][INFO    ][14232] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-05 02:33:05,086 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14232] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-05 02:33:06,654 [salt.state       :300 ][INFO    ][14232] {'pid': 14257, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-05 02:33:06,655 [salt.state       :1951][INFO    ][14232] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:33:06.655708 duration_in_ms=1570.387
2019-03-05 02:33:06,658 [salt.state       :1780][INFO    ][14232] Running state [maas.process_machines] at time 02:33:06.658253
2019-03-05 02:33:06,658 [salt.state       :1813][INFO    ][14232] Executing state module.run for [maas.process_machines]
2019-03-05 02:33:06,660 [salt.utils.decorators:613 ][WARNING ][14232] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-05 02:33:07,377 [salt.loaded.ext.module.maas:412 ][WARNING ][14232] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-05 02:33:07,378 [salt.loaded.ext.module.maas:92  ][INFO    ][14232] machine hostname=cmp002 power_type=ipmi mac_addresses=['00:25:b5:a0:00:6a'] power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus system_id=fxn8ck architecture=amd64/generic power_parameters_power_user=admin
2019-03-05 02:33:08,839 [salt.loaded.ext.module.maas:412 ][WARNING ][14232] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-05 02:33:08,840 [salt.loaded.ext.module.maas:92  ][INFO    ][14232] machine hostname=cmp001 power_type=ipmi mac_addresses=['00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=sg8f7w architecture=amd64/generic power_parameters_power_user=admin
2019-03-05 02:33:10,125 [salt.loaded.ext.module.maas:412 ][WARNING ][14232] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-05 02:33:10,126 [salt.loaded.ext.module.maas:92  ][INFO    ][14232] machine hostname=kvm01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=w4bhwc architecture=amd64/generic power_parameters_power_user=admin
2019-03-05 02:33:11,390 [salt.loaded.ext.module.maas:412 ][WARNING ][14232] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-05 02:33:11,391 [salt.loaded.ext.module.maas:92  ][INFO    ][14232] machine hostname=kvm03 power_type=ipmi mac_addresses=['00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=me4yge architecture=amd64/generic power_parameters_power_user=admin
2019-03-05 02:33:12,648 [salt.loaded.ext.module.maas:412 ][WARNING ][14232] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-05 02:33:12,649 [salt.loaded.ext.module.maas:92  ][INFO    ][14232] machine hostname=kvm02 power_type=ipmi mac_addresses=['00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=xetx4t architecture=amd64/generic power_parameters_power_user=admin
2019-03-05 02:33:13,924 [salt.state       :300 ][INFO    ][14232] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-05 02:33:13,925 [salt.state       :1951][INFO    ][14232] Completed state [maas.process_machines] at time 02:33:13.925243 duration_in_ms=7266.989
2019-03-05 02:33:13,929 [salt.minion      :1711][INFO    ][14232] Returning information for job: 20190305023301490117
2019-03-05 02:33:47,241 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command state.apply with jid 20190305023347228329
2019-03-05 02:33:47,263 [salt.minion      :1432][INFO    ][14520] Starting a new job with PID 14520
2019-03-05 02:33:50,838 [salt.state       :915 ][INFO    ][14520] Loading fresh modules for state activity
2019-03-05 02:33:50,909 [salt.state       :1780][INFO    ][14520] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:33:50.909562
2019-03-05 02:33:50,909 [salt.state       :1813][INFO    ][14520] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-05 02:33:50,911 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14520] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-05 02:33:52,384 [salt.state       :300 ][INFO    ][14520] {'pid': 14527, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-05 02:33:52,385 [salt.state       :1951][INFO    ][14520] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:33:52.385727 duration_in_ms=1476.164
2019-03-05 02:33:52,389 [salt.state       :1780][INFO    ][14520] Running state [maas.wait_for_machine_status] at time 02:33:52.389205
2019-03-05 02:33:52,389 [salt.state       :1813][INFO    ][14520] Executing state module.run for [maas.wait_for_machine_status]
2019-03-05 02:33:52,390 [salt.utils.decorators:613 ][WARNING ][14520] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-05 02:33:55,222 [salt.loaded.ext.module.maas:993 ][INFO    ][14520] Machine me4yge mark broken
2019-03-05 02:33:55,766 [salt.loaded.ext.module.maas:996 ][INFO    ][14520] Machine me4yge mark fixed
2019-03-05 02:33:57,028 [salt.loaded.ext.module.maas:684 ][INFO    ][14520] deploymachines hwe_kernel=ga-16.04 system_id=me4yge distro_series=xenial
2019-03-05 02:34:00,070 [salt.loaded.ext.module.maas:1023][INFO    ][14520] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1492.33136201s left)
2019-03-05 02:34:02,348 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305023402332951
2019-03-05 02:34:02,370 [salt.minion      :1432][INFO    ][14606] Starting a new job with PID 14606
2019-03-05 02:34:02,394 [salt.minion      :1711][INFO    ][14606] Returning information for job: 20190305023402332951
2019-03-05 02:34:32,403 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305023432386334
2019-03-05 02:34:32,426 [salt.minion      :1432][INFO    ][14648] Starting a new job with PID 14648
2019-03-05 02:34:32,449 [salt.minion      :1711][INFO    ][14648] Returning information for job: 20190305023432386334
2019-03-05 02:34:33,246 [salt.loaded.ext.module.maas:1023][INFO    ][14520] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1459.15482092s left)
2019-03-05 02:35:02,467 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305023502418085
2019-03-05 02:35:02,488 [salt.minion      :1432][INFO    ][14693] Starting a new job with PID 14693
2019-03-05 02:35:02,512 [salt.minion      :1711][INFO    ][14693] Returning information for job: 20190305023502418085
2019-03-05 02:35:06,729 [salt.loaded.ext.module.maas:1023][INFO    ][14520] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1425.67239881s left)
2019-03-05 02:35:32,512 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305023532500278
2019-03-05 02:35:32,535 [salt.minion      :1432][INFO    ][14726] Starting a new job with PID 14726
2019-03-05 02:35:32,558 [salt.minion      :1711][INFO    ][14726] Returning information for job: 20190305023532500278
2019-03-05 02:35:40,181 [salt.loaded.ext.module.maas:1023][INFO    ][14520] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1392.21998096s left)
2019-03-05 02:36:02,559 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305023602545371
2019-03-05 02:36:02,581 [salt.minion      :1432][INFO    ][14820] Starting a new job with PID 14820
2019-03-05 02:36:02,606 [salt.minion      :1711][INFO    ][14820] Returning information for job: 20190305023602545371
2019-03-05 02:36:13,704 [salt.loaded.ext.module.maas:1023][INFO    ][14520] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1358.69722795s left)
2019-03-05 02:36:32,619 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305023632605551
2019-03-05 02:36:32,641 [salt.minion      :1432][INFO    ][14903] Starting a new job with PID 14903
2019-03-05 02:36:32,665 [salt.minion      :1711][INFO    ][14903] Returning information for job: 20190305023632605551
2019-03-05 02:36:47,353 [salt.loaded.ext.module.maas:1023][INFO    ][14520] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1325.04818487s left)
2019-03-05 02:37:02,679 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305023702666993
2019-03-05 02:37:02,701 [salt.minion      :1432][INFO    ][14975] Starting a new job with PID 14975
2019-03-05 02:37:02,725 [salt.minion      :1711][INFO    ][14975] Returning information for job: 20190305023702666993
2019-03-05 02:37:20,846 [salt.loaded.ext.module.maas:1023][INFO    ][14520] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1291.55492091s left)
2019-03-05 02:37:32,740 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305023732727936
2019-03-05 02:37:32,762 [salt.minion      :1432][INFO    ][15012] Starting a new job with PID 15012
2019-03-05 02:37:32,785 [salt.minion      :1711][INFO    ][15012] Returning information for job: 20190305023732727936
2019-03-05 02:37:54,293 [salt.loaded.ext.module.maas:1023][INFO    ][14520] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1258.10767198s left)
2019-03-05 02:38:02,804 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305023802791307
2019-03-05 02:38:02,827 [salt.minion      :1432][INFO    ][15067] Starting a new job with PID 15067
2019-03-05 02:38:02,851 [salt.minion      :1711][INFO    ][15067] Returning information for job: 20190305023802791307
2019-03-05 02:38:27,981 [salt.loaded.ext.module.maas:1023][INFO    ][14520] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1224.42044401s left)
2019-03-05 02:38:32,883 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305023832870527
2019-03-05 02:38:32,906 [salt.minion      :1432][INFO    ][15159] Starting a new job with PID 15159
2019-03-05 02:38:32,929 [salt.minion      :1711][INFO    ][15159] Returning information for job: 20190305023832870527
2019-03-05 02:39:00,565 [salt.loaded.ext.module.maas:1023][INFO    ][14520] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1191.8357048s left)
2019-03-05 02:39:02,957 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305023902944447
2019-03-05 02:39:02,979 [salt.minion      :1432][INFO    ][15232] Starting a new job with PID 15232
2019-03-05 02:39:03,003 [salt.minion      :1711][INFO    ][15232] Returning information for job: 20190305023902944447
2019-03-05 02:39:33,035 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305023933022964
2019-03-05 02:39:33,056 [salt.minion      :1432][INFO    ][15292] Starting a new job with PID 15292
2019-03-05 02:39:33,078 [salt.minion      :1711][INFO    ][15292] Returning information for job: 20190305023933022964
2019-03-05 02:39:33,916 [salt.loaded.ext.module.maas:1023][INFO    ][14520] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1158.48475003s left)
2019-03-05 02:40:03,115 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305024003102907
2019-03-05 02:40:03,135 [salt.minion      :1432][INFO    ][15342] Starting a new job with PID 15342
2019-03-05 02:40:03,156 [salt.minion      :1711][INFO    ][15342] Returning information for job: 20190305024003102907
2019-03-05 02:40:06,818 [salt.loaded.ext.module.maas:1023][INFO    ][14520] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1125.5830369s left)
2019-03-05 02:40:33,192 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305024033179910
2019-03-05 02:40:33,215 [salt.minion      :1432][INFO    ][15394] Starting a new job with PID 15394
2019-03-05 02:40:33,239 [salt.minion      :1711][INFO    ][15394] Returning information for job: 20190305024033179910
2019-03-05 02:40:40,508 [salt.loaded.ext.module.maas:1023][INFO    ][14520] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1091.892699s left)
2019-03-05 02:41:03,288 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305024103275756
2019-03-05 02:41:03,312 [salt.minion      :1432][INFO    ][15443] Starting a new job with PID 15443
2019-03-05 02:41:03,337 [salt.minion      :1711][INFO    ][15443] Returning information for job: 20190305024103275756
2019-03-05 02:41:14,019 [salt.loaded.ext.module.maas:1023][INFO    ][14520] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1058.38220596s left)
2019-03-05 02:41:33,392 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305024133379348
2019-03-05 02:41:33,414 [salt.minion      :1432][INFO    ][15512] Starting a new job with PID 15512
2019-03-05 02:41:33,441 [salt.minion      :1711][INFO    ][15512] Returning information for job: 20190305024133379348
2019-03-05 02:41:47,428 [salt.loaded.ext.module.maas:1023][INFO    ][14520] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1024.97260404s left)
2019-03-05 02:42:03,502 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305024203488764
2019-03-05 02:42:03,527 [salt.minion      :1432][INFO    ][15576] Starting a new job with PID 15576
2019-03-05 02:42:03,554 [salt.minion      :1711][INFO    ][15576] Returning information for job: 20190305024203488764
2019-03-05 02:42:20,951 [salt.loaded.ext.module.maas:1023][INFO    ][14520] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (991.450385809s left)
2019-03-05 02:42:33,621 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305024233607839
2019-03-05 02:42:33,646 [salt.minion      :1432][INFO    ][15657] Starting a new job with PID 15657
2019-03-05 02:42:33,672 [salt.minion      :1711][INFO    ][15657] Returning information for job: 20190305024233607839
2019-03-05 02:42:54,531 [salt.loaded.ext.module.maas:1023][INFO    ][14520] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (957.870288849s left)
2019-03-05 02:43:03,745 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305024303732741
2019-03-05 02:43:03,770 [salt.minion      :1432][INFO    ][15704] Starting a new job with PID 15704
2019-03-05 02:43:03,796 [salt.minion      :1711][INFO    ][15704] Returning information for job: 20190305024303732741
2019-03-05 02:43:27,935 [salt.loaded.ext.module.maas:1023][INFO    ][14520] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (924.465974808s left)
2019-03-05 02:43:33,816 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command saltutil.find_job with jid 20190305024333803478
2019-03-05 02:43:33,839 [salt.minion      :1432][INFO    ][15774] Starting a new job with PID 15774
2019-03-05 02:43:33,865 [salt.minion      :1711][INFO    ][15774] Returning information for job: 20190305024333803478
2019-03-05 02:44:01,521 [salt.state       :300 ][INFO    ][14520] {'ret': True}
2019-03-05 02:44:01,522 [salt.state       :1951][INFO    ][14520] Completed state [maas.wait_for_machine_status] at time 02:44:01.522125 duration_in_ms=609132.919
2019-03-05 02:44:01,525 [salt.minion      :1711][INFO    ][14520] Returning information for job: 20190305023347228329
2019-03-05 02:44:02,215 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command state.apply with jid 20190305024402206220
2019-03-05 02:44:02,237 [salt.minion      :1432][INFO    ][15848] Starting a new job with PID 15848
2019-03-05 02:44:05,810 [salt.state       :915 ][INFO    ][15848] Loading fresh modules for state activity
2019-03-05 02:44:05,944 [salt.state       :1780][INFO    ][15848] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:44:05.944568
2019-03-05 02:44:05,944 [salt.state       :1813][INFO    ][15848] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-05 02:44:05,946 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15848] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-05 02:44:07,407 [salt.state       :300 ][INFO    ][15848] {'pid': 15856, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-05 02:44:07,408 [salt.state       :1951][INFO    ][15848] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:44:07.408075 duration_in_ms=1463.507
2019-03-05 02:44:07,409 [salt.state       :1780][INFO    ][15848] Running state [maas_machines_storage_cmp002_lvm] at time 02:44:07.409555
2019-03-05 02:44:07,409 [salt.state       :1813][INFO    ][15848] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-05 02:44:08,104 [salt.state       :300 ][INFO    ][15848] Machine cmp002 is not in Ready state.
2019-03-05 02:44:08,104 [salt.state       :1951][INFO    ][15848] Completed state [maas_machines_storage_cmp002_lvm] at time 02:44:08.104606 duration_in_ms=695.048
2019-03-05 02:44:08,105 [salt.state       :1780][INFO    ][15848] Running state [maas_machines_storage_cmp001_lvm] at time 02:44:08.105468
2019-03-05 02:44:08,106 [salt.state       :1813][INFO    ][15848] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-05 02:44:08,712 [salt.state       :300 ][INFO    ][15848] Machine cmp001 is not in Ready state.
2019-03-05 02:44:08,713 [salt.state       :1951][INFO    ][15848] Completed state [maas_machines_storage_cmp001_lvm] at time 02:44:08.713089 duration_in_ms=607.621
2019-03-05 02:44:08,717 [salt.minion      :1711][INFO    ][15848] Returning information for job: 20190305024402206220
2019-03-05 02:44:09,310 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command state.apply with jid 20190305024409303033
2019-03-05 02:44:09,329 [salt.minion      :1432][INFO    ][15891] Starting a new job with PID 15891
2019-03-05 02:44:09,846 [salt.state       :915 ][INFO    ][15891] Loading fresh modules for state activity
2019-03-05 02:44:09,891 [salt.state       :1780][INFO    ][15891] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:44:09.890986
2019-03-05 02:44:09,891 [salt.state       :1813][INFO    ][15891] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-05 02:44:09,892 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15891] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-05 02:44:11,368 [salt.state       :300 ][INFO    ][15891] {'pid': 15967, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-05 02:44:11,368 [salt.state       :1951][INFO    ][15891] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:44:11.368766 duration_in_ms=1477.778
2019-03-05 02:44:11,371 [salt.state       :1780][INFO    ][15891] Running state [maas.deploy_machines] at time 02:44:11.371143
2019-03-05 02:44:11,371 [salt.state       :1813][INFO    ][15891] Executing state module.run for [maas.deploy_machines]
2019-03-05 02:44:11,372 [salt.utils.decorators:613 ][WARNING ][15891] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-05 02:44:12,113 [salt.state       :300 ][INFO    ][15891] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-05 02:44:12,114 [salt.state       :1951][INFO    ][15891] Completed state [maas.deploy_machines] at time 02:44:12.114316 duration_in_ms=743.173
2019-03-05 02:44:12,118 [salt.minion      :1711][INFO    ][15891] Returning information for job: 20190305024409303033
2019-03-05 02:44:12,726 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command state.apply with jid 20190305024412709139
2019-03-05 02:44:12,748 [salt.minion      :1432][INFO    ][15988] Starting a new job with PID 15988
2019-03-05 02:44:13,354 [salt.state       :915 ][INFO    ][15988] Loading fresh modules for state activity
2019-03-05 02:44:13,444 [salt.state       :1780][INFO    ][15988] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:44:13.444680
2019-03-05 02:44:13,445 [salt.state       :1813][INFO    ][15988] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-05 02:44:13,447 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15988] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-05 02:44:14,928 [salt.state       :300 ][INFO    ][15988] {'pid': 15995, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-05 02:44:14,929 [salt.state       :1951][INFO    ][15988] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:44:14.928994 duration_in_ms=1484.315
2019-03-05 02:44:14,931 [salt.state       :1780][INFO    ][15988] Running state [maas.wait_for_machine_status] at time 02:44:14.931645
2019-03-05 02:44:14,932 [salt.state       :1813][INFO    ][15988] Executing state module.run for [maas.wait_for_machine_status]
2019-03-05 02:44:14,932 [salt.utils.decorators:613 ][WARNING ][15988] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-05 02:44:18,190 [salt.state       :300 ][INFO    ][15988] {'ret': True}
2019-03-05 02:44:18,191 [salt.state       :1951][INFO    ][15988] Completed state [maas.wait_for_machine_status] at time 02:44:18.190941 duration_in_ms=3259.295
2019-03-05 02:44:18,194 [salt.minion      :1711][INFO    ][15988] Returning information for job: 20190305024412709139
2019-03-05 03:10:55,604 [salt.utils.schedule:1377][INFO    ][7590] Running scheduled job: __mine_interval
2019-03-05 04:10:55,604 [salt.utils.schedule:1377][INFO    ][7590] Running scheduled job: __mine_interval
2019-03-05 04:30:15,426 [salt.minion      :1308][INFO    ][7590] User sudo_ubuntu Executing command cp.push_dir with jid 20190305043015413809
2019-03-05 04:30:15,448 [salt.minion      :1432][INFO    ][23614] Starting a new job with PID 23614
