2019-03-18 08:50:07,142 [salt.utils.decorators:613 ][WARNING ][1928] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-18 08:50:08,030 [salt.utils.decorators:613 ][WARNING ][1928] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-18 08:50:10,790 [salt.loaded.int.states.file:2298][WARNING ][2415] 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-18 08:50:42,034 [salt.state       :2022][WARNING ][3025] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-18 08:50:45,070 [salt.utils.decorators:613 ][WARNING ][3025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-18 08:50:56,239 [salt.utils.decorators:613 ][WARNING ][3025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-18 08:51:22,942 [salt.utils.decorators:613 ][WARNING ][3025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-18 08:51:23,806 [salt.utils.decorators:613 ][WARNING ][3025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-18 08:51:25,681 [salt.loaded.ext.module.maasng:1008][WARNING ][3025] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-03-18 08:51:25,682 [salt.loaded.ext.module.maasng:1011][WARNING ][3025] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-03-18 08:51:26,338 [salt.loaded.ext.module.maasng:1235][WARNING ][3025] Ignoring parameter vlan:0
2019-03-18 08:51:35,651 [salt.utils.decorators:613 ][WARNING ][5505] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-18 08:51:35,700 [salt.loaded.ext.module.maas:412 ][WARNING ][5505] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-18 08:51:36,848 [salt.loaded.ext.module.maas:412 ][WARNING ][5505] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-18 08:51:37,986 [salt.loaded.ext.module.maas:412 ][WARNING ][5505] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-18 08:51:39,103 [salt.loaded.ext.module.maas:412 ][WARNING ][5505] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-18 08:51:40,166 [salt.loaded.ext.module.maas:412 ][WARNING ][5505] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-18 08:51:44,124 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6094] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-18 08:51:44,144 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6094] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-03-18 08:51:44,179 [salt.utils.parsers:1051][WARNING ][383] Minion received a SIGTERM. Exiting.
2019-03-18 08:51:45,240 [salt.cli.daemons :293 ][INFO    ][6151] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-03-18 08:51:45,422 [salt.cli.daemons :82  ][INFO    ][6151] Starting up the Salt Minion
2019-03-18 08:51:45,425 [salt.utils.event :1017][INFO    ][6151] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-03-18 08:51:46,584 [salt.minion      :976 ][INFO    ][6151] Creating minion process manager
2019-03-18 08:51:48,177 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6151] Executing command ['date', '+%z'] in directory '/root'
2019-03-18 08:51:48,193 [salt.utils.schedule:568 ][INFO    ][6151] Updating job settings for scheduled job: __mine_interval
2019-03-18 08:51:48,196 [salt.minion      :1108][INFO    ][6151] Added mine.update to scheduler
2019-03-18 08:51:48,201 [salt.minion      :1975][INFO    ][6151] Minion is starting as user 'root'
2019-03-18 08:51:48,214 [salt.minion      :2336][INFO    ][6151] Minion is ready to receive requests!
2019-03-18 08:52:12,401 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command state.apply with jid 20190318085212391817
2019-03-18 08:52:12,418 [salt.minion      :1432][INFO    ][6300] Starting a new job with PID 6300
2019-03-18 08:52:18,057 [salt.state       :915 ][INFO    ][6300] Loading fresh modules for state activity
2019-03-18 08:52:18,113 [salt.fileclient  :1219][INFO    ][6300] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-03-18 08:52:18,158 [salt.state       :1780][INFO    ][6300] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:52:18.158130
2019-03-18 08:52:18,158 [salt.state       :1813][INFO    ][6300] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-18 08:52:18,161 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6300] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-18 08:52:19,820 [salt.state       :300 ][INFO    ][6300] {'pid': 6310, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-18 08:52:19,821 [salt.state       :1951][INFO    ][6300] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:52:19.821147 duration_in_ms=1663.016
2019-03-18 08:52:19,824 [salt.state       :1780][INFO    ][6300] Running state [maas.wait_for_machine_status] at time 08:52:19.824121
2019-03-18 08:52:19,824 [salt.state       :1813][INFO    ][6300] Executing state module.run for [maas.wait_for_machine_status]
2019-03-18 08:52:19,825 [salt.utils.decorators:613 ][WARNING ][6300] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-18 08:52:20,620 [salt.loaded.ext.module.maas:1023][INFO    ][6300] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.21409893s left)
2019-03-18 08:52:27,436 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318085227427950
2019-03-18 08:52:27,455 [salt.minion      :1432][INFO    ][6322] Starting a new job with PID 6322
2019-03-18 08:52:27,480 [salt.minion      :1711][INFO    ][6322] Returning information for job: 20190318085227427950
2019-03-18 08:52:51,293 [salt.loaded.ext.module.maas:1023][INFO    ][6300] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.54147196s left)
2019-03-18 08:52:57,626 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318085257616939
2019-03-18 08:52:57,645 [salt.minion      :1432][INFO    ][6399] Starting a new job with PID 6399
2019-03-18 08:52:57,667 [salt.minion      :1711][INFO    ][6399] Returning information for job: 20190318085257616939
2019-03-18 08:53:22,101 [salt.loaded.ext.module.maas:1023][INFO    ][6300] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.73336387s left)
2019-03-18 08:53:27,823 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318085327806160
2019-03-18 08:53:27,847 [salt.minion      :1432][INFO    ][6419] Starting a new job with PID 6419
2019-03-18 08:53:27,866 [salt.minion      :1711][INFO    ][6419] Returning information for job: 20190318085327806160
2019-03-18 08:53:52,916 [salt.loaded.ext.module.maas:1023][INFO    ][6300] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.9182539s left)
2019-03-18 08:53:58,020 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318085358010791
2019-03-18 08:53:58,033 [salt.minion      :1432][INFO    ][6498] Starting a new job with PID 6498
2019-03-18 08:53:58,053 [salt.minion      :1711][INFO    ][6498] Returning information for job: 20190318085358010791
2019-03-18 08:54:24,042 [salt.loaded.ext.module.maas:1023][INFO    ][6300] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.79246783s left)
2019-03-18 08:54:28,060 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318085428046018
2019-03-18 08:54:28,078 [salt.minion      :1432][INFO    ][6543] Starting a new job with PID 6543
2019-03-18 08:54:28,099 [salt.minion      :1711][INFO    ][6543] Returning information for job: 20190318085428046018
2019-03-18 08:54:55,133 [salt.loaded.ext.module.maas:1023][INFO    ][6300] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.70088792s left)
2019-03-18 08:54:58,145 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318085458137741
2019-03-18 08:54:58,160 [salt.minion      :1432][INFO    ][6773] Starting a new job with PID 6773
2019-03-18 08:54:58,177 [salt.minion      :1711][INFO    ][6773] Returning information for job: 20190318085458137741
2019-03-18 08:55:26,312 [salt.loaded.ext.module.maas:1023][INFO    ][6300] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.52265787s left)
2019-03-18 08:55:28,210 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318085528196943
2019-03-18 08:55:28,226 [salt.minion      :1432][INFO    ][6846] Starting a new job with PID 6846
2019-03-18 08:55:28,248 [salt.minion      :1711][INFO    ][6846] Returning information for job: 20190318085528196943
2019-03-18 08:55:57,628 [salt.loaded.ext.module.maas:1023][INFO    ][6300] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.20617104s left)
2019-03-18 08:55:58,316 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318085558308060
2019-03-18 08:55:58,341 [salt.minion      :1432][INFO    ][7103] Starting a new job with PID 7103
2019-03-18 08:55:58,362 [salt.minion      :1711][INFO    ][7103] Returning information for job: 20190318085558308060
2019-03-18 08:56:28,370 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318085628363406
2019-03-18 08:56:28,388 [salt.minion      :1432][INFO    ][7237] Starting a new job with PID 7237
2019-03-18 08:56:28,412 [salt.minion      :1711][INFO    ][7237] Returning information for job: 20190318085628363406
2019-03-18 08:56:29,433 [salt.loaded.ext.module.maas:1023][INFO    ][6300] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.40162396s left)
2019-03-18 08:56:58,533 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318085658520018
2019-03-18 08:56:58,549 [salt.minion      :1432][INFO    ][7748] Starting a new job with PID 7748
2019-03-18 08:56:58,572 [salt.minion      :1711][INFO    ][7748] Returning information for job: 20190318085658520018
2019-03-18 08:57:01,698 [salt.loaded.ext.module.maas:1023][INFO    ][6300] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.13599181s left)
2019-03-18 08:57:28,605 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318085728590644
2019-03-18 08:57:28,626 [salt.minion      :1432][INFO    ][7807] Starting a new job with PID 7807
2019-03-18 08:57:28,645 [salt.minion      :1711][INFO    ][7807] Returning information for job: 20190318085728590644
2019-03-18 08:57:33,820 [salt.loaded.ext.module.maas:1023][INFO    ][6300] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1186.01424003s left)
2019-03-18 08:57:58,724 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318085758715319
2019-03-18 08:57:58,740 [salt.minion      :1432][INFO    ][8033] Starting a new job with PID 8033
2019-03-18 08:57:58,762 [salt.minion      :1711][INFO    ][8033] Returning information for job: 20190318085758715319
2019-03-18 08:58:06,884 [salt.state       :300 ][INFO    ][6300] {'ret': True}
2019-03-18 08:58:06,884 [salt.state       :1951][INFO    ][6300] Completed state [maas.wait_for_machine_status] at time 08:58:06.884603 duration_in_ms=347060.481
2019-03-18 08:58:06,888 [salt.minion      :1711][INFO    ][6300] Returning information for job: 20190318085212391817
2019-03-18 08:58:07,442 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command state.apply with jid 20190318085807434145
2019-03-18 08:58:07,461 [salt.minion      :1432][INFO    ][8133] Starting a new job with PID 8133
2019-03-18 08:58:13,066 [salt.state       :915 ][INFO    ][8133] Loading fresh modules for state activity
2019-03-18 08:58:13,117 [salt.fileclient  :1219][INFO    ][8133] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-03-18 08:58:13,212 [salt.state       :1780][INFO    ][8133] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:58:13.212248
2019-03-18 08:58:13,212 [salt.state       :1813][INFO    ][8133] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-18 08:58:13,214 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8133] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-18 08:58:14,885 [salt.state       :300 ][INFO    ][8133] {'pid': 8140, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-18 08:58:14,886 [salt.state       :1951][INFO    ][8133] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:58:14.886765 duration_in_ms=1674.516
2019-03-18 08:58:14,890 [salt.state       :1780][INFO    ][8133] Running state [maas_machines_storage_cmp002_lvm] at time 08:58:14.890341
2019-03-18 08:58:14,891 [salt.state       :1813][INFO    ][8133] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-18 08:58:16,000 [salt.loaded.ext.module.maasng:610 ][INFO    ][8133] cex7hp
2019-03-18 08:58:16,001 [salt.loaded.ext.module.maasng:626 ][INFO    ][8133] sda
2019-03-18 08:58:16,537 [salt.loaded.ext.module.maasng:361 ][INFO    ][8133] cex7hp
2019-03-18 08:58:16,626 [salt.loaded.ext.module.maasng:367 ][INFO    ][8133] [{u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'19d39373-7a0a-4637-aabd-5a3c0b0705b4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cex7hp', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'd0c8cd0f-9e9c-4923-b881-0c9ba12b5739', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/cex7hp/blockdevices/1/partition/1'}], u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cex7hp', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/cex7hp/blockdevices/1/'}, {u'block_size': 4096, u'uuid': u'da61a568-5834-4a8e-ae58-6ea084d1ca74', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'id': 3, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'cex7hp', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'fdf73a20-3004-4d63-8c09-9c0d459565f7', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'size': 800097042432, u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cex7hp/blockdevices/3/'}]
2019-03-18 08:58:16,627 [salt.loaded.ext.module.maasng:632 ][INFO    ][8133] vgroot
2019-03-18 08:58:16,627 [salt.loaded.ext.module.maasng:635 ][INFO    ][8133] lvroot
2019-03-18 08:58:16,627 [salt.loaded.ext.module.maasng:639 ][INFO    ][8133] 107374182400
2019-03-18 08:58:17,219 [salt.loaded.ext.module.maasng:645 ][INFO    ][8133] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'id': 4, u'effective_mtu': 1500, u'system_id': u'cex7hp', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cex7hp/interfaces/4/'}, u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 1, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cex7hp', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/cex7hp/blockdevices/1/', u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'bf636698-02f3-4a92-a9db-da4a1d6b1f17', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cex7hp', u'device_id': 1, u'filesystem': {u'uuid': u'0c9a2cfa-e7b0-4207-a99f-045a0dee1b3c', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/cex7hp/blockdevices/1/partition/6'}]}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 11, u'uuid': u'655cbf0a-851e-4ee8-9c00-61f651d11e62', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'cex7hp', u'partition_table_type': None, u'filesystem': {u'uuid': u'1876f998-9b48-4168-9839-b37c3193d7da', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cex7hp/blockdevices/11/', u'used_size': 107374182400, u'model': None, u'partitions': []}], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'cex7hp', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'disable_ipv4': False, u'min_hwe_kernel': u'ga-16.04', u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'id': 1, u'filesystem': None, u'name': u'sda', u'system_id': u'cex7hp', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/cex7hp/blockdevices/1/', u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'bf636698-02f3-4a92-a9db-da4a1d6b1f17', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cex7hp', u'device_id': 1, u'filesystem': {u'uuid': u'0c9a2cfa-e7b0-4207-a99f-045a0dee1b3c', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/cex7hp/blockdevices/1/partition/6'}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'cex7hp', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'id': 1, u'filesystem': None, u'name': u'sda', u'system_id': u'cex7hp', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/cex7hp/blockdevices/1/', u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'bf636698-02f3-4a92-a9db-da4a1d6b1f17', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cex7hp', u'device_id': 1, u'filesystem': {u'uuid': u'0c9a2cfa-e7b0-4207-a99f-045a0dee1b3c', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/cex7hp/blockdevices/1/partition/6'}]}, 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'node_type': 0, u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'uuid': u'655cbf0a-851e-4ee8-9c00-61f651d11e62', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'id': 11, u'filesystem': {u'uuid': u'1876f998-9b48-4168-9839-b37c3193d7da', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'cex7hp', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cex7hp/blockdevices/11/', u'used_size': 107374182400, u'model': None, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'id': 4, u'effective_mtu': 1500, u'system_id': u'cex7hp', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cex7hp/interfaces/4/'}, {u'discovered': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 15, u'effective_mtu': 1500, u'system_id': u'cex7hp', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cex7hp/interfaces/15/'}, {u'discovered': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 14, u'effective_mtu': 1500, u'system_id': u'cex7hp', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cex7hp/interfaces/14/'}, {u'discovered': None, u'name': u'ens2f0', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 11, u'effective_mtu': 1500, u'system_id': u'cex7hp', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cex7hp/interfaces/11/'}, {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'id': 12, u'effective_mtu': 1500, u'system_id': u'cex7hp', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cex7hp/interfaces/12/'}, {u'discovered': None, u'name': u'ens2f1', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 13, u'effective_mtu': 1500, u'system_id': u'cex7hp', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cex7hp/interfaces/13/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/cex7hp/', u'distro_series': u'', u'node_type_name': u'Machine'}
2019-03-18 08:58:17,222 [salt.state       :300 ][INFO    ][8133] {'new': {'storage_layout': 'lvm'}}
2019-03-18 08:58:17,222 [salt.state       :1951][INFO    ][8133] Completed state [maas_machines_storage_cmp002_lvm] at time 08:58:17.222660 duration_in_ms=2332.317
2019-03-18 08:58:17,224 [salt.state       :1780][INFO    ][8133] Running state [maas_machines_storage_cmp001_lvm] at time 08:58:17.224788
2019-03-18 08:58:17,225 [salt.state       :1813][INFO    ][8133] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-18 08:58:18,286 [salt.loaded.ext.module.maasng:610 ][INFO    ][8133] peam3d
2019-03-18 08:58:18,286 [salt.loaded.ext.module.maasng:626 ][INFO    ][8133] sda
2019-03-18 08:58:18,760 [salt.loaded.ext.module.maasng:361 ][INFO    ][8133] peam3d
2019-03-18 08:58:18,828 [salt.loaded.ext.module.maasng:367 ][INFO    ][8133] [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/peam3d/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'peam3d', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'6aca407e-9ad8-40d0-8287-89c471d7461f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'peam3d', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'7dc8991f-cc78-484d-bf83-5549253313fc', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/peam3d/blockdevices/2/partition/2'}]}, {u'size': 800097042432, u'resource_uri': u'/MAAS/api/2.0/nodes/peam3d/blockdevices/4/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'uuid': u'08dfb2ae-3321-426e-837d-141053042d22', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'peam3d', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'd1e04d92-dd39-4e2a-8ade-904174264bfd', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 4, u'serial': None, u'partitions': []}]
2019-03-18 08:58:18,828 [salt.loaded.ext.module.maasng:632 ][INFO    ][8133] vgroot
2019-03-18 08:58:18,828 [salt.loaded.ext.module.maasng:635 ][INFO    ][8133] lvroot
2019-03-18 08:58:18,829 [salt.loaded.ext.module.maasng:639 ][INFO    ][8133] 107374182400
2019-03-18 08:58:19,297 [salt.loaded.ext.module.maasng:645 ][INFO    ][8133] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 40, u'owner_data': {}, u'hwe_kernel': u'', u'boot_interface': {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 5, u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'peam3d', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/peam3d/interfaces/5/'}, 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'boot_disk': {u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/peam3d/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'peam3d', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'4c007e13-d342-4978-841b-803b9f2a7ad1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'peam3d', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a220101b-e06f-4c20-88c3-0183e26531c8', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/peam3d/blockdevices/2/partition/7'}], u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'peam3d', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'd625b0f1-1d37-4921-a923-9d97867bd781', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/peam3d/blockdevices/12/', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'peam3d', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'5e634047-94f8-4039-babf-f26b25d2e925', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'id': 12, u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/peam3d/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'peam3d', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'4c007e13-d342-4978-841b-803b9f2a7ad1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'peam3d', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a220101b-e06f-4c20-88c3-0183e26531c8', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/peam3d/blockdevices/2/partition/7'}]}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/peam3d/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'd625b0f1-1d37-4921-a923-9d97867bd781', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'peam3d', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'5e634047-94f8-4039-babf-f26b25d2e925', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'commissioning_status': 2, u'physicalblockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/peam3d/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'peam3d', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'4c007e13-d342-4978-841b-803b9f2a7ad1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'peam3d', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a220101b-e06f-4c20-88c3-0183e26531c8', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/peam3d/blockdevices/2/partition/7'}], u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'peam3d', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'interface_set': [{u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 5, u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'peam3d', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/peam3d/interfaces/5/'}, {u'discovered': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 18, u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'peam3d', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/peam3d/interfaces/18/'}, {u'discovered': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 19, u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'peam3d', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/peam3d/interfaces/19/'}, {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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.41'}], u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 16, u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'peam3d', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/peam3d/interfaces/16/'}, {u'discovered': None, u'name': u'ens2f0', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 17, u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'peam3d', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/peam3d/interfaces/17/'}, {u'discovered': None, u'name': u'ens2f1', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 20, u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'peam3d', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/peam3d/interfaces/20/'}], u'power_type': u'ipmi', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'min_hwe_kernel': u'ga-16.04', u'current_commissioning_result_id': 4, u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/peam3d/', u'distro_series': u'', u'memory_test_status': -1}
2019-03-18 08:58:19,299 [salt.state       :300 ][INFO    ][8133] {'new': {'storage_layout': 'lvm'}}
2019-03-18 08:58:19,300 [salt.state       :1951][INFO    ][8133] Completed state [maas_machines_storage_cmp001_lvm] at time 08:58:19.300064 duration_in_ms=2075.276
2019-03-18 08:58:19,302 [salt.minion      :1711][INFO    ][8133] Returning information for job: 20190318085807434145
2019-03-18 08:58:19,822 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command state.apply with jid 20190318085819811658
2019-03-18 08:58:19,836 [salt.minion      :1432][INFO    ][8163] Starting a new job with PID 8163
2019-03-18 08:58:20,532 [salt.state       :915 ][INFO    ][8163] Loading fresh modules for state activity
2019-03-18 08:58:20,577 [salt.fileclient  :1219][INFO    ][8163] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-03-18 08:58:20,614 [salt.state       :1780][INFO    ][8163] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:58:20.614576
2019-03-18 08:58:20,615 [salt.state       :1813][INFO    ][8163] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-18 08:58:20,616 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8163] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-18 08:58:22,280 [salt.state       :300 ][INFO    ][8163] {'pid': 8170, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-18 08:58:22,281 [salt.state       :1951][INFO    ][8163] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:58:22.281359 duration_in_ms=1666.782
2019-03-18 08:58:22,283 [salt.state       :1780][INFO    ][8163] Running state [maas.deploy_machines] at time 08:58:22.282821
2019-03-18 08:58:22,283 [salt.state       :1813][INFO    ][8163] Executing state module.run for [maas.deploy_machines]
2019-03-18 08:58:22,284 [salt.utils.decorators:613 ][WARNING ][8163] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-18 08:58:22,764 [salt.loaded.ext.module.maas:684 ][INFO    ][8163] deploymachines hwe_kernel=ga-16.04 system_id=cex7hp distro_series=xenial
2019-03-18 08:58:25,096 [salt.loaded.ext.module.maas:684 ][INFO    ][8163] deploymachines hwe_kernel=ga-16.04 system_id=peam3d distro_series=xenial
2019-03-18 08:58:27,355 [salt.loaded.ext.module.maas:684 ][INFO    ][8163] deploymachines hwe_kernel=ga-16.04 system_id=hh6xaf distro_series=xenial
2019-03-18 08:58:29,660 [salt.loaded.ext.module.maas:684 ][INFO    ][8163] deploymachines hwe_kernel=ga-16.04 system_id=pachmg distro_series=xenial
2019-03-18 08:58:31,965 [salt.loaded.ext.module.maas:684 ][INFO    ][8163] deploymachines hwe_kernel=ga-16.04 system_id=ygsm7e distro_series=xenial
2019-03-18 08:58:34,262 [salt.state       :300 ][INFO    ][8163] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-18 08:58:34,266 [salt.state       :1951][INFO    ][8163] Completed state [maas.deploy_machines] at time 08:58:34.265942 duration_in_ms=11983.12
2019-03-18 08:58:34,269 [salt.minion      :1711][INFO    ][8163] Returning information for job: 20190318085819811658
2019-03-18 08:58:34,812 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command state.apply with jid 20190318085834799863
2019-03-18 08:58:34,826 [salt.minion      :1432][INFO    ][8466] Starting a new job with PID 8466
2019-03-18 08:58:40,793 [salt.state       :915 ][INFO    ][8466] Loading fresh modules for state activity
2019-03-18 08:58:40,847 [salt.fileclient  :1219][INFO    ][8466] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-03-18 08:58:40,891 [salt.state       :1780][INFO    ][8466] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:58:40.891410
2019-03-18 08:58:40,891 [salt.state       :1813][INFO    ][8466] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-18 08:58:40,893 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8466] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-18 08:58:42,625 [salt.state       :300 ][INFO    ][8466] {'pid': 8482, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-18 08:58:42,628 [salt.state       :1951][INFO    ][8466] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:58:42.627782 duration_in_ms=1736.373
2019-03-18 08:58:42,630 [salt.state       :1780][INFO    ][8466] Running state [maas.wait_for_machine_status] at time 08:58:42.630325
2019-03-18 08:58:42,631 [salt.state       :1813][INFO    ][8466] Executing state module.run for [maas.wait_for_machine_status]
2019-03-18 08:58:42,631 [salt.utils.decorators:613 ][WARNING ][8466] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-18 08:58:45,464 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.17985106s left)
2019-03-18 08:58:49,881 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318085849872722
2019-03-18 08:58:49,904 [salt.minion      :1432][INFO    ][8548] Starting a new job with PID 8548
2019-03-18 08:58:49,926 [salt.minion      :1711][INFO    ][8548] Returning information for job: 20190318085849872722
2019-03-18 08:59:18,343 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.30093694s left)
2019-03-18 08:59:20,073 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318085920060825
2019-03-18 08:59:20,096 [salt.minion      :1432][INFO    ][8606] Starting a new job with PID 8606
2019-03-18 08:59:20,117 [salt.minion      :1711][INFO    ][8606] Returning information for job: 20190318085920060825
2019-03-18 08:59:50,093 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318085950083208
2019-03-18 08:59:50,108 [salt.minion      :1432][INFO    ][8681] Starting a new job with PID 8681
2019-03-18 08:59:50,126 [salt.minion      :1711][INFO    ][8681] Returning information for job: 20190318085950083208
2019-03-18 08:59:51,130 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.514045s left)
2019-03-18 09:00:20,147 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318090020135360
2019-03-18 09:00:20,168 [salt.minion      :1432][INFO    ][8709] Starting a new job with PID 8709
2019-03-18 09:00:20,189 [salt.minion      :1711][INFO    ][8709] Returning information for job: 20190318090020135360
2019-03-18 09:00:24,018 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.62648392s left)
2019-03-18 09:00:50,201 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318090050189197
2019-03-18 09:00:50,222 [salt.minion      :1432][INFO    ][8791] Starting a new job with PID 8791
2019-03-18 09:00:50,243 [salt.minion      :1711][INFO    ][8791] Returning information for job: 20190318090050189197
2019-03-18 09:00:56,750 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.89385295s left)
2019-03-18 09:01:20,250 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318090120241125
2019-03-18 09:01:20,265 [salt.minion      :1432][INFO    ][8850] Starting a new job with PID 8850
2019-03-18 09:01:20,285 [salt.minion      :1711][INFO    ][8850] Returning information for job: 20190318090120241125
2019-03-18 09:01:29,696 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.94833612s left)
2019-03-18 09:01:50,309 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318090150294043
2019-03-18 09:01:50,332 [salt.minion      :1432][INFO    ][9039] Starting a new job with PID 9039
2019-03-18 09:01:50,352 [salt.minion      :1711][INFO    ][9039] Returning information for job: 20190318090150294043
2019-03-18 09:02:02,653 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.99065208s left)
2019-03-18 09:02:20,396 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318090220381766
2019-03-18 09:02:20,418 [salt.minion      :1432][INFO    ][9105] Starting a new job with PID 9105
2019-03-18 09:02:20,438 [salt.minion      :1711][INFO    ][9105] Returning information for job: 20190318090220381766
2019-03-18 09:02:35,467 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2017.17678499s left)
2019-03-18 09:02:50,497 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318090250487635
2019-03-18 09:02:50,515 [salt.minion      :1432][INFO    ][9420] Starting a new job with PID 9420
2019-03-18 09:02:50,536 [salt.minion      :1711][INFO    ][9420] Returning information for job: 20190318090250487635
2019-03-18 09:03:08,204 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1984.4404211s left)
2019-03-18 09:03:20,550 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318090320538789
2019-03-18 09:03:20,572 [salt.minion      :1432][INFO    ][9490] Starting a new job with PID 9490
2019-03-18 09:03:20,594 [salt.minion      :1711][INFO    ][9490] Returning information for job: 20190318090320538789
2019-03-18 09:03:41,395 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1951.24895096s left)
2019-03-18 09:03:50,690 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318090350683715
2019-03-18 09:03:50,709 [salt.minion      :1432][INFO    ][9941] Starting a new job with PID 9941
2019-03-18 09:03:50,732 [salt.minion      :1711][INFO    ][9941] Returning information for job: 20190318090350683715
2019-03-18 09:04:14,296 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1918.34795499s left)
2019-03-18 09:04:20,796 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318090420781938
2019-03-18 09:04:20,817 [salt.minion      :1432][INFO    ][10006] Starting a new job with PID 10006
2019-03-18 09:04:20,838 [salt.minion      :1711][INFO    ][10006] Returning information for job: 20190318090420781938
2019-03-18 09:04:48,528 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1884.11592007s left)
2019-03-18 09:04:50,937 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318090450922605
2019-03-18 09:04:50,959 [salt.minion      :1432][INFO    ][10439] Starting a new job with PID 10439
2019-03-18 09:04:50,980 [salt.minion      :1711][INFO    ][10439] Returning information for job: 20190318090450922605
2019-03-18 09:05:21,026 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318090521018772
2019-03-18 09:05:21,041 [salt.minion      :1432][INFO    ][10484] Starting a new job with PID 10484
2019-03-18 09:05:21,059 [salt.minion      :1711][INFO    ][10484] Returning information for job: 20190318090521018772
2019-03-18 09:05:21,405 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1851.23894s left)
2019-03-18 09:05:51,150 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318090551131824
2019-03-18 09:05:51,173 [salt.minion      :1432][INFO    ][10776] Starting a new job with PID 10776
2019-03-18 09:05:51,192 [salt.minion      :1711][INFO    ][10776] Returning information for job: 20190318090551131824
2019-03-18 09:05:54,359 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1818.28505397s left)
2019-03-18 09:06:21,260 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318090621244254
2019-03-18 09:06:21,283 [salt.minion      :1432][INFO    ][10808] Starting a new job with PID 10808
2019-03-18 09:06:21,304 [salt.minion      :1711][INFO    ][10808] Returning information for job: 20190318090621244254
2019-03-18 09:06:27,120 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1785.52389598s left)
2019-03-18 09:06:51,285 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318090651277685
2019-03-18 09:06:51,299 [salt.minion      :1432][INFO    ][10917] Starting a new job with PID 10917
2019-03-18 09:06:51,319 [salt.minion      :1711][INFO    ][10917] Returning information for job: 20190318090651277685
2019-03-18 09:07:00,105 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1752.53943801s left)
2019-03-18 09:07:21,491 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318090721477199
2019-03-18 09:07:21,512 [salt.minion      :1432][INFO    ][10992] Starting a new job with PID 10992
2019-03-18 09:07:21,534 [salt.minion      :1711][INFO    ][10992] Returning information for job: 20190318090721477199
2019-03-18 09:07:32,912 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1719.73198605s left)
2019-03-18 09:07:51,682 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318090751675811
2019-03-18 09:07:51,696 [salt.minion      :1432][INFO    ][11289] Starting a new job with PID 11289
2019-03-18 09:07:51,715 [salt.minion      :1711][INFO    ][11289] Returning information for job: 20190318090751675811
2019-03-18 09:08:05,824 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1686.82005s left)
2019-03-18 09:08:21,809 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318090821794581
2019-03-18 09:08:21,827 [salt.minion      :1432][INFO    ][11315] Starting a new job with PID 11315
2019-03-18 09:08:21,849 [salt.minion      :1711][INFO    ][11315] Returning information for job: 20190318090821794581
2019-03-18 09:08:38,601 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1654.04303694s left)
2019-03-18 09:08:51,959 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318090851949537
2019-03-18 09:08:51,980 [salt.minion      :1432][INFO    ][11399] Starting a new job with PID 11399
2019-03-18 09:08:52,003 [salt.minion      :1711][INFO    ][11399] Returning information for job: 20190318090851949537
2019-03-18 09:09:11,405 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1621.23920107s left)
2019-03-18 09:09:22,098 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318090922085070
2019-03-18 09:09:22,123 [salt.minion      :1432][INFO    ][11419] Starting a new job with PID 11419
2019-03-18 09:09:22,145 [salt.minion      :1711][INFO    ][11419] Returning information for job: 20190318090922085070
2019-03-18 09:09:44,277 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1588.36664605s left)
2019-03-18 09:09:52,269 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318090952254306
2019-03-18 09:09:52,286 [salt.minion      :1432][INFO    ][11554] Starting a new job with PID 11554
2019-03-18 09:09:52,310 [salt.minion      :1711][INFO    ][11554] Returning information for job: 20190318090952254306
2019-03-18 09:10:17,285 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1555.35923195s left)
2019-03-18 09:10:22,329 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318091022321200
2019-03-18 09:10:22,342 [salt.minion      :1432][INFO    ][11614] Starting a new job with PID 11614
2019-03-18 09:10:22,363 [salt.minion      :1711][INFO    ][11614] Returning information for job: 20190318091022321200
2019-03-18 09:10:50,029 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1522.61466503s left)
2019-03-18 09:10:52,533 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318091052522422
2019-03-18 09:10:52,555 [salt.minion      :1432][INFO    ][12077] Starting a new job with PID 12077
2019-03-18 09:10:52,577 [salt.minion      :1711][INFO    ][12077] Returning information for job: 20190318091052522422
2019-03-18 09:11:22,716 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1489.92819595s left)
2019-03-18 09:11:22,726 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318091122715620
2019-03-18 09:11:22,747 [salt.minion      :1432][INFO    ][12104] Starting a new job with PID 12104
2019-03-18 09:11:22,771 [salt.minion      :1711][INFO    ][12104] Returning information for job: 20190318091122715620
2019-03-18 09:11:52,913 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318091152899606
2019-03-18 09:11:52,927 [salt.minion      :1432][INFO    ][12185] Starting a new job with PID 12185
2019-03-18 09:11:52,945 [salt.minion      :1711][INFO    ][12185] Returning information for job: 20190318091152899606
2019-03-18 09:11:55,600 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1457.04405594s left)
2019-03-18 09:12:23,129 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318091223114417
2019-03-18 09:12:23,147 [salt.minion      :1432][INFO    ][12206] Starting a new job with PID 12206
2019-03-18 09:12:23,170 [salt.minion      :1711][INFO    ][12206] Returning information for job: 20190318091223114417
2019-03-18 09:12:28,478 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1424.16634202s left)
2019-03-18 09:12:53,325 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318091253310092
2019-03-18 09:12:53,347 [salt.minion      :1432][INFO    ][12287] Starting a new job with PID 12287
2019-03-18 09:12:53,368 [salt.minion      :1711][INFO    ][12287] Returning information for job: 20190318091253310092
2019-03-18 09:13:01,229 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1391.41500306s left)
2019-03-18 09:13:23,512 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318091323499486
2019-03-18 09:13:23,529 [salt.minion      :1432][INFO    ][12307] Starting a new job with PID 12307
2019-03-18 09:13:23,553 [salt.minion      :1711][INFO    ][12307] Returning information for job: 20190318091323499486
2019-03-18 09:13:34,138 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1358.50581813s left)
2019-03-18 09:13:53,681 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318091353672112
2019-03-18 09:13:53,698 [salt.minion      :1432][INFO    ][12395] Starting a new job with PID 12395
2019-03-18 09:13:53,721 [salt.minion      :1711][INFO    ][12395] Returning information for job: 20190318091353672112
2019-03-18 09:14:06,942 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1325.70244694s left)
2019-03-18 09:14:23,725 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318091423712924
2019-03-18 09:14:23,746 [salt.minion      :1432][INFO    ][12416] Starting a new job with PID 12416
2019-03-18 09:14:23,769 [salt.minion      :1711][INFO    ][12416] Returning information for job: 20190318091423712924
2019-03-18 09:14:39,473 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1293.17118907s left)
2019-03-18 09:14:53,766 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318091453754074
2019-03-18 09:14:53,785 [salt.minion      :1432][INFO    ][12509] Starting a new job with PID 12509
2019-03-18 09:14:53,809 [salt.minion      :1711][INFO    ][12509] Returning information for job: 20190318091453754074
2019-03-18 09:15:12,270 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1260.37419391s left)
2019-03-18 09:15:23,828 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318091523820012
2019-03-18 09:15:23,842 [salt.minion      :1432][INFO    ][12529] Starting a new job with PID 12529
2019-03-18 09:15:23,864 [salt.minion      :1711][INFO    ][12529] Returning information for job: 20190318091523820012
2019-03-18 09:15:44,960 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1227.68404412s left)
2019-03-18 09:15:53,872 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318091553862947
2019-03-18 09:15:53,896 [salt.minion      :1432][INFO    ][12613] Starting a new job with PID 12613
2019-03-18 09:15:53,920 [salt.minion      :1711][INFO    ][12613] Returning information for job: 20190318091553862947
2019-03-18 09:16:17,692 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1194.95184112s left)
2019-03-18 09:16:23,966 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318091623953579
2019-03-18 09:16:23,989 [salt.minion      :1432][INFO    ][12635] Starting a new job with PID 12635
2019-03-18 09:16:24,011 [salt.minion      :1711][INFO    ][12635] Returning information for job: 20190318091623953579
2019-03-18 09:16:50,450 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1162.1938591s left)
2019-03-18 09:16:54,048 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318091654041024
2019-03-18 09:16:54,060 [salt.minion      :1432][INFO    ][12716] Starting a new job with PID 12716
2019-03-18 09:16:54,081 [salt.minion      :1711][INFO    ][12716] Returning information for job: 20190318091654041024
2019-03-18 09:17:23,203 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1129.44159603s left)
2019-03-18 09:17:24,113 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318091724104136
2019-03-18 09:17:24,134 [salt.minion      :1432][INFO    ][12739] Starting a new job with PID 12739
2019-03-18 09:17:24,156 [salt.minion      :1711][INFO    ][12739] Returning information for job: 20190318091724104136
2019-03-18 09:17:54,248 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318091754239988
2019-03-18 09:17:54,265 [salt.minion      :1432][INFO    ][12820] Starting a new job with PID 12820
2019-03-18 09:17:54,287 [salt.minion      :1711][INFO    ][12820] Returning information for job: 20190318091754239988
2019-03-18 09:17:55,984 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1096.65998793s left)
2019-03-18 09:18:24,353 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318091824338185
2019-03-18 09:18:24,374 [salt.minion      :1432][INFO    ][12838] Starting a new job with PID 12838
2019-03-18 09:18:24,401 [salt.minion      :1711][INFO    ][12838] Returning information for job: 20190318091824338185
2019-03-18 09:18:28,637 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1064.00663209s left)
2019-03-18 09:18:54,509 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318091854499446
2019-03-18 09:18:54,530 [salt.minion      :1432][INFO    ][12919] Starting a new job with PID 12919
2019-03-18 09:18:54,553 [salt.minion      :1711][INFO    ][12919] Returning information for job: 20190318091854499446
2019-03-18 09:19:01,308 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1031.33616304s left)
2019-03-18 09:19:24,654 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318091924641654
2019-03-18 09:19:24,672 [salt.minion      :1432][INFO    ][12943] Starting a new job with PID 12943
2019-03-18 09:19:24,694 [salt.minion      :1711][INFO    ][12943] Returning information for job: 20190318091924641654
2019-03-18 09:19:34,079 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (998.564887047s left)
2019-03-18 09:19:54,846 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318091954832454
2019-03-18 09:19:54,870 [salt.minion      :1432][INFO    ][13028] Starting a new job with PID 13028
2019-03-18 09:19:54,892 [salt.minion      :1711][INFO    ][13028] Returning information for job: 20190318091954832454
2019-03-18 09:20:06,742 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (965.902393103s left)
2019-03-18 09:20:25,002 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318092024988691
2019-03-18 09:20:25,018 [salt.minion      :1432][INFO    ][13049] Starting a new job with PID 13049
2019-03-18 09:20:25,041 [salt.minion      :1711][INFO    ][13049] Returning information for job: 20190318092024988691
2019-03-18 09:20:39,498 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (933.146088123s left)
2019-03-18 09:20:55,025 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318092055015572
2019-03-18 09:20:55,044 [salt.minion      :1432][INFO    ][13149] Starting a new job with PID 13149
2019-03-18 09:20:55,068 [salt.minion      :1711][INFO    ][13149] Returning information for job: 20190318092055015572
2019-03-18 09:21:12,261 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (900.383352995s left)
2019-03-18 09:21:25,225 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318092125211050
2019-03-18 09:21:25,249 [salt.minion      :1432][INFO    ][13173] Starting a new job with PID 13173
2019-03-18 09:21:25,274 [salt.minion      :1711][INFO    ][13173] Returning information for job: 20190318092125211050
2019-03-18 09:21:45,088 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (867.557641029s left)
2019-03-18 09:21:55,270 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318092155260186
2019-03-18 09:21:55,292 [salt.minion      :1432][INFO    ][13254] Starting a new job with PID 13254
2019-03-18 09:21:55,315 [salt.minion      :1711][INFO    ][13254] Returning information for job: 20190318092155260186
2019-03-18 09:22:17,746 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (834.897941113s left)
2019-03-18 09:22:25,290 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318092225276247
2019-03-18 09:22:25,312 [salt.minion      :1432][INFO    ][13275] Starting a new job with PID 13275
2019-03-18 09:22:25,333 [salt.minion      :1711][INFO    ][13275] Returning information for job: 20190318092225276247
2019-03-18 09:22:50,503 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (802.141252041s left)
2019-03-18 09:22:55,343 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318092255335852
2019-03-18 09:22:55,365 [salt.minion      :1432][INFO    ][13356] Starting a new job with PID 13356
2019-03-18 09:22:55,388 [salt.minion      :1711][INFO    ][13356] Returning information for job: 20190318092255335852
2019-03-18 09:23:23,102 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (769.542347908s left)
2019-03-18 09:23:25,388 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318092325382199
2019-03-18 09:23:25,404 [salt.minion      :1432][INFO    ][13379] Starting a new job with PID 13379
2019-03-18 09:23:25,423 [salt.minion      :1711][INFO    ][13379] Returning information for job: 20190318092325382199
2019-03-18 09:23:55,429 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318092355421854
2019-03-18 09:23:55,448 [salt.minion      :1432][INFO    ][13466] Starting a new job with PID 13466
2019-03-18 09:23:55,471 [salt.minion      :1711][INFO    ][13466] Returning information for job: 20190318092355421854
2019-03-18 09:23:55,769 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (736.874798059s left)
2019-03-18 09:24:25,576 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318092425564341
2019-03-18 09:24:25,594 [salt.minion      :1432][INFO    ][13484] Starting a new job with PID 13484
2019-03-18 09:24:25,617 [salt.minion      :1711][INFO    ][13484] Returning information for job: 20190318092425564341
2019-03-18 09:24:28,629 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (704.015365124s left)
2019-03-18 09:24:55,652 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318092455641992
2019-03-18 09:24:55,673 [salt.minion      :1432][INFO    ][13564] Starting a new job with PID 13564
2019-03-18 09:24:55,697 [salt.minion      :1711][INFO    ][13564] Returning information for job: 20190318092455641992
2019-03-18 09:25:01,326 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (671.318538904s left)
2019-03-18 09:25:25,812 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318092525803299
2019-03-18 09:25:25,832 [salt.minion      :1432][INFO    ][13585] Starting a new job with PID 13585
2019-03-18 09:25:25,855 [salt.minion      :1711][INFO    ][13585] Returning information for job: 20190318092525803299
2019-03-18 09:25:34,081 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (638.563332081s left)
2019-03-18 09:25:55,936 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318092555922271
2019-03-18 09:25:55,955 [salt.minion      :1432][INFO    ][13666] Starting a new job with PID 13666
2019-03-18 09:25:55,976 [salt.minion      :1711][INFO    ][13666] Returning information for job: 20190318092555922271
2019-03-18 09:26:06,718 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (605.925961018s left)
2019-03-18 09:26:26,109 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318092626093171
2019-03-18 09:26:26,132 [salt.minion      :1432][INFO    ][13687] Starting a new job with PID 13687
2019-03-18 09:26:26,154 [salt.minion      :1711][INFO    ][13687] Returning information for job: 20190318092626093171
2019-03-18 09:26:39,491 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (573.153429985s left)
2019-03-18 09:26:56,305 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318092656291875
2019-03-18 09:26:56,327 [salt.minion      :1432][INFO    ][13769] Starting a new job with PID 13769
2019-03-18 09:26:56,349 [salt.minion      :1711][INFO    ][13769] Returning information for job: 20190318092656291875
2019-03-18 09:27:12,370 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (540.274544954s left)
2019-03-18 09:27:26,346 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318092726332382
2019-03-18 09:27:26,366 [salt.minion      :1432][INFO    ][13790] Starting a new job with PID 13790
2019-03-18 09:27:26,390 [salt.minion      :1711][INFO    ][13790] Returning information for job: 20190318092726332382
2019-03-18 09:27:45,029 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (507.614794016s left)
2019-03-18 09:27:56,567 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318092756554510
2019-03-18 09:27:56,589 [salt.minion      :1432][INFO    ][13873] Starting a new job with PID 13873
2019-03-18 09:27:56,616 [salt.minion      :1711][INFO    ][13873] Returning information for job: 20190318092756554510
2019-03-18 09:28:17,688 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (474.955778122s left)
2019-03-18 09:28:26,625 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318092826613627
2019-03-18 09:28:26,647 [salt.minion      :1432][INFO    ][13894] Starting a new job with PID 13894
2019-03-18 09:28:26,670 [salt.minion      :1711][INFO    ][13894] Returning information for job: 20190318092826613627
2019-03-18 09:28:50,377 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (442.267318964s left)
2019-03-18 09:28:56,833 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318092856820708
2019-03-18 09:28:56,846 [salt.minion      :1432][INFO    ][13980] Starting a new job with PID 13980
2019-03-18 09:28:56,873 [salt.minion      :1711][INFO    ][13980] Returning information for job: 20190318092856820708
2019-03-18 09:29:23,094 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (409.549772978s left)
2019-03-18 09:29:26,942 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318092926933292
2019-03-18 09:29:26,958 [salt.minion      :1432][INFO    ][14001] Starting a new job with PID 14001
2019-03-18 09:29:26,981 [salt.minion      :1711][INFO    ][14001] Returning information for job: 20190318092926933292
2019-03-18 09:29:55,735 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (376.909025908s left)
2019-03-18 09:29:57,061 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318092957046218
2019-03-18 09:29:57,082 [salt.minion      :1432][INFO    ][14083] Starting a new job with PID 14083
2019-03-18 09:29:57,106 [salt.minion      :1711][INFO    ][14083] Returning information for job: 20190318092957046218
2019-03-18 09:30:27,168 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318093027158737
2019-03-18 09:30:27,187 [salt.minion      :1432][INFO    ][14102] Starting a new job with PID 14102
2019-03-18 09:30:27,212 [salt.minion      :1711][INFO    ][14102] Returning information for job: 20190318093027158737
2019-03-18 09:30:28,516 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (344.127908945s left)
2019-03-18 09:30:57,341 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318093057328092
2019-03-18 09:30:57,360 [salt.minion      :1432][INFO    ][14324] Starting a new job with PID 14324
2019-03-18 09:30:57,383 [salt.minion      :1711][INFO    ][14324] Returning information for job: 20190318093057328092
2019-03-18 09:31:01,423 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (311.221471071s left)
2019-03-18 09:31:27,446 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318093127432214
2019-03-18 09:31:27,469 [salt.minion      :1432][INFO    ][14347] Starting a new job with PID 14347
2019-03-18 09:31:27,497 [salt.minion      :1711][INFO    ][14347] Returning information for job: 20190318093127432214
2019-03-18 09:31:34,049 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (278.59513998s left)
2019-03-18 09:31:57,654 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318093157639784
2019-03-18 09:31:57,676 [salt.minion      :1432][INFO    ][14427] Starting a new job with PID 14427
2019-03-18 09:31:57,701 [salt.minion      :1711][INFO    ][14427] Returning information for job: 20190318093157639784
2019-03-18 09:32:06,738 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (245.90563798s left)
2019-03-18 09:32:27,822 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318093227806521
2019-03-18 09:32:27,838 [salt.minion      :1432][INFO    ][14450] Starting a new job with PID 14450
2019-03-18 09:32:27,861 [salt.minion      :1711][INFO    ][14450] Returning information for job: 20190318093227806521
2019-03-18 09:32:39,377 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (213.267203093s left)
2019-03-18 09:32:57,860 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318093257850376
2019-03-18 09:32:57,883 [salt.minion      :1432][INFO    ][14534] Starting a new job with PID 14534
2019-03-18 09:32:57,908 [salt.minion      :1711][INFO    ][14534] Returning information for job: 20190318093257850376
2019-03-18 09:33:11,935 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (180.708769083s left)
2019-03-18 09:33:27,883 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318093327875542
2019-03-18 09:33:27,907 [salt.minion      :1432][INFO    ][14559] Starting a new job with PID 14559
2019-03-18 09:33:27,943 [salt.minion      :1711][INFO    ][14559] Returning information for job: 20190318093327875542
2019-03-18 09:33:44,704 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (147.940222025s left)
2019-03-18 09:33:57,940 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318093357926103
2019-03-18 09:33:57,960 [salt.minion      :1432][INFO    ][14640] Starting a new job with PID 14640
2019-03-18 09:33:57,983 [salt.minion      :1711][INFO    ][14640] Returning information for job: 20190318093357926103
2019-03-18 09:34:17,418 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (115.226421118s left)
2019-03-18 09:34:27,967 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318093427956032
2019-03-18 09:34:27,986 [salt.minion      :1432][INFO    ][14661] Starting a new job with PID 14661
2019-03-18 09:34:28,008 [salt.minion      :1711][INFO    ][14661] Returning information for job: 20190318093427956032
2019-03-18 09:34:50,417 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (82.2267820835s left)
2019-03-18 09:34:58,054 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318093458034603
2019-03-18 09:34:58,073 [salt.minion      :1432][INFO    ][14741] Starting a new job with PID 14741
2019-03-18 09:34:58,096 [salt.minion      :1711][INFO    ][14741] Returning information for job: 20190318093458034603
2019-03-18 09:35:23,039 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (49.6047010422s left)
2019-03-18 09:35:28,134 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318093528126632
2019-03-18 09:35:28,148 [salt.minion      :1432][INFO    ][14762] Starting a new job with PID 14762
2019-03-18 09:35:28,167 [salt.minion      :1711][INFO    ][14762] Returning information for job: 20190318093528126632
2019-03-18 09:35:55,818 [salt.loaded.ext.module.maas:1023][INFO    ][8466] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (16.825783968s left)
2019-03-18 09:35:58,261 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318093558253165
2019-03-18 09:35:58,280 [salt.minion      :1432][INFO    ][14845] Starting a new job with PID 14845
2019-03-18 09:35:58,301 [salt.minion      :1711][INFO    ][14845] Returning information for job: 20190318093558253165
2019-03-18 09:36:28,388 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318093628378615
2019-03-18 09:36:28,404 [salt.minion      :1432][INFO    ][14868] Starting a new job with PID 14868
2019-03-18 09:36:28,425 [salt.minion      :1711][INFO    ][14868] Returning information for job: 20190318093628378615
2019-03-18 09:36:28,473 [salt.state       :302 ][ERROR   ][8466] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-03-18 09:36:28,474 [salt.state       :1951][INFO    ][8466] Completed state [maas.wait_for_machine_status] at time 09:36:28.474028 duration_in_ms=2265843.701
2019-03-18 09:36:28,481 [salt.minion      :1711][INFO    ][8466] Returning information for job: 20190318085834799863
2019-03-18 09:36:39,246 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command pillar.get with jid 20190318093639233712
2019-03-18 09:36:39,264 [salt.minion      :1432][INFO    ][14888] Starting a new job with PID 14888
2019-03-18 09:36:39,274 [salt.minion      :1711][INFO    ][14888] Returning information for job: 20190318093639233712
2019-03-18 09:36:39,809 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command service.status with jid 20190318093639800957
2019-03-18 09:36:39,826 [salt.minion      :1432][INFO    ][14893] Starting a new job with PID 14893
2019-03-18 09:36:40,318 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14893] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-03-18 09:36:40,352 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14893] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-03-18 09:36:40,366 [salt.minion      :1711][INFO    ][14893] Returning information for job: 20190318093639800957
2019-03-18 09:36:40,928 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command state.apply with jid 20190318093640916246
2019-03-18 09:36:40,950 [salt.minion      :1432][INFO    ][14904] Starting a new job with PID 14904
2019-03-18 09:36:46,700 [salt.state       :915 ][INFO    ][14904] Loading fresh modules for state activity
2019-03-18 09:36:47,256 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14904] Executing command 'salt-minion --version' in directory '/root'
2019-03-18 09:36:47,541 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14904] Executing command 'salt-minion --version' in directory '/root'
2019-03-18 09:36:48,475 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14904] Executing command 'salt-minion --version' in directory '/root'
2019-03-18 09:36:48,749 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14904] Executing command 'salt-minion --version' in directory '/root'
2019-03-18 09:36:50,436 [salt.state       :1780][INFO    ][14904] Running state [salt-minion] at time 09:36:50.436269
2019-03-18 09:36:50,436 [salt.state       :1813][INFO    ][14904] Executing state pkg.installed for [salt-minion]
2019-03-18 09:36:50,437 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14904] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-18 09:36:50,520 [salt.state       :300 ][INFO    ][14904] All specified packages are already installed
2019-03-18 09:36:50,520 [salt.state       :1951][INFO    ][14904] Completed state [salt-minion] at time 09:36:50.520689 duration_in_ms=84.42
2019-03-18 09:36:50,521 [salt.state       :1780][INFO    ][14904] Running state [salt_minion_dependency_packages] at time 09:36:50.521016
2019-03-18 09:36:50,521 [salt.state       :1813][INFO    ][14904] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-03-18 09:36:50,531 [salt.state       :300 ][INFO    ][14904] All specified packages are already installed
2019-03-18 09:36:50,531 [salt.state       :1951][INFO    ][14904] Completed state [salt_minion_dependency_packages] at time 09:36:50.531454 duration_in_ms=10.438
2019-03-18 09:36:50,534 [salt.state       :1780][INFO    ][14904] Running state [/etc/salt/minion.d/minion.conf] at time 09:36:50.534329
2019-03-18 09:36:50,534 [salt.state       :1813][INFO    ][14904] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-03-18 09:36:50,799 [salt.state       :300 ][INFO    ][14904] File /etc/salt/minion.d/minion.conf is in the correct state
2019-03-18 09:36:50,799 [salt.state       :1951][INFO    ][14904] Completed state [/etc/salt/minion.d/minion.conf] at time 09:36:50.799787 duration_in_ms=265.458
2019-03-18 09:36:50,800 [salt.state       :1780][INFO    ][14904] Running state [python-netaddr] at time 09:36:50.800039
2019-03-18 09:36:50,800 [salt.state       :1813][INFO    ][14904] Executing state pkg.installed for [python-netaddr]
2019-03-18 09:36:50,808 [salt.state       :300 ][INFO    ][14904] All specified packages are already installed
2019-03-18 09:36:50,809 [salt.state       :1951][INFO    ][14904] Completed state [python-netaddr] at time 09:36:50.809047 duration_in_ms=9.008
2019-03-18 09:36:50,813 [salt.state       :1780][INFO    ][14904] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 09:36:50.813171
2019-03-18 09:36:50,813 [salt.state       :1813][INFO    ][14904] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-03-18 09:36:50,826 [salt.state       :300 ][INFO    ][14904] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-03-18 09:36:50,826 [salt.state       :1951][INFO    ][14904] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 09:36:50.826336 duration_in_ms=13.165
2019-03-18 09:36:50,827 [salt.state       :1780][INFO    ][14904] Running state [salt-minion] at time 09:36:50.827871
2019-03-18 09:36:50,828 [salt.state       :1813][INFO    ][14904] Executing state service.running for [salt-minion]
2019-03-18 09:36:50,829 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14904] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-18 09:36:50,864 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14904] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-03-18 09:36:50,877 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14904] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-03-18 09:36:50,892 [salt.state       :300 ][INFO    ][14904] The service salt-minion is already running
2019-03-18 09:36:50,893 [salt.state       :1951][INFO    ][14904] Completed state [salt-minion] at time 09:36:50.893045 duration_in_ms=65.174
2019-03-18 09:36:50,897 [salt.state       :1780][INFO    ][14904] Running state [/etc/salt/grains.d] at time 09:36:50.897254
2019-03-18 09:36:50,897 [salt.state       :1813][INFO    ][14904] Executing state file.directory for [/etc/salt/grains.d]
2019-03-18 09:36:50,899 [salt.state       :300 ][INFO    ][14904] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-03-18 09:36:50,899 [salt.state       :1951][INFO    ][14904] Completed state [/etc/salt/grains.d] at time 09:36:50.899440 duration_in_ms=2.186
2019-03-18 09:36:50,900 [salt.state       :1780][INFO    ][14904] Running state [/etc/salt/grains] at time 09:36:50.900450
2019-03-18 09:36:50,900 [salt.state       :1813][INFO    ][14904] Executing state file.managed for [/etc/salt/grains]
2019-03-18 09:36:50,901 [salt.state       :300 ][INFO    ][14904] File /etc/salt/grains exists with proper permissions. No changes made.
2019-03-18 09:36:50,902 [salt.state       :1951][INFO    ][14904] Completed state [/etc/salt/grains] at time 09:36:50.902222 duration_in_ms=1.772
2019-03-18 09:36:50,903 [salt.state       :1780][INFO    ][14904] Running state [/etc/salt/grains.d/placeholder] at time 09:36:50.902968
2019-03-18 09:36:50,903 [salt.state       :1813][INFO    ][14904] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-03-18 09:36:50,904 [salt.state       :300 ][INFO    ][14904] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-03-18 09:36:50,904 [salt.state       :1951][INFO    ][14904] Completed state [/etc/salt/grains.d/placeholder] at time 09:36:50.904404 duration_in_ms=1.436
2019-03-18 09:36:50,905 [salt.state       :1780][INFO    ][14904] Running state [/etc/salt/grains.d/sphinx] at time 09:36:50.905119
2019-03-18 09:36:50,905 [salt.state       :1813][INFO    ][14904] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-03-18 09:36:50,909 [salt.state       :300 ][INFO    ][14904] File /etc/salt/grains.d/sphinx is in the correct state
2019-03-18 09:36:50,909 [salt.state       :1951][INFO    ][14904] Completed state [/etc/salt/grains.d/sphinx] at time 09:36:50.909214 duration_in_ms=4.095
2019-03-18 09:36:50,911 [salt.state       :1780][INFO    ][14904] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:36:50.911149
2019-03-18 09:36:50,911 [salt.state       :1813][INFO    ][14904] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-03-18 09:36:50,911 [salt.state       :300 ][INFO    ][14904] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-03-18 09:36:50,911 [salt.state       :1951][INFO    ][14904] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:36:50.911919 duration_in_ms=0.77
2019-03-18 09:36:50,912 [salt.state       :1780][INFO    ][14904] Running state [/etc/salt/grains.d/dns_records] at time 09:36:50.912394
2019-03-18 09:36:50,912 [salt.state       :1813][INFO    ][14904] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-03-18 09:36:50,913 [salt.state       :300 ][INFO    ][14904] File /etc/salt/grains.d/dns_records is in the correct state
2019-03-18 09:36:50,913 [salt.state       :1951][INFO    ][14904] Completed state [/etc/salt/grains.d/dns_records] at time 09:36:50.913711 duration_in_ms=1.317
2019-03-18 09:36:50,914 [salt.state       :1780][INFO    ][14904] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:36:50.914626
2019-03-18 09:36:50,914 [salt.state       :1813][INFO    ][14904] 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-18 09:36:50,915 [salt.state       :300 ][INFO    ][14904] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-03-18 09:36:50,915 [salt.state       :1951][INFO    ][14904] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:36:50.915390 duration_in_ms=0.764
2019-03-18 09:36:50,915 [salt.state       :1780][INFO    ][14904] Running state [/etc/salt/grains.d/salt] at time 09:36:50.915861
2019-03-18 09:36:50,916 [salt.state       :1813][INFO    ][14904] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-03-18 09:36:50,917 [salt.state       :300 ][INFO    ][14904] File /etc/salt/grains.d/salt is in the correct state
2019-03-18 09:36:50,917 [salt.state       :1951][INFO    ][14904] Completed state [/etc/salt/grains.d/salt] at time 09:36:50.917184 duration_in_ms=1.322
2019-03-18 09:36:50,918 [salt.state       :1780][INFO    ][14904] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:36:50.918055
2019-03-18 09:36:50,918 [salt.state       :1813][INFO    ][14904] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-03-18 09:36:50,918 [salt.state       :300 ][INFO    ][14904] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-03-18 09:36:50,919 [salt.state       :1951][INFO    ][14904] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:36:50.918795 duration_in_ms=0.74
2019-03-18 09:36:50,921 [salt.state       :1780][INFO    ][14904] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:36:50.921533
2019-03-18 09:36:50,921 [salt.state       :1813][INFO    ][14904] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-03-18 09:36:50,922 [salt.state       :300 ][INFO    ][14904] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-03-18 09:36:50,922 [salt.state       :1951][INFO    ][14904] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:36:50.922295 duration_in_ms=0.762
2019-03-18 09:36:50,923 [salt.state       :1780][INFO    ][14904] Running state [mine.update] at time 09:36:50.922962
2019-03-18 09:36:50,923 [salt.state       :1813][INFO    ][14904] Executing state module.wait for [mine.update]
2019-03-18 09:36:50,923 [salt.state       :300 ][INFO    ][14904] No changes made for mine.update
2019-03-18 09:36:50,923 [salt.state       :1951][INFO    ][14904] Completed state [mine.update] at time 09:36:50.923658 duration_in_ms=0.696
2019-03-18 09:36:50,923 [salt.state       :1780][INFO    ][14904] Running state [ca-certificates] at time 09:36:50.923890
2019-03-18 09:36:50,924 [salt.state       :1813][INFO    ][14904] Executing state pkg.installed for [ca-certificates]
2019-03-18 09:36:50,933 [salt.state       :300 ][INFO    ][14904] All specified packages are already installed
2019-03-18 09:36:50,933 [salt.state       :1951][INFO    ][14904] Completed state [ca-certificates] at time 09:36:50.933732 duration_in_ms=9.843
2019-03-18 09:36:50,934 [salt.state       :1780][INFO    ][14904] Running state [update-ca-certificates] at time 09:36:50.934413
2019-03-18 09:36:50,934 [salt.state       :1813][INFO    ][14904] Executing state cmd.wait for [update-ca-certificates]
2019-03-18 09:36:50,935 [salt.state       :300 ][INFO    ][14904] No changes made for update-ca-certificates
2019-03-18 09:36:50,935 [salt.state       :1951][INFO    ][14904] Completed state [update-ca-certificates] at time 09:36:50.935427 duration_in_ms=1.014
2019-03-18 09:36:50,935 [salt.state       :1780][INFO    ][14904] Running state [iptables] at time 09:36:50.935659
2019-03-18 09:36:50,935 [salt.state       :1813][INFO    ][14904] Executing state pkg.installed for [iptables]
2019-03-18 09:36:50,945 [salt.state       :300 ][INFO    ][14904] All specified packages are already installed
2019-03-18 09:36:50,945 [salt.state       :1951][INFO    ][14904] Completed state [iptables] at time 09:36:50.945295 duration_in_ms=9.636
2019-03-18 09:36:50,945 [salt.state       :1780][INFO    ][14904] Running state [iptables-persistent] at time 09:36:50.945534
2019-03-18 09:36:50,945 [salt.state       :1813][INFO    ][14904] Executing state pkg.installed for [iptables-persistent]
2019-03-18 09:36:50,954 [salt.state       :300 ][INFO    ][14904] All specified packages are already installed
2019-03-18 09:36:50,954 [salt.state       :1951][INFO    ][14904] Completed state [iptables-persistent] at time 09:36:50.954398 duration_in_ms=8.864
2019-03-18 09:36:50,955 [salt.state       :1780][INFO    ][14904] Running state [iptables_modules_v4_load] at time 09:36:50.955677
2019-03-18 09:36:50,955 [salt.state       :1813][INFO    ][14904] Executing state kmod.present for [iptables_modules_v4_load]
2019-03-18 09:36:50,956 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14904] Executing command 'lsmod' in directory '/root'
2019-03-18 09:36:50,976 [salt.state       :300 ][INFO    ][14904] Kernel modules iptable_filter, ip_tables are already present
2019-03-18 09:36:50,976 [salt.state       :1951][INFO    ][14904] Completed state [iptables_modules_v4_load] at time 09:36:50.976606 duration_in_ms=20.929
2019-03-18 09:36:50,977 [salt.state       :1780][INFO    ][14904] Running state [/etc/iptables/rules.v4] at time 09:36:50.977501
2019-03-18 09:36:50,977 [salt.state       :1813][INFO    ][14904] Executing state file.managed for [/etc/iptables/rules.v4]
2019-03-18 09:36:51,077 [salt.state       :300 ][INFO    ][14904] File /etc/iptables/rules.v4 is in the correct state
2019-03-18 09:36:51,077 [salt.state       :1951][INFO    ][14904] Completed state [/etc/iptables/rules.v4] at time 09:36:51.077472 duration_in_ms=99.971
2019-03-18 09:36:51,078 [salt.state       :1780][INFO    ][14904] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 09:36:51.078617
2019-03-18 09:36:51,079 [salt.state       :1813][INFO    ][14904] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-03-18 09:36:51,079 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14904] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-03-18 09:36:51,102 [salt.state       :300 ][INFO    ][14904] onlyif execution failed
2019-03-18 09:36:51,103 [salt.state       :1951][INFO    ][14904] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 09:36:51.102792 duration_in_ms=24.174
2019-03-18 09:36:51,105 [salt.state       :1780][INFO    ][14904] Running state [netfilter-persistent] at time 09:36:51.105164
2019-03-18 09:36:51,105 [salt.state       :1813][INFO    ][14904] Executing state service.running for [netfilter-persistent]
2019-03-18 09:36:51,107 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14904] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-03-18 09:36:51,128 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14904] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-03-18 09:36:51,150 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14904] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-03-18 09:36:51,168 [salt.state       :300 ][INFO    ][14904] The service netfilter-persistent is already running
2019-03-18 09:36:51,169 [salt.state       :1951][INFO    ][14904] Completed state [netfilter-persistent] at time 09:36:51.169284 duration_in_ms=64.121
2019-03-18 09:36:51,170 [salt.state       :1780][INFO    ][14904] Running state [iptables_extra.remove_stale_tables] at time 09:36:51.170383
2019-03-18 09:36:51,170 [salt.state       :1813][INFO    ][14904] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-03-18 09:36:51,174 [salt.state       :300 ][INFO    ][14904] No changes made for iptables_extra.remove_stale_tables
2019-03-18 09:36:51,174 [salt.state       :1951][INFO    ][14904] Completed state [iptables_extra.remove_stale_tables] at time 09:36:51.174384 duration_in_ms=4.0
2019-03-18 09:36:51,174 [salt.state       :1780][INFO    ][14904] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 09:36:51.174772
2019-03-18 09:36:51,175 [salt.state       :1813][INFO    ][14904] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-03-18 09:36:51,176 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14904] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-03-18 09:36:51,191 [salt.state       :300 ][INFO    ][14904] onlyif execution failed
2019-03-18 09:36:51,191 [salt.state       :1951][INFO    ][14904] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 09:36:51.191386 duration_in_ms=16.615
2019-03-18 09:36:51,192 [salt.state       :1780][INFO    ][14904] Running state [/etc/iptables/rules.v6] at time 09:36:51.192590
2019-03-18 09:36:51,192 [salt.state       :1813][INFO    ][14904] Executing state file.absent for [/etc/iptables/rules.v6]
2019-03-18 09:36:51,193 [salt.state       :300 ][INFO    ][14904] File /etc/iptables/rules.v6 is not present
2019-03-18 09:36:51,193 [salt.state       :1951][INFO    ][14904] Completed state [/etc/iptables/rules.v6] at time 09:36:51.193750 duration_in_ms=1.16
2019-03-18 09:36:51,194 [salt.state       :1780][INFO    ][14904] Running state [iptables_extra.flush_all] at time 09:36:51.194589
2019-03-18 09:36:51,198 [salt.state       :1813][INFO    ][14904] Executing state module.wait for [iptables_extra.flush_all]
2019-03-18 09:36:51,198 [salt.state       :300 ][INFO    ][14904] No changes made for iptables_extra.flush_all
2019-03-18 09:36:51,198 [salt.state       :1951][INFO    ][14904] Completed state [iptables_extra.flush_all] at time 09:36:51.198679 duration_in_ms=4.09
2019-03-18 09:36:51,201 [salt.minion      :1711][INFO    ][14904] Returning information for job: 20190318093640916246
2019-03-18 09:36:51,738 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command state.apply with jid 20190318093651730803
2019-03-18 09:36:51,756 [salt.minion      :1432][INFO    ][15030] Starting a new job with PID 15030
2019-03-18 09:36:52,489 [salt.state       :915 ][INFO    ][15030] Loading fresh modules for state activity
2019-03-18 09:36:53,326 [salt.state       :1780][INFO    ][15030] Running state [maas-rack-controller] at time 09:36:53.326203
2019-03-18 09:36:53,326 [salt.state       :1813][INFO    ][15030] Executing state pkg.installed for [maas-rack-controller]
2019-03-18 09:36:53,327 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15030] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-18 09:36:53,442 [salt.state       :300 ][INFO    ][15030] All specified packages are already installed
2019-03-18 09:36:53,444 [salt.state       :1951][INFO    ][15030] Completed state [maas-rack-controller] at time 09:36:53.443954 duration_in_ms=117.742
2019-03-18 09:36:53,444 [salt.state       :1780][INFO    ][15030] Running state [ipmitool] at time 09:36:53.444576
2019-03-18 09:36:53,445 [salt.state       :1813][INFO    ][15030] Executing state pkg.installed for [ipmitool]
2019-03-18 09:36:53,455 [salt.state       :300 ][INFO    ][15030] All specified packages are already installed
2019-03-18 09:36:53,455 [salt.state       :1951][INFO    ][15030] Completed state [ipmitool] at time 09:36:53.455842 duration_in_ms=11.265
2019-03-18 09:36:53,460 [salt.state       :1780][INFO    ][15030] Running state [/etc/maas/rackd.conf] at time 09:36:53.460491
2019-03-18 09:36:53,460 [salt.state       :1813][INFO    ][15030] Executing state file.line for [/etc/maas/rackd.conf]
2019-03-18 09:36:53,461 [salt.state       :300 ][INFO    ][15030] No changes needed to be made
2019-03-18 09:36:53,462 [salt.state       :1951][INFO    ][15030] Completed state [/etc/maas/rackd.conf] at time 09:36:53.462095 duration_in_ms=1.604
2019-03-18 09:36:53,462 [salt.state       :1780][INFO    ][15030] Running state [/etc/maas/rackd.conf] at time 09:36:53.462335
2019-03-18 09:36:53,462 [salt.state       :1813][INFO    ][15030] Executing state file.managed for [/etc/maas/rackd.conf]
2019-03-18 09:36:53,464 [salt.loaded.int.states.file:2298][WARNING ][15030] 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-18 09:36:53,464 [salt.state       :300 ][INFO    ][15030] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-03-18 09:36:53,464 [salt.state       :1951][INFO    ][15030] Completed state [/etc/maas/rackd.conf] at time 09:36:53.464790 duration_in_ms=2.455
2019-03-18 09:36:53,465 [salt.state       :1780][INFO    ][15030] Running state [maas-rackd] at time 09:36:53.465792
2019-03-18 09:36:53,466 [salt.state       :1813][INFO    ][15030] Executing state service.running for [maas-rackd]
2019-03-18 09:36:53,466 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15030] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-03-18 09:36:53,493 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15030] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-03-18 09:36:53,512 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15030] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-03-18 09:36:53,529 [salt.state       :300 ][INFO    ][15030] The service maas-rackd is already running
2019-03-18 09:36:53,530 [salt.state       :1951][INFO    ][15030] Completed state [maas-rackd] at time 09:36:53.529952 duration_in_ms=64.16
2019-03-18 09:36:53,532 [salt.minion      :1711][INFO    ][15030] Returning information for job: 20190318093651730803
2019-03-18 09:36:54,056 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command state.apply with jid 20190318093654045824
2019-03-18 09:36:54,079 [salt.minion      :1432][INFO    ][15059] Starting a new job with PID 15059
2019-03-18 09:36:54,782 [salt.state       :915 ][INFO    ][15059] Loading fresh modules for state activity
2019-03-18 09:36:55,796 [salt.state       :1780][INFO    ][15059] Running state [maas-region-controller] at time 09:36:55.796184
2019-03-18 09:36:55,796 [salt.state       :1813][INFO    ][15059] Executing state pkg.installed for [maas-region-controller]
2019-03-18 09:36:55,797 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15059] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-18 09:36:55,903 [salt.state       :300 ][INFO    ][15059] All specified packages are already installed
2019-03-18 09:36:55,903 [salt.state       :1951][INFO    ][15059] Completed state [maas-region-controller] at time 09:36:55.903623 duration_in_ms=107.439
2019-03-18 09:36:55,903 [salt.state       :1780][INFO    ][15059] Running state [python-oauth] at time 09:36:55.903932
2019-03-18 09:36:55,904 [salt.state       :1813][INFO    ][15059] Executing state pkg.installed for [python-oauth]
2019-03-18 09:36:55,913 [salt.state       :300 ][INFO    ][15059] All specified packages are already installed
2019-03-18 09:36:55,913 [salt.state       :1951][INFO    ][15059] Completed state [python-oauth] at time 09:36:55.913883 duration_in_ms=9.95
2019-03-18 09:36:55,916 [salt.state       :1780][INFO    ][15059] Running state [/etc/maas/regiond.conf] at time 09:36:55.916643
2019-03-18 09:36:55,916 [salt.state       :1813][INFO    ][15059] Executing state file.replace for [/etc/maas/regiond.conf]
2019-03-18 09:36:55,921 [salt.state       :300 ][INFO    ][15059] No changes needed to be made
2019-03-18 09:36:55,921 [salt.state       :1951][INFO    ][15059] Completed state [/etc/maas/regiond.conf] at time 09:36:55.921756 duration_in_ms=5.113
2019-03-18 09:36:55,922 [salt.state       :1780][INFO    ][15059] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 09:36:55.922227
2019-03-18 09:36:55,922 [salt.state       :1813][INFO    ][15059] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-03-18 09:36:55,981 [salt.state       :300 ][INFO    ][15059] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-03-18 09:36:55,981 [salt.state       :1951][INFO    ][15059] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 09:36:55.981465 duration_in_ms=59.238
2019-03-18 09:36:55,982 [salt.state       :1780][INFO    ][15059] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:36:55.982002
2019-03-18 09:36:55,982 [salt.state       :1813][INFO    ][15059] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-18 09:36:55,986 [salt.state       :300 ][INFO    ][15059] No changes needed to be made
2019-03-18 09:36:55,986 [salt.state       :1951][INFO    ][15059] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:36:55.986550 duration_in_ms=4.548
2019-03-18 09:36:55,987 [salt.state       :1780][INFO    ][15059] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:36:55.987033
2019-03-18 09:36:55,987 [salt.state       :1813][INFO    ][15059] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-18 09:36:55,990 [salt.state       :300 ][INFO    ][15059] No changes needed to be made
2019-03-18 09:36:55,990 [salt.state       :1951][INFO    ][15059] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:36:55.990784 duration_in_ms=3.751
2019-03-18 09:36:55,991 [salt.state       :1780][INFO    ][15059] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:36:55.991265
2019-03-18 09:36:55,991 [salt.state       :1813][INFO    ][15059] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-03-18 09:36:56,016 [salt.state       :300 ][INFO    ][15059] No changes needed to be made
2019-03-18 09:36:56,016 [salt.state       :1951][INFO    ][15059] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:36:56.016331 duration_in_ms=25.066
2019-03-18 09:36:56,016 [salt.state       :1780][INFO    ][15059] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 09:36:56.016867
2019-03-18 09:36:56,017 [salt.state       :1813][INFO    ][15059] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-03-18 09:36:56,030 [salt.state       :300 ][INFO    ][15059] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-03-18 09:36:56,030 [salt.state       :1951][INFO    ][15059] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 09:36:56.030599 duration_in_ms=13.732
2019-03-18 09:36:56,033 [salt.state       :1780][INFO    ][15059] Running state [a2enmod headers] at time 09:36:56.033750
2019-03-18 09:36:56,034 [salt.state       :1813][INFO    ][15059] Executing state cmd.run for [a2enmod headers]
2019-03-18 09:36:56,034 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15059] Executing command 'a2enmod headers' in directory '/root'
2019-03-18 09:36:56,093 [salt.state       :300 ][INFO    ][15059] {'pid': 15078, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-03-18 09:36:56,094 [salt.state       :1951][INFO    ][15059] Completed state [a2enmod headers] at time 09:36:56.094406 duration_in_ms=60.656
2019-03-18 09:36:56,095 [salt.state       :1780][INFO    ][15059] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:36:56.095045
2019-03-18 09:36:56,095 [salt.state       :1813][INFO    ][15059] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-03-18 09:36:56,114 [salt.state       :300 ][INFO    ][15059] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-03-18 09:36:56,114 [salt.state       :1951][INFO    ][15059] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:36:56.114741 duration_in_ms=19.695
2019-03-18 09:36:56,117 [salt.state       :1780][INFO    ][15059] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:36:56.117690
2019-03-18 09:36:56,118 [salt.state       :1813][INFO    ][15059] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-03-18 09:36:56,172 [salt.state       :300 ][INFO    ][15059] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-03-18 09:36:56,172 [salt.state       :1951][INFO    ][15059] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:36:56.172248 duration_in_ms=54.558
2019-03-18 09:36:56,172 [salt.state       :1780][INFO    ][15059] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:36:56.172727
2019-03-18 09:36:56,172 [salt.state       :1813][INFO    ][15059] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-03-18 09:36:56,230 [salt.state       :300 ][INFO    ][15059] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-03-18 09:36:56,230 [salt.state       :1951][INFO    ][15059] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:36:56.230429 duration_in_ms=57.702
2019-03-18 09:36:56,230 [salt.state       :1780][INFO    ][15059] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:36:56.230928
2019-03-18 09:36:56,231 [salt.state       :1813][INFO    ][15059] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-03-18 09:36:56,306 [salt.state       :300 ][INFO    ][15059] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-03-18 09:36:56,306 [salt.state       :1951][INFO    ][15059] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:36:56.306519 duration_in_ms=75.59
2019-03-18 09:36:56,308 [salt.state       :1780][INFO    ][15059] Running state [/root/.pgpass] at time 09:36:56.306832
2019-03-18 09:36:56,308 [salt.state       :1813][INFO    ][15059] Executing state file.managed for [/root/.pgpass]
2019-03-18 09:36:56,352 [salt.state       :300 ][INFO    ][15059] File /root/.pgpass is in the correct state
2019-03-18 09:36:56,352 [salt.state       :1951][INFO    ][15059] Completed state [/root/.pgpass] at time 09:36:56.352283 duration_in_ms=45.451
2019-03-18 09:36:56,359 [salt.state       :1780][INFO    ][15059] Running state [maas-region syncdb --noinput] at time 09:36:56.359383
2019-03-18 09:36:56,359 [salt.state       :1813][INFO    ][15059] Executing state cmd.run for [maas-region syncdb --noinput]
2019-03-18 09:36:56,360 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15059] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-03-18 09:36:58,789 [salt.state       :300 ][INFO    ][15059] {'pid': 15091, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: piston3, metadataserver, sessions, contenttypes, sites, maasserver, auth\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-03-18 09:36:58,790 [salt.state       :1951][INFO    ][15059] Completed state [maas-region syncdb --noinput] at time 09:36:58.790231 duration_in_ms=2430.846
2019-03-18 09:36:58,791 [salt.state       :2022][WARNING ][15059] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-18 09:36:58,794 [salt.state       :1780][INFO    ][15059] Running state [maas-regiond] at time 09:36:58.794457
2019-03-18 09:36:58,795 [salt.state       :1813][INFO    ][15059] Executing state service.running for [maas-regiond]
2019-03-18 09:36:58,796 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15059] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-03-18 09:36:58,831 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15059] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-03-18 09:36:58,846 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15059] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-03-18 09:36:58,862 [salt.state       :300 ][INFO    ][15059] The service maas-regiond is already running
2019-03-18 09:36:58,865 [salt.state       :1951][INFO    ][15059] Completed state [maas-regiond] at time 09:36:58.865609 duration_in_ms=71.152
2019-03-18 09:36:58,869 [salt.state       :1780][INFO    ][15059] Running state [bind9] at time 09:36:58.869109
2019-03-18 09:36:58,869 [salt.state       :1813][INFO    ][15059] Executing state service.running for [bind9]
2019-03-18 09:36:58,870 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15059] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-03-18 09:36:58,890 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15059] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-03-18 09:36:58,908 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15059] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-03-18 09:36:58,930 [salt.state       :300 ][INFO    ][15059] The service bind9 is already running
2019-03-18 09:36:58,931 [salt.state       :1951][INFO    ][15059] Completed state [bind9] at time 09:36:58.931521 duration_in_ms=62.413
2019-03-18 09:36:58,934 [salt.state       :1780][INFO    ][15059] Running state [apache2] at time 09:36:58.934734
2019-03-18 09:36:58,937 [salt.state       :1813][INFO    ][15059] Executing state service.running for [apache2]
2019-03-18 09:36:58,938 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15059] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-03-18 09:36:58,956 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15059] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-03-18 09:36:58,976 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15059] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-03-18 09:36:59,003 [salt.state       :300 ][INFO    ][15059] The service apache2 is already running
2019-03-18 09:36:59,004 [salt.state       :1951][INFO    ][15059] Completed state [apache2] at time 09:36:59.004122 duration_in_ms=69.389
2019-03-18 09:36:59,005 [salt.state       :1780][INFO    ][15059] Running state [maasng.wait_for_http_code] at time 09:36:59.005371
2019-03-18 09:36:59,005 [salt.state       :1813][INFO    ][15059] Executing state module.run for [maasng.wait_for_http_code]
2019-03-18 09:36:59,006 [salt.utils.decorators:613 ][WARNING ][15059] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-18 09:36:59,110 [salt.state       :300 ][INFO    ][15059] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-03-18 09:36:59,110 [salt.state       :1951][INFO    ][15059] Completed state [maasng.wait_for_http_code] at time 09:36:59.110580 duration_in_ms=105.208
2019-03-18 09:36:59,112 [salt.state       :1780][INFO    ][15059] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:36:59.112038
2019-03-18 09:36:59,112 [salt.state       :1813][INFO    ][15059] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-03-18 09:36:59,113 [salt.state       :300 ][INFO    ][15059] /var/lib/maas/.setup_admin exists
2019-03-18 09:36:59,113 [salt.state       :1951][INFO    ][15059] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:36:59.113712 duration_in_ms=1.675
2019-03-18 09:36:59,116 [salt.state       :1780][INFO    ][15059] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:36:59.116831
2019-03-18 09:36:59,117 [salt.state       :1813][INFO    ][15059] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-18 09:36:59,117 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15059] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-18 09:37:00,793 [salt.state       :300 ][INFO    ][15059] {'pid': 15112, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-18 09:37:00,794 [salt.state       :1951][INFO    ][15059] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:37:00.794255 duration_in_ms=1677.423
2019-03-18 09:37:00,802 [salt.state       :1780][INFO    ][15059] Running state [maas_region_boot_source_resources_mirror] at time 09:37:00.802553
2019-03-18 09:37:00,803 [salt.state       :1813][INFO    ][15059] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-03-18 09:37:00,881 [salt.state       :300 ][INFO    ][15059] {'changes': {}}
2019-03-18 09:37:00,882 [salt.state       :1951][INFO    ][15059] Completed state [maas_region_boot_source_resources_mirror] at time 09:37:00.882099 duration_in_ms=79.547
2019-03-18 09:37:00,882 [salt.state       :1780][INFO    ][15059] Running state [maasng.boot_resources_import] at time 09:37:00.882741
2019-03-18 09:37:00,883 [salt.state       :1813][INFO    ][15059] Executing state module.run for [maasng.boot_resources_import]
2019-03-18 09:37:00,884 [salt.utils.decorators:613 ][WARNING ][15059] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-18 09:37:00,968 [salt.loaded.ext.module.maasng:1600][INFO    ][15059] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-03-18 09:37:06,020 [salt.loaded.ext.module.maasng:1600][INFO    ][15059] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-03-18 09:37:09,170 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318093709157854
2019-03-18 09:37:09,189 [salt.minion      :1432][INFO    ][15271] Starting a new job with PID 15271
2019-03-18 09:37:09,212 [salt.minion      :1711][INFO    ][15271] Returning information for job: 20190318093709157854
2019-03-18 09:37:11,111 [salt.state       :300 ][INFO    ][15059] {'ret': True}
2019-03-18 09:37:11,112 [salt.state       :1951][INFO    ][15059] Completed state [maasng.boot_resources_import] at time 09:37:11.112412 duration_in_ms=10229.67
2019-03-18 09:37:11,114 [salt.state       :1780][INFO    ][15059] Running state [maas_region_boot_sources_selection_xenial] at time 09:37:11.114037
2019-03-18 09:37:11,114 [salt.state       :1813][INFO    ][15059] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-03-18 09:37:11,276 [salt.state       :300 ][INFO    ][15059] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-03-18 09:37:11,276 [salt.state       :1951][INFO    ][15059] Completed state [maas_region_boot_sources_selection_xenial] at time 09:37:11.276309 duration_in_ms=162.272
2019-03-18 09:37:11,277 [salt.state       :1780][INFO    ][15059] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 09:37:11.277289
2019-03-18 09:37:11,277 [salt.state       :1813][INFO    ][15059] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-03-18 09:37:11,278 [salt.utils.decorators:613 ][WARNING ][15059] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-18 09:37:11,278 [salt.loaded.ext.module.maasng:1771][INFO    ][15059] boot-sources sync initiated for ALL Rack's
2019-03-18 09:37:12,217 [salt.state       :300 ][INFO    ][15059] {'ret': True}
2019-03-18 09:37:12,217 [salt.state       :1951][INFO    ][15059] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 09:37:12.217802 duration_in_ms=940.513
2019-03-18 09:37:12,220 [salt.state       :1780][INFO    ][15059] Running state [maas.process_maas_config] at time 09:37:12.220179
2019-03-18 09:37:12,220 [salt.state       :1813][INFO    ][15059] Executing state module.run for [maas.process_maas_config]
2019-03-18 09:37:12,221 [salt.utils.decorators:613 ][WARNING ][15059] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-18 09:37:12,221 [salt.loaded.ext.module.maas:92  ][INFO    ][15059] maasconfig name=enable_http_proxy value=True
2019-03-18 09:37:12,273 [salt.loaded.ext.module.maas:92  ][INFO    ][15059] maasconfig name=upstream_dns value=8.8.8.8
2019-03-18 09:37:12,325 [salt.loaded.ext.module.maas:92  ][INFO    ][15059] maasconfig name=commissioning_distro_series value=xenial
2019-03-18 09:37:12,376 [salt.loaded.ext.module.maas:92  ][INFO    ][15059] maasconfig name=default_osystem value=ubuntu
2019-03-18 09:37:13,426 [salt.loaded.ext.module.maas:92  ][INFO    ][15059] maasconfig name=active_discovery_interval value=600
2019-03-18 09:37:13,473 [salt.loaded.ext.module.maas:92  ][INFO    ][15059] maasconfig name=dnssec_validation value=no
2019-03-18 09:37:13,514 [salt.loaded.ext.module.maas:92  ][INFO    ][15059] maasconfig name=maas_name value=mas01
2019-03-18 09:37:13,556 [salt.loaded.ext.module.maas:92  ][INFO    ][15059] maasconfig name=network_discovery value=enabled
2019-03-18 09:37:13,644 [salt.loaded.ext.module.maas:92  ][INFO    ][15059] maasconfig name=enable_third_party_drivers value=True
2019-03-18 09:37:13,692 [salt.loaded.ext.module.maas:92  ][INFO    ][15059] maasconfig name=default_storage_layout value=lvm
2019-03-18 09:37:13,739 [salt.loaded.ext.module.maas:92  ][INFO    ][15059] maasconfig name=ntp_external_only value=True
2019-03-18 09:37:13,799 [salt.loaded.ext.module.maas:92  ][INFO    ][15059] maasconfig name=disk_erase_with_secure_erase value=False
2019-03-18 09:37:13,840 [salt.loaded.ext.module.maas:92  ][INFO    ][15059] maasconfig name=default_distro_series value=xenial
2019-03-18 09:37:13,890 [salt.loaded.ext.module.maas:92  ][INFO    ][15059] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-03-18 09:37:14,036 [salt.state       :300 ][INFO    ][15059] {'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-18 09:37:14,037 [salt.state       :1951][INFO    ][15059] Completed state [maas.process_maas_config] at time 09:37:14.037018 duration_in_ms=1816.839
2019-03-18 09:37:14,037 [salt.state       :1780][INFO    ][15059] Running state [pxe_admin] at time 09:37:14.037788
2019-03-18 09:37:14,038 [salt.state       :1813][INFO    ][15059] Executing state maasng.fabric_present for [pxe_admin]
2019-03-18 09:37:14,088 [salt.loaded.ext.module.maasng:945 ][INFO    ][15059] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'id': 2}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'id': 1}]
2019-03-18 09:37:14,135 [salt.loaded.ext.module.maasng:1008][WARNING ][15059] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-03-18 09:37:14,136 [salt.loaded.ext.module.maasng:1011][WARNING ][15059] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-03-18 09:37:14,202 [salt.state       :300 ][INFO    ][15059] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-03-18 09:37:14,203 [salt.state       :1951][INFO    ][15059] Completed state [pxe_admin] at time 09:37:14.203099 duration_in_ms=165.311
2019-03-18 09:37:14,203 [salt.state       :1780][INFO    ][15059] Running state [vlan 0] at time 09:37:14.203481
2019-03-18 09:37:14,203 [salt.state       :1813][INFO    ][15059] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-18 09:37:14,256 [salt.loaded.ext.module.maasng:945 ][INFO    ][15059] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ehdhrf', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-18 09:37:14,351 [salt.loaded.ext.module.maasng:945 ][INFO    ][15059] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ehdhrf', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-18 09:37:14,608 [salt.loaded.ext.module.maasng:945 ][INFO    ][15059] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'id': 2}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'id': 1}]
2019-03-18 09:37:14,695 [salt.state       :300 ][INFO    ][15059] {'new': 'Vlan untagged was updated'}
2019-03-18 09:37:14,696 [salt.state       :1951][INFO    ][15059] Completed state [vlan 0] at time 09:37:14.696260 duration_in_ms=492.779
2019-03-18 09:37:14,697 [salt.state       :1780][INFO    ][15059] Running state [192.168.11.0/24] at time 09:37:14.697417
2019-03-18 09:37:14,697 [salt.state       :1813][INFO    ][15059] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-03-18 09:37:14,870 [salt.loaded.ext.module.maasng:945 ][INFO    ][15059] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-03-18 09:37:14,873 [salt.loaded.ext.module.maasng:1235][WARNING ][15059] Ignoring parameter vlan:0
2019-03-18 09:37:14,949 [salt.state       :300 ][INFO    ][15059] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-03-18 09:37:14,950 [salt.state       :1951][INFO    ][15059] Completed state [192.168.11.0/24] at time 09:37:14.949994 duration_in_ms=252.576
2019-03-18 09:37:14,950 [salt.state       :1780][INFO    ][15059] Running state [maas_create_iprange_1] at time 09:37:14.950774
2019-03-18 09:37:14,951 [salt.state       :1813][INFO    ][15059] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-03-18 09:37:15,006 [salt.state       :300 ][INFO    ][15059] Iprange maas_create_iprange_1 already exist.
2019-03-18 09:37:15,006 [salt.state       :1951][INFO    ][15059] Completed state [maas_create_iprange_1] at time 09:37:15.006255 duration_in_ms=55.48
2019-03-18 09:37:15,006 [salt.state       :1780][INFO    ][15059] Running state [vlan 0] at time 09:37:15.006523
2019-03-18 09:37:15,006 [salt.state       :1813][INFO    ][15059] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-18 09:37:15,047 [salt.loaded.ext.module.maasng:945 ][INFO    ][15059] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'ehdhrf', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-18 09:37:15,137 [salt.loaded.ext.module.maasng:945 ][INFO    ][15059] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-18 09:37:15,333 [salt.loaded.ext.module.maasng:945 ][INFO    ][15059] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'id': 2}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ehdhrf', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'id': 1}]
2019-03-18 09:37:15,439 [salt.state       :300 ][INFO    ][15059] {'new': 'Vlan untagged was updated'}
2019-03-18 09:37:15,440 [salt.state       :1951][INFO    ][15059] Completed state [vlan 0] at time 09:37:15.440018 duration_in_ms=433.494
2019-03-18 09:37:15,440 [salt.state       :1780][INFO    ][15059] Running state [opnfv] at time 09:37:15.440758
2019-03-18 09:37:15,441 [salt.state       :1813][INFO    ][15059] Executing state maasng.sshkey_present for [opnfv]
2019-03-18 09:37:15,489 [salt.loaded.ext.module.maasng:1903][INFO    ][15059] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H', u'keysource': u''}]
2019-03-18 09:37:15,490 [salt.state       :300 ][INFO    ][15059] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H already exist for user opnfv.
2019-03-18 09:37:15,490 [salt.state       :1951][INFO    ][15059] Completed state [opnfv] at time 09:37:15.490259 duration_in_ms=49.499
2019-03-18 09:37:15,493 [salt.minion      :1711][INFO    ][15059] Returning information for job: 20190318093654045824
2019-03-18 09:37:16,068 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command state.apply with jid 20190318093716057860
2019-03-18 09:37:16,086 [salt.minion      :1432][INFO    ][15502] Starting a new job with PID 15502
2019-03-18 09:37:21,805 [salt.state       :915 ][INFO    ][15502] Loading fresh modules for state activity
2019-03-18 09:37:21,893 [salt.state       :1780][INFO    ][15502] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:37:21.893737
2019-03-18 09:37:21,894 [salt.state       :1813][INFO    ][15502] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-18 09:37:21,896 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15502] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-18 09:37:23,590 [salt.state       :300 ][INFO    ][15502] {'pid': 15528, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-18 09:37:23,591 [salt.state       :1951][INFO    ][15502] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:37:23.591480 duration_in_ms=1697.743
2019-03-18 09:37:23,592 [salt.state       :1780][INFO    ][15502] Running state [maas.process_machines] at time 09:37:23.592846
2019-03-18 09:37:23,593 [salt.state       :1813][INFO    ][15502] Executing state module.run for [maas.process_machines]
2019-03-18 09:37:23,593 [salt.utils.decorators:613 ][WARNING ][15502] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-18 09:37:24,141 [salt.loaded.ext.module.maas:412 ][WARNING ][15502] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-18 09:37:24,142 [salt.loaded.ext.module.maas:92  ][INFO    ][15502] machine hostname=cmp002 power_type=ipmi mac_addresses=['9c:b6:54:8a:10:18'] power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 system_id=cex7hp architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-18 09:37:25,261 [salt.loaded.ext.module.maas:412 ][WARNING ][15502] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-18 09:37:25,264 [salt.loaded.ext.module.maas:92  ][INFO    ][15502] machine hostname=cmp001 power_type=ipmi mac_addresses=['9c:b6:54:8a:95:a0'] power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 system_id=peam3d architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-18 09:37:26,347 [salt.loaded.ext.module.maas:412 ][WARNING ][15502] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-18 09:37:26,347 [salt.loaded.ext.module.maas:92  ][INFO    ][15502] machine hostname=kvm01 power_type=ipmi mac_addresses=['14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=hh6xaf architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-18 09:37:27,438 [salt.loaded.ext.module.maas:412 ][WARNING ][15502] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-18 09:37:27,440 [salt.loaded.ext.module.maas:92  ][INFO    ][15502] machine hostname=kvm03 power_type=ipmi mac_addresses=['14:58:d0:54:7a:28'] power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 system_id=pachmg architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-18 09:37:28,548 [salt.loaded.ext.module.maas:412 ][WARNING ][15502] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-18 09:37:28,549 [salt.loaded.ext.module.maas:92  ][INFO    ][15502] machine hostname=kvm02 power_type=ipmi mac_addresses=['14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=ygsm7e architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-18 09:37:29,620 [salt.state       :300 ][INFO    ][15502] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-18 09:37:29,621 [salt.state       :1951][INFO    ][15502] Completed state [maas.process_machines] at time 09:37:29.621032 duration_in_ms=6028.184
2019-03-18 09:37:29,624 [salt.minion      :1711][INFO    ][15502] Returning information for job: 20190318093716057860
2019-03-18 09:38:02,581 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command state.apply with jid 20190318093802573434
2019-03-18 09:38:02,601 [salt.minion      :1432][INFO    ][15819] Starting a new job with PID 15819
2019-03-18 09:38:08,227 [salt.state       :915 ][INFO    ][15819] Loading fresh modules for state activity
2019-03-18 09:38:08,316 [salt.state       :1780][INFO    ][15819] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:38:08.316477
2019-03-18 09:38:08,316 [salt.state       :1813][INFO    ][15819] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-18 09:38:08,318 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15819] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-18 09:38:09,985 [salt.state       :300 ][INFO    ][15819] {'pid': 15829, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-18 09:38:09,986 [salt.state       :1951][INFO    ][15819] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:38:09.986209 duration_in_ms=1669.73
2019-03-18 09:38:09,990 [salt.state       :1780][INFO    ][15819] Running state [maas.wait_for_machine_status] at time 09:38:09.990430
2019-03-18 09:38:09,991 [salt.state       :1813][INFO    ][15819] Executing state module.run for [maas.wait_for_machine_status]
2019-03-18 09:38:09,991 [salt.utils.decorators:613 ][WARNING ][15819] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-18 09:38:12,725 [salt.state       :300 ][INFO    ][15819] {'ret': True}
2019-03-18 09:38:12,725 [salt.state       :1951][INFO    ][15819] Completed state [maas.wait_for_machine_status] at time 09:38:12.725503 duration_in_ms=2735.072
2019-03-18 09:38:12,727 [salt.minion      :1711][INFO    ][15819] Returning information for job: 20190318093802573434
2019-03-18 09:38:13,259 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command state.apply with jid 20190318093813249557
2019-03-18 09:38:13,279 [salt.minion      :1432][INFO    ][15842] Starting a new job with PID 15842
2019-03-18 09:38:13,981 [salt.state       :915 ][INFO    ][15842] Loading fresh modules for state activity
2019-03-18 09:38:14,116 [salt.state       :1780][INFO    ][15842] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:38:14.116502
2019-03-18 09:38:14,116 [salt.state       :1813][INFO    ][15842] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-18 09:38:14,118 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15842] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-18 09:38:15,805 [salt.state       :300 ][INFO    ][15842] {'pid': 15849, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-18 09:38:15,806 [salt.state       :1951][INFO    ][15842] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:38:15.806587 duration_in_ms=1690.083
2019-03-18 09:38:15,811 [salt.state       :1780][INFO    ][15842] Running state [maas_machines_storage_cmp002_lvm] at time 09:38:15.811370
2019-03-18 09:38:15,812 [salt.state       :1813][INFO    ][15842] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-18 09:38:16,365 [salt.state       :300 ][INFO    ][15842] Machine cmp002 is not in Ready state.
2019-03-18 09:38:16,366 [salt.state       :1951][INFO    ][15842] Completed state [maas_machines_storage_cmp002_lvm] at time 09:38:16.366462 duration_in_ms=555.089
2019-03-18 09:38:16,367 [salt.state       :1780][INFO    ][15842] Running state [maas_machines_storage_cmp001_lvm] at time 09:38:16.367080
2019-03-18 09:38:16,367 [salt.state       :1813][INFO    ][15842] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-18 09:38:16,900 [salt.state       :300 ][INFO    ][15842] Machine cmp001 is not in Ready state.
2019-03-18 09:38:16,901 [salt.state       :1951][INFO    ][15842] Completed state [maas_machines_storage_cmp001_lvm] at time 09:38:16.900995 duration_in_ms=533.914
2019-03-18 09:38:16,903 [salt.minion      :1711][INFO    ][15842] Returning information for job: 20190318093813249557
2019-03-18 09:38:17,434 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command state.apply with jid 20190318093817422735
2019-03-18 09:38:17,454 [salt.minion      :1432][INFO    ][15863] Starting a new job with PID 15863
2019-03-18 09:38:18,146 [salt.state       :915 ][INFO    ][15863] Loading fresh modules for state activity
2019-03-18 09:38:18,235 [salt.state       :1780][INFO    ][15863] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:38:18.235052
2019-03-18 09:38:18,235 [salt.state       :1813][INFO    ][15863] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-18 09:38:18,237 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15863] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-18 09:38:19,903 [salt.state       :300 ][INFO    ][15863] {'pid': 15871, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-18 09:38:19,904 [salt.state       :1951][INFO    ][15863] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:38:19.904247 duration_in_ms=1669.196
2019-03-18 09:38:19,905 [salt.state       :1780][INFO    ][15863] Running state [maas.deploy_machines] at time 09:38:19.905635
2019-03-18 09:38:19,905 [salt.state       :1813][INFO    ][15863] Executing state module.run for [maas.deploy_machines]
2019-03-18 09:38:19,906 [salt.utils.decorators:613 ][WARNING ][15863] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-18 09:38:20,450 [salt.loaded.ext.module.maas:684 ][INFO    ][15863] deploymachines hwe_kernel=ga-16.04 system_id=ygsm7e distro_series=xenial
2019-03-18 09:38:22,682 [salt.state       :300 ][INFO    ][15863] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-03-18 09:38:22,684 [salt.state       :1951][INFO    ][15863] Completed state [maas.deploy_machines] at time 09:38:22.684427 duration_in_ms=2778.79
2019-03-18 09:38:22,687 [salt.minion      :1711][INFO    ][15863] Returning information for job: 20190318093817422735
2019-03-18 09:38:23,225 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command state.apply with jid 20190318093823212838
2019-03-18 09:38:23,243 [salt.minion      :1432][INFO    ][15931] Starting a new job with PID 15931
2019-03-18 09:38:23,951 [salt.state       :915 ][INFO    ][15931] Loading fresh modules for state activity
2019-03-18 09:38:24,037 [salt.state       :1780][INFO    ][15931] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:38:24.037468
2019-03-18 09:38:24,037 [salt.state       :1813][INFO    ][15931] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-18 09:38:24,040 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15931] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-18 09:38:25,707 [salt.state       :300 ][INFO    ][15931] {'pid': 15938, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-18 09:38:25,708 [salt.state       :1951][INFO    ][15931] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:38:25.708393 duration_in_ms=1670.924
2019-03-18 09:38:25,711 [salt.state       :1780][INFO    ][15931] Running state [maas.wait_for_machine_status] at time 09:38:25.711722
2019-03-18 09:38:25,712 [salt.state       :1813][INFO    ][15931] Executing state module.run for [maas.wait_for_machine_status]
2019-03-18 09:38:25,712 [salt.utils.decorators:613 ][WARNING ][15931] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-18 09:38:28,413 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.30969s left)
2019-03-18 09:38:38,340 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318093838325633
2019-03-18 09:38:38,362 [salt.minion      :1432][INFO    ][15966] Starting a new job with PID 15966
2019-03-18 09:38:38,388 [salt.minion      :1711][INFO    ][15966] Returning information for job: 20190318093838325633
2019-03-18 09:39:01,115 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.60739613s left)
2019-03-18 09:39:08,539 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318093908486210
2019-03-18 09:39:08,558 [salt.minion      :1432][INFO    ][16041] Starting a new job with PID 16041
2019-03-18 09:39:08,589 [salt.minion      :1711][INFO    ][16041] Returning information for job: 20190318093908486210
2019-03-18 09:39:33,898 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.82444906s left)
2019-03-18 09:39:38,533 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318093938518443
2019-03-18 09:39:38,555 [salt.minion      :1432][INFO    ][16070] Starting a new job with PID 16070
2019-03-18 09:39:38,582 [salt.minion      :1711][INFO    ][16070] Returning information for job: 20190318093938518443
2019-03-18 09:40:06,674 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.04919696s left)
2019-03-18 09:40:08,723 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318094008715179
2019-03-18 09:40:08,745 [salt.minion      :1432][INFO    ][16142] Starting a new job with PID 16142
2019-03-18 09:40:08,769 [salt.minion      :1711][INFO    ][16142] Returning information for job: 20190318094008715179
2019-03-18 09:40:38,910 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318094038902499
2019-03-18 09:40:38,928 [salt.minion      :1432][INFO    ][16177] Starting a new job with PID 16177
2019-03-18 09:40:38,954 [salt.minion      :1711][INFO    ][16177] Returning information for job: 20190318094038902499
2019-03-18 09:40:39,421 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.30147195s left)
2019-03-18 09:41:09,114 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318094109098027
2019-03-18 09:41:09,139 [salt.minion      :1432][INFO    ][16262] Starting a new job with PID 16262
2019-03-18 09:41:09,163 [salt.minion      :1711][INFO    ][16262] Returning information for job: 20190318094109098027
2019-03-18 09:41:12,072 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.65071416s left)
2019-03-18 09:41:39,317 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318094139298343
2019-03-18 09:41:39,336 [salt.minion      :1432][INFO    ][16291] Starting a new job with PID 16291
2019-03-18 09:41:39,359 [salt.minion      :1711][INFO    ][16291] Returning information for job: 20190318094139298343
2019-03-18 09:41:44,730 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.99248314s left)
2019-03-18 09:42:09,520 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318094209504609
2019-03-18 09:42:09,545 [salt.minion      :1432][INFO    ][16367] Starting a new job with PID 16367
2019-03-18 09:42:09,569 [salt.minion      :1711][INFO    ][16367] Returning information for job: 20190318094209504609
2019-03-18 09:42:17,592 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2018.13116002s left)
2019-03-18 09:42:39,716 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318094239708765
2019-03-18 09:42:39,730 [salt.minion      :1432][INFO    ][16421] Starting a new job with PID 16421
2019-03-18 09:42:39,752 [salt.minion      :1711][INFO    ][16421] Returning information for job: 20190318094239708765
2019-03-18 09:42:50,489 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1985.23394895s left)
2019-03-18 09:43:09,912 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318094309897409
2019-03-18 09:43:09,932 [salt.minion      :1432][INFO    ][16524] Starting a new job with PID 16524
2019-03-18 09:43:09,957 [salt.minion      :1711][INFO    ][16524] Returning information for job: 20190318094309897409
2019-03-18 09:43:23,189 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1952.53346109s left)
2019-03-18 09:43:40,008 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318094339992718
2019-03-18 09:43:40,029 [salt.minion      :1432][INFO    ][16559] Starting a new job with PID 16559
2019-03-18 09:43:40,052 [salt.minion      :1711][INFO    ][16559] Returning information for job: 20190318094339992718
2019-03-18 09:43:55,895 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1919.82755399s left)
2019-03-18 09:44:10,058 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318094410051837
2019-03-18 09:44:10,079 [salt.minion      :1432][INFO    ][16786] Starting a new job with PID 16786
2019-03-18 09:44:10,103 [salt.minion      :1711][INFO    ][16786] Returning information for job: 20190318094410051837
2019-03-18 09:44:28,834 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1886.88881302s left)
2019-03-18 09:44:40,265 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318094440252854
2019-03-18 09:44:40,283 [salt.minion      :1432][INFO    ][16817] Starting a new job with PID 16817
2019-03-18 09:44:40,305 [salt.minion      :1711][INFO    ][16817] Returning information for job: 20190318094440252854
2019-03-18 09:45:01,704 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1854.01875806s left)
2019-03-18 09:45:10,480 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318094510468366
2019-03-18 09:45:10,505 [salt.minion      :1432][INFO    ][16947] Starting a new job with PID 16947
2019-03-18 09:45:10,526 [salt.minion      :1711][INFO    ][16947] Returning information for job: 20190318094510468366
2019-03-18 09:45:34,440 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1821.2828691s left)
2019-03-18 09:45:40,685 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318094540676599
2019-03-18 09:45:40,711 [salt.minion      :1432][INFO    ][16978] Starting a new job with PID 16978
2019-03-18 09:45:40,732 [salt.minion      :1711][INFO    ][16978] Returning information for job: 20190318094540676599
2019-03-18 09:46:07,199 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1788.52357197s left)
2019-03-18 09:46:10,712 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318094610698332
2019-03-18 09:46:10,740 [salt.minion      :1432][INFO    ][17117] Starting a new job with PID 17117
2019-03-18 09:46:10,762 [salt.minion      :1711][INFO    ][17117] Returning information for job: 20190318094610698332
2019-03-18 09:46:40,077 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1755.64563394s left)
2019-03-18 09:46:40,734 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318094640720445
2019-03-18 09:46:40,757 [salt.minion      :1432][INFO    ][17150] Starting a new job with PID 17150
2019-03-18 09:46:40,778 [salt.minion      :1711][INFO    ][17150] Returning information for job: 20190318094640720445
2019-03-18 09:47:10,745 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318094710736235
2019-03-18 09:47:10,762 [salt.minion      :1432][INFO    ][17220] Starting a new job with PID 17220
2019-03-18 09:47:10,785 [salt.minion      :1711][INFO    ][17220] Returning information for job: 20190318094710736235
2019-03-18 09:47:12,847 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1722.87607002s left)
2019-03-18 09:47:40,962 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318094740949909
2019-03-18 09:47:40,984 [salt.minion      :1432][INFO    ][17249] Starting a new job with PID 17249
2019-03-18 09:47:41,011 [salt.minion      :1711][INFO    ][17249] Returning information for job: 20190318094740949909
2019-03-18 09:47:45,714 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1690.00863099s left)
2019-03-18 09:48:10,988 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318094810974094
2019-03-18 09:48:11,010 [salt.minion      :1432][INFO    ][17324] Starting a new job with PID 17324
2019-03-18 09:48:11,039 [salt.minion      :1711][INFO    ][17324] Returning information for job: 20190318094810974094
2019-03-18 09:48:18,566 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1657.15691805s left)
2019-03-18 09:48:41,021 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318094841007033
2019-03-18 09:48:41,038 [salt.minion      :1432][INFO    ][17353] Starting a new job with PID 17353
2019-03-18 09:48:41,068 [salt.minion      :1711][INFO    ][17353] Returning information for job: 20190318094841007033
2019-03-18 09:48:51,417 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1624.3058331s left)
2019-03-18 09:49:11,063 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318094911048534
2019-03-18 09:49:11,082 [salt.minion      :1432][INFO    ][17426] Starting a new job with PID 17426
2019-03-18 09:49:11,107 [salt.minion      :1711][INFO    ][17426] Returning information for job: 20190318094911048534
2019-03-18 09:49:24,182 [salt.loaded.ext.module.maas:1023][INFO    ][15931] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1591.54044914s left)
2019-03-18 09:49:41,220 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command saltutil.find_job with jid 20190318094941204606
2019-03-18 09:49:41,234 [salt.minion      :1432][INFO    ][17493] Starting a new job with PID 17493
2019-03-18 09:49:41,266 [salt.minion      :1711][INFO    ][17493] Returning information for job: 20190318094941204606
2019-03-18 09:49:57,110 [salt.state       :300 ][INFO    ][15931] {'ret': True}
2019-03-18 09:49:57,112 [salt.state       :1951][INFO    ][15931] Completed state [maas.wait_for_machine_status] at time 09:49:57.112882 duration_in_ms=691401.159
2019-03-18 09:49:57,115 [salt.minion      :1711][INFO    ][15931] Returning information for job: 20190318093823212838
2019-03-18 09:51:49,216 [salt.utils.schedule:1377][INFO    ][6151] Running scheduled job: __mine_interval
2019-03-18 10:51:49,217 [salt.utils.schedule:1377][INFO    ][6151] Running scheduled job: __mine_interval
2019-03-18 11:07:28,531 [salt.minion      :1308][INFO    ][6151] User sudo_ubuntu Executing command cp.push_dir with jid 20190318110728520621
2019-03-18 11:07:28,549 [salt.minion      :1432][INFO    ][25779] Starting a new job with PID 25779
