2019-04-25 09:44:29,994 [salt.utils.decorators:613 ][WARNING ][2130] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 09:44:30,852 [salt.utils.decorators:613 ][WARNING ][2130] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 09:44:33,823 [salt.loaded.int.states.file:2298][WARNING ][2512] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-25 09:45:01,486 [salt.state       :2022][WARNING ][2980] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-25 09:45:04,534 [salt.utils.decorators:613 ][WARNING ][2980] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 09:45:16,832 [salt.utils.decorators:613 ][WARNING ][2980] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 09:45:38,662 [salt.utils.decorators:613 ][WARNING ][2980] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 09:45:39,517 [salt.utils.decorators:613 ][WARNING ][2980] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 09:45:41,900 [salt.loaded.ext.module.maasng:1008][WARNING ][2980] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-04-25 09:45:41,900 [salt.loaded.ext.module.maasng:1011][WARNING ][2980] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-04-25 09:45:42,468 [salt.loaded.ext.module.maasng:1235][WARNING ][2980] Ignoring parameter vlan:0
2019-04-25 09:45:52,207 [salt.utils.decorators:613 ][WARNING ][5226] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 09:45:52,272 [salt.loaded.ext.module.maas:412 ][WARNING ][5226] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-25 09:45:53,432 [salt.loaded.ext.module.maas:412 ][WARNING ][5226] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-25 09:45:54,547 [salt.loaded.ext.module.maas:412 ][WARNING ][5226] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-25 09:45:55,610 [salt.loaded.ext.module.maas:412 ][WARNING ][5226] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-25 09:45:56,749 [salt.loaded.ext.module.maas:412 ][WARNING ][5226] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-25 09:46:00,127 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5776] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-25 09:46:00,146 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5776] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-25 09:46:00,200 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-04-25 09:46:01,175 [salt.cli.daemons :293 ][INFO    ][5831] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-04-25 09:46:01,289 [salt.cli.daemons :82  ][INFO    ][5831] Starting up the Salt Minion
2019-04-25 09:46:01,289 [salt.utils.event :1017][INFO    ][5831] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-04-25 09:46:02,393 [salt.minion      :976 ][INFO    ][5831] Creating minion process manager
2019-04-25 09:46:04,271 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5831] Executing command ['date', '+%z'] in directory '/root'
2019-04-25 09:46:04,297 [salt.utils.schedule:568 ][INFO    ][5831] Updating job settings for scheduled job: __mine_interval
2019-04-25 09:46:04,300 [salt.minion      :1108][INFO    ][5831] Added mine.update to scheduler
2019-04-25 09:46:04,305 [salt.minion      :1975][INFO    ][5831] Minion is starting as user 'root'
2019-04-25 09:46:04,321 [salt.minion      :2336][INFO    ][5831] Minion is ready to receive requests!
2019-04-25 09:46:39,244 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command state.apply with jid 20190425094639235750
2019-04-25 09:46:39,262 [salt.minion      :1432][INFO    ][5994] Starting a new job with PID 5994
2019-04-25 09:46:45,170 [salt.state       :915 ][INFO    ][5994] Loading fresh modules for state activity
2019-04-25 09:46:45,225 [salt.fileclient  :1219][INFO    ][5994] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-25 09:46:45,270 [salt.state       :1780][INFO    ][5994] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:46:45.270321
2019-04-25 09:46:45,270 [salt.state       :1813][INFO    ][5994] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-25 09:46:45,272 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5994] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-25 09:46:46,968 [salt.state       :300 ][INFO    ][5994] {'pid': 6002, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-25 09:46:46,968 [salt.state       :1951][INFO    ][5994] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:46:46.968524 duration_in_ms=1698.204
2019-04-25 09:46:46,969 [salt.state       :1780][INFO    ][5994] Running state [maas.wait_for_machine_status] at time 09:46:46.969862
2019-04-25 09:46:46,970 [salt.state       :1813][INFO    ][5994] Executing state module.run for [maas.wait_for_machine_status]
2019-04-25 09:46:46,970 [salt.utils.decorators:613 ][WARNING ][5994] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 09:46:48,117 [salt.loaded.ext.module.maas:1023][INFO    ][5994] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.86201811s left)
2019-04-25 09:46:54,349 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425094654338716
2019-04-25 09:46:54,370 [salt.minion      :1432][INFO    ][6055] Starting a new job with PID 6055
2019-04-25 09:46:54,396 [salt.minion      :1711][INFO    ][6055] Returning information for job: 20190425094654338716
2019-04-25 09:47:18,904 [salt.loaded.ext.module.maas:1023][INFO    ][5994] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.07499504s left)
2019-04-25 09:47:24,573 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425094724526644
2019-04-25 09:47:24,590 [salt.minion      :1432][INFO    ][6124] Starting a new job with PID 6124
2019-04-25 09:47:24,611 [salt.minion      :1711][INFO    ][6124] Returning information for job: 20190425094724526644
2019-04-25 09:47:49,668 [salt.loaded.ext.module.maas:1023][INFO    ][5994] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.31138301s left)
2019-04-25 09:47:54,634 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425094754626681
2019-04-25 09:47:54,653 [salt.minion      :1432][INFO    ][6157] Starting a new job with PID 6157
2019-04-25 09:47:54,675 [salt.minion      :1711][INFO    ][6157] Returning information for job: 20190425094754626681
2019-04-25 09:48:20,584 [salt.loaded.ext.module.maas:1023][INFO    ][5994] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.39543009s left)
2019-04-25 09:48:24,685 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425094824667611
2019-04-25 09:48:24,705 [salt.minion      :1432][INFO    ][6250] Starting a new job with PID 6250
2019-04-25 09:48:24,727 [salt.minion      :1711][INFO    ][6250] Returning information for job: 20190425094824667611
2019-04-25 09:48:51,666 [salt.loaded.ext.module.maas:1023][INFO    ][5994] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.31309891s left)
2019-04-25 09:48:54,740 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425094854727339
2019-04-25 09:48:54,763 [salt.minion      :1432][INFO    ][6343] Starting a new job with PID 6343
2019-04-25 09:48:54,782 [salt.minion      :1711][INFO    ][6343] Returning information for job: 20190425094854727339
2019-04-25 09:49:22,901 [salt.loaded.ext.module.maas:1023][INFO    ][5994] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.07868791s left)
2019-04-25 09:49:24,836 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425094924822690
2019-04-25 09:49:24,853 [salt.minion      :1432][INFO    ][6553] Starting a new job with PID 6553
2019-04-25 09:49:24,875 [salt.minion      :1711][INFO    ][6553] Returning information for job: 20190425094924822690
2019-04-25 09:49:54,174 [salt.loaded.ext.module.maas:1023][INFO    ][5994] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.80580902s left)
2019-04-25 09:49:54,912 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425094954902044
2019-04-25 09:49:54,933 [salt.minion      :1432][INFO    ][6683] Starting a new job with PID 6683
2019-04-25 09:49:54,954 [salt.minion      :1711][INFO    ][6683] Returning information for job: 20190425094954902044
2019-04-25 09:50:24,988 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425095024978199
2019-04-25 09:50:25,005 [salt.minion      :1432][INFO    ][6959] Starting a new job with PID 6959
2019-04-25 09:50:25,026 [salt.minion      :1711][INFO    ][6959] Returning information for job: 20190425095024978199
2019-04-25 09:50:25,857 [salt.loaded.ext.module.maas:1023][INFO    ][5994] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.1221261s left)
2019-04-25 09:50:55,090 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425095055079038
2019-04-25 09:50:55,112 [salt.minion      :1432][INFO    ][7149] Starting a new job with PID 7149
2019-04-25 09:50:55,138 [salt.minion      :1711][INFO    ][7149] Returning information for job: 20190425095055079038
2019-04-25 09:50:58,012 [salt.loaded.ext.module.maas:1023][INFO    ][5994] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1248.96739793s left)
2019-04-25 09:51:25,250 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425095125242732
2019-04-25 09:51:25,265 [salt.minion      :1432][INFO    ][7570] Starting a new job with PID 7570
2019-04-25 09:51:25,283 [salt.minion      :1711][INFO    ][7570] Returning information for job: 20190425095125242732
2019-04-25 09:51:30,031 [salt.loaded.ext.module.maas:1023][INFO    ][5994] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1216.94839501s left)
2019-04-25 09:51:55,318 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425095155310138
2019-04-25 09:51:55,333 [salt.minion      :1432][INFO    ][7708] Starting a new job with PID 7708
2019-04-25 09:51:55,355 [salt.minion      :1711][INFO    ][7708] Returning information for job: 20190425095155310138
2019-04-25 09:52:02,772 [salt.loaded.ext.module.maas:1023][INFO    ][5994] Waiting status:Ready|Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.20720005s left)
2019-04-25 09:52:25,435 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425095225420338
2019-04-25 09:52:25,457 [salt.minion      :1432][INFO    ][7876] Starting a new job with PID 7876
2019-04-25 09:52:25,477 [salt.minion      :1711][INFO    ][7876] Returning information for job: 20190425095225420338
2019-04-25 09:52:35,361 [salt.state       :300 ][INFO    ][5994] {'ret': True}
2019-04-25 09:52:35,362 [salt.state       :1951][INFO    ][5994] Completed state [maas.wait_for_machine_status] at time 09:52:35.362266 duration_in_ms=348392.403
2019-04-25 09:52:35,366 [salt.minion      :1711][INFO    ][5994] Returning information for job: 20190425094639235750
2019-04-25 09:52:35,921 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command state.apply with jid 20190425095235904597
2019-04-25 09:52:35,939 [salt.minion      :1432][INFO    ][7919] Starting a new job with PID 7919
2019-04-25 09:52:41,785 [salt.state       :915 ][INFO    ][7919] Loading fresh modules for state activity
2019-04-25 09:52:41,841 [salt.fileclient  :1219][INFO    ][7919] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-25 09:52:41,938 [salt.state       :1780][INFO    ][7919] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:52:41.938774
2019-04-25 09:52:41,939 [salt.state       :1813][INFO    ][7919] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-25 09:52:41,940 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7919] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-25 09:52:43,756 [salt.state       :300 ][INFO    ][7919] {'pid': 7928, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-25 09:52:43,756 [salt.state       :1951][INFO    ][7919] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:52:43.756481 duration_in_ms=1817.707
2019-04-25 09:52:43,758 [salt.state       :1780][INFO    ][7919] Running state [maas_machines_storage_cmp002_lvm] at time 09:52:43.758310
2019-04-25 09:52:43,758 [salt.state       :1813][INFO    ][7919] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-25 09:52:44,876 [salt.loaded.ext.module.maasng:610 ][INFO    ][7919] emsqep
2019-04-25 09:52:44,877 [salt.loaded.ext.module.maasng:626 ][INFO    ][7919] sda
2019-04-25 09:52:45,439 [salt.loaded.ext.module.maasng:361 ][INFO    ][7919] emsqep
2019-04-25 09:52:45,532 [salt.loaded.ext.module.maasng:367 ][INFO    ][7919] [{u'size': 800109715456, u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/emsqep/blockdevices/1/', u'used_size': 800106479616, u'id': 1, u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'emsqep', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'09b0a936-02e0-40a9-9692-7aded3d7a563', u'resource_uri': u'/MAAS/api/2.0/nodes/emsqep/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'emsqep', u'filesystem': {u'uuid': u'26da17e0-091f-4f51-9578-a42317559072', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 800101236736}]}, {u'size': 800097042432, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/emsqep/blockdevices/3/', u'used_size': 800097042432, u'id': 3, u'tags': [], u'uuid': u'437ec9d5-f187-4ee9-975c-db9d666ac512', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'emsqep', u'partition_table_type': None, u'filesystem': {u'uuid': u'a0f3d96a-c3cb-4be5-8318-5d19e48d9e75', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'partitions': []}]
2019-04-25 09:52:45,533 [salt.loaded.ext.module.maasng:632 ][INFO    ][7919] vgroot
2019-04-25 09:52:45,533 [salt.loaded.ext.module.maasng:635 ][INFO    ][7919] lvroot
2019-04-25 09:52:45,533 [salt.loaded.ext.module.maasng:639 ][INFO    ][7919] 107374182400
2019-04-25 09:52:46,124 [salt.loaded.ext.module.maasng:645 ][INFO    ][7919] {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'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {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'cqxxgq', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'cqxxgq', 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'mode': u'dhcp', u'id': 17}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'cqxxgq', 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'system_id': u'emsqep', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/emsqep/interfaces/4/'}, u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/emsqep/', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'emsqep', 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'resource_uri': u'/MAAS/api/2.0/nodes/emsqep/blockdevices/11/', u'uuid': u'668690d8-3cf3-4f3c-8fae-e7e37619cd7c', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'4c28cc2e-3d74-46e2-b662-a24cd3dd1465', u'fstype': u'ext4', u'mount_options': None}, u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'emsqep', u'block_size': 4096, u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'model': None, u'partitions': [], u'type': u'virtual', u'id': 11, u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/emsqep/blockdevices/1/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'emsqep', u'block_size': 4096, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'used_for': u'MBR partitioned with 1 partition', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'ae9019ff-4605-4174-be1a-fdde21982a27', u'resource_uri': u'/MAAS/api/2.0/nodes/emsqep/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'emsqep', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'8eb70e11-f1d7-42d4-9f71-ec4c2fae29ab', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/emsqep/blockdevices/11/', u'available_size': 0, u'uuid': u'668690d8-3cf3-4f3c-8fae-e7e37619cd7c', u'tags': [], u'used_size': 107374182400, u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'emsqep', u'block_size': 4096, u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'4c28cc2e-3d74-46e2-b662-a24cd3dd1465', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 11, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'emsqep', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'resource_uri': u'/MAAS/api/2.0/nodes/emsqep/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'emsqep', u'block_size': 4096, u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'used_for': u'MBR partitioned with 1 partition', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'ae9019ff-4605-4174-be1a-fdde21982a27', u'resource_uri': u'/MAAS/api/2.0/nodes/emsqep/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'emsqep', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'8eb70e11-f1d7-42d4-9f71-ec4c2fae29ab', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, u'interface_set': [{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'cqxxgq', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'cqxxgq', 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'mode': u'dhcp', u'id': 17}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'cqxxgq', 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'system_id': u'emsqep', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/emsqep/interfaces/4/'}, {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'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 18}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:48', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'emsqep', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/emsqep/interfaces/14/'}, {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'cqxxgq', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'cqxxgq', 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'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:1c', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'cqxxgq', 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'system_id': u'emsqep', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/emsqep/interfaces/15/'}, {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'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:49', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'emsqep', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/emsqep/interfaces/12/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:50', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'emsqep', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/emsqep/interfaces/11/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:51', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'emsqep', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/emsqep/interfaces/13/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/emsqep/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'emsqep', u'block_size': 4096, u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'used_for': u'MBR partitioned with 1 partition', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'ae9019ff-4605-4174-be1a-fdde21982a27', u'resource_uri': u'/MAAS/api/2.0/nodes/emsqep/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'emsqep', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'8eb70e11-f1d7-42d4-9f71-ec4c2fae29ab', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 2, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-04-25 09:52:46,127 [salt.state       :300 ][INFO    ][7919] {'new': {'storage_layout': 'lvm'}}
2019-04-25 09:52:46,128 [salt.state       :1951][INFO    ][7919] Completed state [maas_machines_storage_cmp002_lvm] at time 09:52:46.128186 duration_in_ms=2369.873
2019-04-25 09:52:46,128 [salt.state       :1780][INFO    ][7919] Running state [maas_machines_storage_cmp001_lvm] at time 09:52:46.128842
2019-04-25 09:52:46,129 [salt.state       :1813][INFO    ][7919] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-25 09:52:47,228 [salt.loaded.ext.module.maasng:610 ][INFO    ][7919] cyq8w6
2019-04-25 09:52:47,229 [salt.loaded.ext.module.maasng:626 ][INFO    ][7919] sda
2019-04-25 09:52:47,776 [salt.loaded.ext.module.maasng:361 ][INFO    ][7919] cyq8w6
2019-04-25 09:52:47,863 [salt.loaded.ext.module.maasng:367 ][INFO    ][7919] [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/cyq8w6/blockdevices/2/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cyq8w6', u'block_size': 4096, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'used_for': u'MBR partitioned with 1 partition', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'481c0602-7a26-4ab6-a2fd-045a0354dfee', u'resource_uri': u'/MAAS/api/2.0/nodes/cyq8w6/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cyq8w6', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'5e6d983a-a9e2-4df9-8634-eca2444a1802', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'device_id': 2}], u'type': u'physical', u'id': 2, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cyq8w6/blockdevices/4/', u'available_size': 0, u'uuid': u'40246611-ecb1-4e08-8349-7ffaf6c2ebcc', u'tags': [], u'used_size': 800097042432, u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'cyq8w6', u'block_size': 4096, u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'f2928ca2-26bb-4678-8677-6ca027171f5a', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 4, u'size': 800097042432}]
2019-04-25 09:52:47,864 [salt.loaded.ext.module.maasng:632 ][INFO    ][7919] vgroot
2019-04-25 09:52:47,864 [salt.loaded.ext.module.maasng:635 ][INFO    ][7919] lvroot
2019-04-25 09:52:47,864 [salt.loaded.ext.module.maasng:639 ][INFO    ][7919] 107374182400
2019-04-25 09:52:48,446 [salt.loaded.ext.module.maasng:645 ][INFO    ][7919] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, 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'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/cyq8w6/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'cyq8w6', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'blockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/cyq8w6/blockdevices/2/', u'type': u'physical', u'tags': [u'ssd'], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'cyq8w6', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'8b613209-aad2-4a82-8c58-d55e25e8ddad', u'resource_uri': u'/MAAS/api/2.0/nodes/cyq8w6/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cyq8w6', u'filesystem': {u'uuid': u'20b44cd1-0572-416d-9fb4-92e6295f79d1', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}]}, {u'size': 107374182400, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/cyq8w6/blockdevices/12/', u'type': u'virtual', u'tags': [], u'filesystem': {u'uuid': u'2884a3ac-2fba-4206-95e8-1d136d11808d', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'uuid': u'59c9a687-3de9-4d71-a608-6bb8be439148', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'cyq8w6', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/cyq8w6/blockdevices/2/', u'type': u'physical', u'tags': [u'ssd'], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'cyq8w6', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'8b613209-aad2-4a82-8c58-d55e25e8ddad', u'resource_uri': u'/MAAS/api/2.0/nodes/cyq8w6/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cyq8w6', u'filesystem': {u'uuid': u'20b44cd1-0572-416d-9fb4-92e6295f79d1', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'cyq8w6', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_interface': {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cqxxgq', u'mtu': 1500, 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.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'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cqxxgq', u'mtu': 1500, 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.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'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cqxxgq', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'id': 5, u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'system_id': u'cyq8w6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cyq8w6/interfaces/5/'}, u'interface_set': [{u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cqxxgq', u'mtu': 1500, 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.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'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cqxxgq', u'mtu': 1500, 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.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'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cqxxgq', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'id': 5, u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'system_id': u'cyq8w6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cyq8w6/interfaces/5/'}, {u'discovered': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 16, u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'system_id': u'cyq8w6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cyq8w6/interfaces/16/'}, {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'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'system_id': u'cyq8w6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cyq8w6/interfaces/18/'}, {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cqxxgq', u'mtu': 1500, 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.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'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cqxxgq', u'mtu': 1500, 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': 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'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cqxxgq', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'id': 17, u'effective_mtu': 1500, u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'system_id': u'cyq8w6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cyq8w6/interfaces/17/'}, {u'discovered': None, u'name': u'ens2f1', 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'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 19, u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'system_id': u'cyq8w6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cyq8w6/interfaces/19/'}, {u'discovered': None, u'name': u'ens2f0', 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'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 20, u'effective_mtu': 1500, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'system_id': u'cyq8w6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cyq8w6/interfaces/20/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'virtualblockdevice_set': [{u'size': 107374182400, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/cyq8w6/blockdevices/12/', u'type': u'virtual', u'tags': [], u'filesystem': {u'uuid': u'2884a3ac-2fba-4206-95e8-1d136d11808d', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'uuid': u'59c9a687-3de9-4d71-a608-6bb8be439148', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'cyq8w6', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'boot_disk': {u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/cyq8w6/blockdevices/2/', u'type': u'physical', u'tags': [u'ssd'], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'cyq8w6', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'8b613209-aad2-4a82-8c58-d55e25e8ddad', u'resource_uri': u'/MAAS/api/2.0/nodes/cyq8w6/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cyq8w6', u'filesystem': {u'uuid': u'20b44cd1-0572-416d-9fb4-92e6295f79d1', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}]}, u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-04-25 09:52:48,450 [salt.state       :300 ][INFO    ][7919] {'new': {'storage_layout': 'lvm'}}
2019-04-25 09:52:48,450 [salt.state       :1951][INFO    ][7919] Completed state [maas_machines_storage_cmp001_lvm] at time 09:52:48.450678 duration_in_ms=2321.835
2019-04-25 09:52:48,454 [salt.minion      :1711][INFO    ][7919] Returning information for job: 20190425095235904597
2019-04-25 09:52:49,002 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command state.apply with jid 20190425095248989360
2019-04-25 09:52:49,019 [salt.minion      :1432][INFO    ][7946] Starting a new job with PID 7946
2019-04-25 09:52:49,978 [salt.state       :915 ][INFO    ][7946] Loading fresh modules for state activity
2019-04-25 09:52:50,029 [salt.fileclient  :1219][INFO    ][7946] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-25 09:52:50,071 [salt.state       :1780][INFO    ][7946] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:52:50.071382
2019-04-25 09:52:50,071 [salt.state       :1813][INFO    ][7946] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-25 09:52:50,073 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7946] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-25 09:52:51,944 [salt.state       :300 ][INFO    ][7946] {'pid': 7953, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-25 09:52:51,945 [salt.state       :1951][INFO    ][7946] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:52:51.945417 duration_in_ms=1874.035
2019-04-25 09:52:51,947 [salt.state       :1780][INFO    ][7946] Running state [maas.deploy_machines] at time 09:52:51.947535
2019-04-25 09:52:51,947 [salt.state       :1813][INFO    ][7946] Executing state module.run for [maas.deploy_machines]
2019-04-25 09:52:51,948 [salt.utils.decorators:613 ][WARNING ][7946] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 09:52:52,640 [salt.loaded.ext.module.maas:684 ][INFO    ][7946] deploymachines hwe_kernel=ga-16.04 system_id=emsqep distro_series=xenial
2019-04-25 09:52:54,998 [salt.loaded.ext.module.maas:684 ][INFO    ][7946] deploymachines hwe_kernel=ga-16.04 system_id=cyq8w6 distro_series=xenial
2019-04-25 09:52:57,344 [salt.loaded.ext.module.maas:684 ][INFO    ][7946] deploymachines hwe_kernel=ga-16.04 system_id=ryq7cc distro_series=xenial
2019-04-25 09:52:59,723 [salt.loaded.ext.module.maas:684 ][INFO    ][7946] deploymachines hwe_kernel=ga-16.04 system_id=nxp34g distro_series=xenial
2019-04-25 09:53:02,051 [salt.loaded.ext.module.maas:684 ][INFO    ][7946] deploymachines hwe_kernel=ga-16.04 system_id=a4x87s distro_series=xenial
2019-04-25 09:53:04,037 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425095304026496
2019-04-25 09:53:04,053 [salt.minion      :1432][INFO    ][8221] Starting a new job with PID 8221
2019-04-25 09:53:04,074 [salt.minion      :1711][INFO    ][8221] Returning information for job: 20190425095304026496
2019-04-25 09:53:04,401 [salt.state       :300 ][INFO    ][7946] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-25 09:53:04,402 [salt.state       :1951][INFO    ][7946] Completed state [maas.deploy_machines] at time 09:53:04.402153 duration_in_ms=12454.616
2019-04-25 09:53:04,405 [salt.minion      :1711][INFO    ][7946] Returning information for job: 20190425095248989360
2019-04-25 09:53:05,014 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command state.apply with jid 20190425095305002329
2019-04-25 09:53:05,035 [salt.minion      :1432][INFO    ][8261] Starting a new job with PID 8261
2019-04-25 09:53:11,015 [salt.state       :915 ][INFO    ][8261] Loading fresh modules for state activity
2019-04-25 09:53:11,073 [salt.fileclient  :1219][INFO    ][8261] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-25 09:53:11,117 [salt.state       :1780][INFO    ][8261] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:53:11.117147
2019-04-25 09:53:11,117 [salt.state       :1813][INFO    ][8261] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-25 09:53:11,119 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8261] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-25 09:53:12,879 [salt.state       :300 ][INFO    ][8261] {'pid': 8294, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-25 09:53:12,880 [salt.state       :1951][INFO    ][8261] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:53:12.880845 duration_in_ms=1763.699
2019-04-25 09:53:12,884 [salt.state       :1780][INFO    ][8261] Running state [maas.wait_for_machine_status] at time 09:53:12.884276
2019-04-25 09:53:12,884 [salt.state       :1813][INFO    ][8261] Executing state module.run for [maas.wait_for_machine_status]
2019-04-25 09:53:12,885 [salt.utils.decorators:613 ][WARNING ][8261] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 09:53:15,718 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.17816281s left)
2019-04-25 09:53:20,104 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425095320092282
2019-04-25 09:53:20,123 [salt.minion      :1432][INFO    ][8314] Starting a new job with PID 8314
2019-04-25 09:53:20,144 [salt.minion      :1711][INFO    ][8314] Returning information for job: 20190425095320092282
2019-04-25 09:53:48,472 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.42371583s left)
2019-04-25 09:53:50,297 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425095350289288
2019-04-25 09:53:50,316 [salt.minion      :1432][INFO    ][8348] Starting a new job with PID 8348
2019-04-25 09:53:50,336 [salt.minion      :1711][INFO    ][8348] Returning information for job: 20190425095350289288
2019-04-25 09:54:20,489 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425095420481902
2019-04-25 09:54:20,505 [salt.minion      :1432][INFO    ][8423] Starting a new job with PID 8423
2019-04-25 09:54:20,526 [salt.minion      :1711][INFO    ][8423] Returning information for job: 20190425095420481902
2019-04-25 09:54:21,260 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.63618588s left)
2019-04-25 09:54:50,666 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425095450658632
2019-04-25 09:54:50,686 [salt.minion      :1432][INFO    ][8443] Starting a new job with PID 8443
2019-04-25 09:54:50,706 [salt.minion      :1711][INFO    ][8443] Returning information for job: 20190425095450658632
2019-04-25 09:54:54,189 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.70650887s left)
2019-04-25 09:55:20,853 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425095520844223
2019-04-25 09:55:20,874 [salt.minion      :1432][INFO    ][8539] Starting a new job with PID 8539
2019-04-25 09:55:20,892 [salt.minion      :1711][INFO    ][8539] Returning information for job: 20190425095520844223
2019-04-25 09:55:26,942 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.95409584s left)
2019-04-25 09:55:50,884 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425095550875771
2019-04-25 09:55:50,897 [salt.minion      :1432][INFO    ][8597] Starting a new job with PID 8597
2019-04-25 09:55:50,917 [salt.minion      :1711][INFO    ][8597] Returning information for job: 20190425095550875771
2019-04-25 09:55:59,761 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.13492703s left)
2019-04-25 09:56:20,914 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425095620896719
2019-04-25 09:56:20,937 [salt.minion      :1432][INFO    ][8811] Starting a new job with PID 8811
2019-04-25 09:56:20,959 [salt.minion      :1711][INFO    ][8811] Returning information for job: 20190425095620896719
2019-04-25 09:56:32,666 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2050.22991395s left)
2019-04-25 09:56:50,999 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425095650986064
2019-04-25 09:56:51,020 [salt.minion      :1432][INFO    ][8887] Starting a new job with PID 8887
2019-04-25 09:56:51,038 [salt.minion      :1711][INFO    ][8887] Returning information for job: 20190425095650986064
2019-04-25 09:57:06,650 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.24603391s left)
2019-04-25 09:57:21,013 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425095721005913
2019-04-25 09:57:21,026 [salt.minion      :1432][INFO    ][9185] Starting a new job with PID 9185
2019-04-25 09:57:21,048 [salt.minion      :1711][INFO    ][9185] Returning information for job: 20190425095721005913
2019-04-25 09:57:39,664 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.23155785s left)
2019-04-25 09:57:51,081 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425095751072799
2019-04-25 09:57:51,102 [salt.minion      :1432][INFO    ][9321] Starting a new job with PID 9321
2019-04-25 09:57:51,122 [salt.minion      :1711][INFO    ][9321] Returning information for job: 20190425095751072799
2019-04-25 09:58:12,624 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.27165484s left)
2019-04-25 09:58:21,122 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425095821104810
2019-04-25 09:58:21,145 [salt.minion      :1432][INFO    ][9693] Starting a new job with PID 9693
2019-04-25 09:58:21,170 [salt.minion      :1711][INFO    ][9693] Returning information for job: 20190425095821104810
2019-04-25 09:58:45,524 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1917.37178588s left)
2019-04-25 09:58:51,347 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425095851333565
2019-04-25 09:58:51,369 [salt.minion      :1432][INFO    ][9896] Starting a new job with PID 9896
2019-04-25 09:58:51,396 [salt.minion      :1711][INFO    ][9896] Returning information for job: 20190425095851333565
2019-04-25 09:59:18,521 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1884.37520385s left)
2019-04-25 09:59:21,369 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425095921360516
2019-04-25 09:59:21,393 [salt.minion      :1432][INFO    ][10219] Starting a new job with PID 10219
2019-04-25 09:59:21,415 [salt.minion      :1711][INFO    ][10219] Returning information for job: 20190425095921360516
2019-04-25 09:59:51,383 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425095951373042
2019-04-25 09:59:51,404 [salt.minion      :1432][INFO    ][10343] Starting a new job with PID 10343
2019-04-25 09:59:51,426 [salt.minion      :1711][INFO    ][10343] Returning information for job: 20190425095951373042
2019-04-25 09:59:51,508 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1851.38743782s left)
2019-04-25 10:00:21,432 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425100021420371
2019-04-25 10:00:21,453 [salt.minion      :1432][INFO    ][10581] Starting a new job with PID 10581
2019-04-25 10:00:21,482 [salt.minion      :1711][INFO    ][10581] Returning information for job: 20190425100021420371
2019-04-25 10:00:24,446 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1818.44992995s left)
2019-04-25 10:00:51,452 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425100051444312
2019-04-25 10:00:51,476 [salt.minion      :1432][INFO    ][10668] Starting a new job with PID 10668
2019-04-25 10:00:51,504 [salt.minion      :1711][INFO    ][10668] Returning information for job: 20190425100051444312
2019-04-25 10:00:57,359 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1785.53716302s left)
2019-04-25 10:01:21,490 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425100121480170
2019-04-25 10:01:21,513 [salt.minion      :1432][INFO    ][10893] Starting a new job with PID 10893
2019-04-25 10:01:21,541 [salt.minion      :1711][INFO    ][10893] Returning information for job: 20190425100121480170
2019-04-25 10:01:30,214 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1752.68168902s left)
2019-04-25 10:01:51,680 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425100151668137
2019-04-25 10:01:51,703 [salt.minion      :1432][INFO    ][11004] Starting a new job with PID 11004
2019-04-25 10:01:51,731 [salt.minion      :1711][INFO    ][11004] Returning information for job: 20190425100151668137
2019-04-25 10:02:03,109 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1719.7871809s left)
2019-04-25 10:02:21,718 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425100221708103
2019-04-25 10:02:21,734 [salt.minion      :1432][INFO    ][11180] Starting a new job with PID 11180
2019-04-25 10:02:21,761 [salt.minion      :1711][INFO    ][11180] Returning information for job: 20190425100221708103
2019-04-25 10:02:36,057 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1686.83906794s left)
2019-04-25 10:02:51,758 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425100251747779
2019-04-25 10:02:51,777 [salt.minion      :1432][INFO    ][11254] Starting a new job with PID 11254
2019-04-25 10:02:51,804 [salt.minion      :1711][INFO    ][11254] Returning information for job: 20190425100251747779
2019-04-25 10:03:09,100 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1653.79749489s left)
2019-04-25 10:03:21,932 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425100321920275
2019-04-25 10:03:21,954 [salt.minion      :1432][INFO    ][11430] Starting a new job with PID 11430
2019-04-25 10:03:21,980 [salt.minion      :1711][INFO    ][11430] Returning information for job: 20190425100321920275
2019-04-25 10:03:41,872 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1621.02338386s left)
2019-04-25 10:03:51,962 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425100351954138
2019-04-25 10:03:51,985 [salt.minion      :1432][INFO    ][11488] Starting a new job with PID 11488
2019-04-25 10:03:52,016 [salt.minion      :1711][INFO    ][11488] Returning information for job: 20190425100351954138
2019-04-25 10:04:14,637 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1588.25918698s left)
2019-04-25 10:04:22,149 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425100422136120
2019-04-25 10:04:22,173 [salt.minion      :1432][INFO    ][11557] Starting a new job with PID 11557
2019-04-25 10:04:22,200 [salt.minion      :1711][INFO    ][11557] Returning information for job: 20190425100422136120
2019-04-25 10:04:47,578 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1555.31785703s left)
2019-04-25 10:04:52,317 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425100452305445
2019-04-25 10:04:52,333 [salt.minion      :1432][INFO    ][11721] Starting a new job with PID 11721
2019-04-25 10:04:52,361 [salt.minion      :1711][INFO    ][11721] Returning information for job: 20190425100452305445
2019-04-25 10:05:20,292 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1522.60351801s left)
2019-04-25 10:05:22,489 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425100522476815
2019-04-25 10:05:22,508 [salt.minion      :1432][INFO    ][11876] Starting a new job with PID 11876
2019-04-25 10:05:22,535 [salt.minion      :1711][INFO    ][11876] Returning information for job: 20190425100522476815
2019-04-25 10:05:52,617 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425100552607517
2019-04-25 10:05:52,633 [salt.minion      :1432][INFO    ][11980] Starting a new job with PID 11980
2019-04-25 10:05:52,662 [salt.minion      :1711][INFO    ][11980] Returning information for job: 20190425100552607517
2019-04-25 10:05:53,365 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1489.53081584s left)
2019-04-25 10:06:22,641 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425100622629282
2019-04-25 10:06:22,664 [salt.minion      :1432][INFO    ][12199] Starting a new job with PID 12199
2019-04-25 10:06:22,691 [salt.minion      :1711][INFO    ][12199] Returning information for job: 20190425100622629282
2019-04-25 10:06:26,116 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1456.78030205s left)
2019-04-25 10:06:52,836 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425100652827412
2019-04-25 10:06:52,854 [salt.minion      :1432][INFO    ][12243] Starting a new job with PID 12243
2019-04-25 10:06:52,888 [salt.minion      :1711][INFO    ][12243] Returning information for job: 20190425100652827412
2019-04-25 10:06:58,972 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1423.92420387s left)
2019-04-25 10:07:22,866 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425100722852601
2019-04-25 10:07:22,890 [salt.minion      :1432][INFO    ][12311] Starting a new job with PID 12311
2019-04-25 10:07:22,921 [salt.minion      :1711][INFO    ][12311] Returning information for job: 20190425100722852601
2019-04-25 10:07:31,793 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1391.10309982s left)
2019-04-25 10:07:53,089 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425100753077781
2019-04-25 10:07:53,110 [salt.minion      :1432][INFO    ][12346] Starting a new job with PID 12346
2019-04-25 10:07:53,140 [salt.minion      :1711][INFO    ][12346] Returning information for job: 20190425100753077781
2019-04-25 10:08:04,681 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1358.21470094s left)
2019-04-25 10:08:23,135 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425100823122957
2019-04-25 10:08:23,156 [salt.minion      :1432][INFO    ][12421] Starting a new job with PID 12421
2019-04-25 10:08:23,185 [salt.minion      :1711][INFO    ][12421] Returning information for job: 20190425100823122957
2019-04-25 10:08:37,401 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1325.49504685s left)
2019-04-25 10:08:53,183 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425100853175599
2019-04-25 10:08:53,198 [salt.minion      :1432][INFO    ][12454] Starting a new job with PID 12454
2019-04-25 10:08:53,229 [salt.minion      :1711][INFO    ][12454] Returning information for job: 20190425100853175599
2019-04-25 10:09:10,325 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1292.57101703s left)
2019-04-25 10:09:23,220 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425100923207566
2019-04-25 10:09:23,240 [salt.minion      :1432][INFO    ][12525] Starting a new job with PID 12525
2019-04-25 10:09:23,269 [salt.minion      :1711][INFO    ][12525] Returning information for job: 20190425100923207566
2019-04-25 10:09:43,021 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1259.87459183s left)
2019-04-25 10:09:53,301 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425100953286631
2019-04-25 10:09:53,325 [salt.minion      :1432][INFO    ][12558] Starting a new job with PID 12558
2019-04-25 10:09:53,355 [salt.minion      :1711][INFO    ][12558] Returning information for job: 20190425100953286631
2019-04-25 10:10:15,873 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1227.023417s left)
2019-04-25 10:10:23,510 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425101023497691
2019-04-25 10:10:23,531 [salt.minion      :1432][INFO    ][12638] Starting a new job with PID 12638
2019-04-25 10:10:23,563 [salt.minion      :1711][INFO    ][12638] Returning information for job: 20190425101023497691
2019-04-25 10:10:48,504 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1194.39212584s left)
2019-04-25 10:10:53,657 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425101053645096
2019-04-25 10:10:53,675 [salt.minion      :1432][INFO    ][12671] Starting a new job with PID 12671
2019-04-25 10:10:53,708 [salt.minion      :1711][INFO    ][12671] Returning information for job: 20190425101053645096
2019-04-25 10:11:21,131 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1161.76464701s left)
2019-04-25 10:11:23,858 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425101123845749
2019-04-25 10:11:23,878 [salt.minion      :1432][INFO    ][12740] Starting a new job with PID 12740
2019-04-25 10:11:23,908 [salt.minion      :1711][INFO    ][12740] Returning information for job: 20190425101123845749
2019-04-25 10:11:53,942 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1128.95408702s left)
2019-04-25 10:11:53,983 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425101153972901
2019-04-25 10:11:54,001 [salt.minion      :1432][INFO    ][12775] Starting a new job with PID 12775
2019-04-25 10:11:54,033 [salt.minion      :1711][INFO    ][12775] Returning information for job: 20190425101153972901
2019-04-25 10:12:24,078 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425101224069536
2019-04-25 10:12:24,093 [salt.minion      :1432][INFO    ][12841] Starting a new job with PID 12841
2019-04-25 10:12:24,125 [salt.minion      :1711][INFO    ][12841] Returning information for job: 20190425101224069536
2019-04-25 10:12:26,767 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1096.12849283s left)
2019-04-25 10:12:54,203 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425101254189207
2019-04-25 10:12:54,225 [salt.minion      :1432][INFO    ][12873] Starting a new job with PID 12873
2019-04-25 10:12:54,256 [salt.minion      :1711][INFO    ][12873] Returning information for job: 20190425101254189207
2019-04-25 10:12:59,497 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1063.39892197s left)
2019-04-25 10:13:24,325 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425101324313585
2019-04-25 10:13:24,347 [salt.minion      :1432][INFO    ][12941] Starting a new job with PID 12941
2019-04-25 10:13:24,374 [salt.minion      :1711][INFO    ][12941] Returning information for job: 20190425101324313585
2019-04-25 10:13:32,156 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1030.73969603s left)
2019-04-25 10:13:54,493 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425101354482649
2019-04-25 10:13:54,514 [salt.minion      :1432][INFO    ][12974] Starting a new job with PID 12974
2019-04-25 10:13:54,547 [salt.minion      :1711][INFO    ][12974] Returning information for job: 20190425101354482649
2019-04-25 10:14:05,245 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (997.650956869s left)
2019-04-25 10:14:24,643 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425101424634062
2019-04-25 10:14:24,662 [salt.minion      :1432][INFO    ][13043] Starting a new job with PID 13043
2019-04-25 10:14:24,692 [salt.minion      :1711][INFO    ][13043] Returning information for job: 20190425101424634062
2019-04-25 10:14:38,054 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (964.842391968s left)
2019-04-25 10:14:54,768 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425101454760731
2019-04-25 10:14:54,792 [salt.minion      :1432][INFO    ][13086] Starting a new job with PID 13086
2019-04-25 10:14:54,828 [salt.minion      :1711][INFO    ][13086] Returning information for job: 20190425101454760731
2019-04-25 10:15:10,701 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (932.194495916s left)
2019-04-25 10:15:24,986 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425101524979058
2019-04-25 10:15:24,998 [salt.minion      :1432][INFO    ][13174] Starting a new job with PID 13174
2019-04-25 10:15:25,028 [salt.minion      :1711][INFO    ][13174] Returning information for job: 20190425101524979058
2019-04-25 10:15:43,399 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (899.496984959s left)
2019-04-25 10:15:55,204 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425101555196074
2019-04-25 10:15:55,230 [salt.minion      :1432][INFO    ][13209] Starting a new job with PID 13209
2019-04-25 10:15:55,264 [salt.minion      :1711][INFO    ][13209] Returning information for job: 20190425101555196074
2019-04-25 10:16:16,076 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (866.819651842s left)
2019-04-25 10:16:25,398 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425101625383801
2019-04-25 10:16:25,422 [salt.minion      :1432][INFO    ][13277] Starting a new job with PID 13277
2019-04-25 10:16:25,452 [salt.minion      :1711][INFO    ][13277] Returning information for job: 20190425101625383801
2019-04-25 10:16:48,980 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (833.915575027s left)
2019-04-25 10:16:55,508 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425101655499499
2019-04-25 10:16:55,528 [salt.minion      :1432][INFO    ][13310] Starting a new job with PID 13310
2019-04-25 10:16:55,557 [salt.minion      :1711][INFO    ][13310] Returning information for job: 20190425101655499499
2019-04-25 10:17:21,792 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (801.104045868s left)
2019-04-25 10:17:25,564 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425101725550577
2019-04-25 10:17:25,581 [salt.minion      :1432][INFO    ][13381] Starting a new job with PID 13381
2019-04-25 10:17:25,614 [salt.minion      :1711][INFO    ][13381] Returning information for job: 20190425101725550577
2019-04-25 10:17:54,568 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (768.327497005s left)
2019-04-25 10:17:55,613 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425101755601032
2019-04-25 10:17:55,633 [salt.minion      :1432][INFO    ][13414] Starting a new job with PID 13414
2019-04-25 10:17:55,667 [salt.minion      :1711][INFO    ][13414] Returning information for job: 20190425101755601032
2019-04-25 10:18:25,720 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425101825710238
2019-04-25 10:18:25,737 [salt.minion      :1432][INFO    ][13483] Starting a new job with PID 13483
2019-04-25 10:18:25,768 [salt.minion      :1711][INFO    ][13483] Returning information for job: 20190425101825710238
2019-04-25 10:18:27,272 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (735.623553038s left)
2019-04-25 10:18:55,782 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425101855768873
2019-04-25 10:18:55,805 [salt.minion      :1432][INFO    ][13515] Starting a new job with PID 13515
2019-04-25 10:18:55,834 [salt.minion      :1711][INFO    ][13515] Returning information for job: 20190425101855768873
2019-04-25 10:18:59,928 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (702.967420816s left)
2019-04-25 10:19:25,904 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425101925896035
2019-04-25 10:19:25,925 [salt.minion      :1432][INFO    ][13587] Starting a new job with PID 13587
2019-04-25 10:19:25,953 [salt.minion      :1711][INFO    ][13587] Returning information for job: 20190425101925896035
2019-04-25 10:19:32,612 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (670.284384012s left)
2019-04-25 10:19:56,014 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425101956005648
2019-04-25 10:19:56,037 [salt.minion      :1432][INFO    ][13620] Starting a new job with PID 13620
2019-04-25 10:19:56,068 [salt.minion      :1711][INFO    ][13620] Returning information for job: 20190425101956005648
2019-04-25 10:20:05,513 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (637.383301973s left)
2019-04-25 10:20:26,068 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425102026058817
2019-04-25 10:20:26,082 [salt.minion      :1432][INFO    ][13689] Starting a new job with PID 13689
2019-04-25 10:20:26,110 [salt.minion      :1711][INFO    ][13689] Returning information for job: 20190425102026058817
2019-04-25 10:20:37,941 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (604.954463959s left)
2019-04-25 10:20:56,229 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425102056221812
2019-04-25 10:20:56,244 [salt.minion      :1432][INFO    ][13724] Starting a new job with PID 13724
2019-04-25 10:20:56,273 [salt.minion      :1711][INFO    ][13724] Returning information for job: 20190425102056221812
2019-04-25 10:21:10,449 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (572.44729805s left)
2019-04-25 10:21:26,321 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425102126311732
2019-04-25 10:21:26,339 [salt.minion      :1432][INFO    ][13795] Starting a new job with PID 13795
2019-04-25 10:21:26,369 [salt.minion      :1711][INFO    ][13795] Returning information for job: 20190425102126311732
2019-04-25 10:21:42,774 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (540.121826887s left)
2019-04-25 10:21:56,410 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425102156402494
2019-04-25 10:21:56,426 [salt.minion      :1432][INFO    ][13828] Starting a new job with PID 13828
2019-04-25 10:21:56,465 [salt.minion      :1711][INFO    ][13828] Returning information for job: 20190425102156402494
2019-04-25 10:22:15,241 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (507.654515982s left)
2019-04-25 10:22:26,544 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425102226534309
2019-04-25 10:22:26,561 [salt.minion      :1432][INFO    ][13896] Starting a new job with PID 13896
2019-04-25 10:22:26,589 [salt.minion      :1711][INFO    ][13896] Returning information for job: 20190425102226534309
2019-04-25 10:22:47,674 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (475.22170496s left)
2019-04-25 10:22:56,684 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425102256676434
2019-04-25 10:22:56,700 [salt.minion      :1432][INFO    ][13929] Starting a new job with PID 13929
2019-04-25 10:22:56,725 [salt.minion      :1711][INFO    ][13929] Returning information for job: 20190425102256676434
2019-04-25 10:23:20,011 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (442.884385824s left)
2019-04-25 10:23:26,857 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425102326848330
2019-04-25 10:23:26,880 [salt.minion      :1432][INFO    ][13999] Starting a new job with PID 13999
2019-04-25 10:23:26,912 [salt.minion      :1711][INFO    ][13999] Returning information for job: 20190425102326848330
2019-04-25 10:23:52,413 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (410.482643843s left)
2019-04-25 10:23:57,004 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425102356995741
2019-04-25 10:23:57,021 [salt.minion      :1432][INFO    ][14034] Starting a new job with PID 14034
2019-04-25 10:23:57,049 [salt.minion      :1711][INFO    ][14034] Returning information for job: 20190425102356995741
2019-04-25 10:24:24,910 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (377.985903978s left)
2019-04-25 10:24:27,161 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425102427153487
2019-04-25 10:24:27,176 [salt.minion      :1432][INFO    ][14107] Starting a new job with PID 14107
2019-04-25 10:24:27,204 [salt.minion      :1711][INFO    ][14107] Returning information for job: 20190425102427153487
2019-04-25 10:24:57,318 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (345.578202009s left)
2019-04-25 10:24:57,324 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425102457316367
2019-04-25 10:24:57,344 [salt.minion      :1432][INFO    ][14275] Starting a new job with PID 14275
2019-04-25 10:24:57,372 [salt.minion      :1711][INFO    ][14275] Returning information for job: 20190425102457316367
2019-04-25 10:25:27,512 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425102527503214
2019-04-25 10:25:27,528 [salt.minion      :1432][INFO    ][14354] Starting a new job with PID 14354
2019-04-25 10:25:27,552 [salt.minion      :1711][INFO    ][14354] Returning information for job: 20190425102527503214
2019-04-25 10:25:29,801 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (313.094763994s left)
2019-04-25 10:25:57,691 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425102557683366
2019-04-25 10:25:57,708 [salt.minion      :1432][INFO    ][14386] Starting a new job with PID 14386
2019-04-25 10:25:57,733 [salt.minion      :1711][INFO    ][14386] Returning information for job: 20190425102557683366
2019-04-25 10:26:02,185 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (280.71115303s left)
2019-04-25 10:26:27,889 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425102627881724
2019-04-25 10:26:27,906 [salt.minion      :1432][INFO    ][14456] Starting a new job with PID 14456
2019-04-25 10:26:27,932 [salt.minion      :1711][INFO    ][14456] Returning information for job: 20190425102627881724
2019-04-25 10:26:34,542 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (248.353616953s left)
2019-04-25 10:26:58,089 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425102658082140
2019-04-25 10:26:58,104 [salt.minion      :1432][INFO    ][14489] Starting a new job with PID 14489
2019-04-25 10:26:58,133 [salt.minion      :1711][INFO    ][14489] Returning information for job: 20190425102658082140
2019-04-25 10:27:07,229 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (215.66681695s left)
2019-04-25 10:27:28,148 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425102728140641
2019-04-25 10:27:28,164 [salt.minion      :1432][INFO    ][14561] Starting a new job with PID 14561
2019-04-25 10:27:28,193 [salt.minion      :1711][INFO    ][14561] Returning information for job: 20190425102728140641
2019-04-25 10:27:39,557 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (183.338663816s left)
2019-04-25 10:27:58,232 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425102758224963
2019-04-25 10:27:58,249 [salt.minion      :1432][INFO    ][14594] Starting a new job with PID 14594
2019-04-25 10:27:58,279 [salt.minion      :1711][INFO    ][14594] Returning information for job: 20190425102758224963
2019-04-25 10:28:12,309 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (150.587218046s left)
2019-04-25 10:28:28,428 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425102828419974
2019-04-25 10:28:28,446 [salt.minion      :1432][INFO    ][14666] Starting a new job with PID 14666
2019-04-25 10:28:28,474 [salt.minion      :1711][INFO    ][14666] Returning information for job: 20190425102828419974
2019-04-25 10:28:44,969 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (117.926851988s left)
2019-04-25 10:28:58,650 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425102858637159
2019-04-25 10:28:58,670 [salt.minion      :1432][INFO    ][14699] Starting a new job with PID 14699
2019-04-25 10:28:58,709 [salt.minion      :1711][INFO    ][14699] Returning information for job: 20190425102858637159
2019-04-25 10:29:17,784 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (85.111992836s left)
2019-04-25 10:29:28,792 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425102928773624
2019-04-25 10:29:28,814 [salt.minion      :1432][INFO    ][14769] Starting a new job with PID 14769
2019-04-25 10:29:28,848 [salt.minion      :1711][INFO    ][14769] Returning information for job: 20190425102928773624
2019-04-25 10:29:50,525 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (52.3707480431s left)
2019-04-25 10:29:58,953 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425102958945499
2019-04-25 10:29:58,974 [salt.minion      :1432][INFO    ][14802] Starting a new job with PID 14802
2019-04-25 10:29:59,003 [salt.minion      :1711][INFO    ][14802] Returning information for job: 20190425102958945499
2019-04-25 10:30:23,372 [salt.loaded.ext.module.maas:1023][INFO    ][8261] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (19.5242679119s left)
2019-04-25 10:30:29,136 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425103029122415
2019-04-25 10:30:29,149 [salt.minion      :1432][INFO    ][14874] Starting a new job with PID 14874
2019-04-25 10:30:29,182 [salt.minion      :1711][INFO    ][14874] Returning information for job: 20190425103029122415
2019-04-25 10:30:55,976 [salt.state       :302 ][ERROR   ][8261] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-04-25 10:30:55,977 [salt.state       :1951][INFO    ][8261] Completed state [maas.wait_for_machine_status] at time 10:30:55.976961 duration_in_ms=2263092.68
2019-04-25 10:30:55,986 [salt.minion      :1711][INFO    ][8261] Returning information for job: 20190425095305002329
2019-04-25 10:31:06,950 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command pillar.get with jid 20190425103106938249
2019-04-25 10:31:06,969 [salt.minion      :1432][INFO    ][14950] Starting a new job with PID 14950
2019-04-25 10:31:06,979 [salt.minion      :1711][INFO    ][14950] Returning information for job: 20190425103106938249
2019-04-25 10:31:07,665 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command service.status with jid 20190425103107655496
2019-04-25 10:31:07,681 [salt.minion      :1432][INFO    ][14955] Starting a new job with PID 14955
2019-04-25 10:31:08,427 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14955] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-25 10:31:08,464 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14955] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-25 10:31:08,480 [salt.minion      :1711][INFO    ][14955] Returning information for job: 20190425103107655496
2019-04-25 10:31:09,164 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command state.apply with jid 20190425103109155150
2019-04-25 10:31:09,182 [salt.minion      :1432][INFO    ][14966] Starting a new job with PID 14966
2019-04-25 10:31:15,385 [salt.state       :915 ][INFO    ][14966] Loading fresh modules for state activity
2019-04-25 10:31:15,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14966] Executing command 'salt-minion --version' in directory '/root'
2019-04-25 10:31:16,295 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14966] Executing command 'salt-minion --version' in directory '/root'
2019-04-25 10:31:17,317 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14966] Executing command 'salt-minion --version' in directory '/root'
2019-04-25 10:31:17,648 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14966] Executing command 'salt-minion --version' in directory '/root'
2019-04-25 10:31:19,783 [salt.state       :1780][INFO    ][14966] Running state [salt-minion] at time 10:31:19.783264
2019-04-25 10:31:19,783 [salt.state       :1813][INFO    ][14966] Executing state pkg.installed for [salt-minion]
2019-04-25 10:31:19,784 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14966] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-25 10:31:19,897 [salt.state       :300 ][INFO    ][14966] All specified packages are already installed
2019-04-25 10:31:19,898 [salt.state       :1951][INFO    ][14966] Completed state [salt-minion] at time 10:31:19.897911 duration_in_ms=114.647
2019-04-25 10:31:19,898 [salt.state       :1780][INFO    ][14966] Running state [salt_minion_dependency_packages] at time 10:31:19.898268
2019-04-25 10:31:19,898 [salt.state       :1813][INFO    ][14966] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-25 10:31:19,908 [salt.state       :300 ][INFO    ][14966] All specified packages are already installed
2019-04-25 10:31:19,908 [salt.state       :1951][INFO    ][14966] Completed state [salt_minion_dependency_packages] at time 10:31:19.908687 duration_in_ms=10.418
2019-04-25 10:31:19,912 [salt.state       :1780][INFO    ][14966] Running state [/etc/salt/minion.d/minion.conf] at time 10:31:19.912105
2019-04-25 10:31:19,912 [salt.state       :1813][INFO    ][14966] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-25 10:31:20,181 [salt.state       :300 ][INFO    ][14966] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-25 10:31:20,181 [salt.state       :1951][INFO    ][14966] Completed state [/etc/salt/minion.d/minion.conf] at time 10:31:20.181804 duration_in_ms=269.699
2019-04-25 10:31:20,182 [salt.state       :1780][INFO    ][14966] Running state [python-netaddr] at time 10:31:20.182098
2019-04-25 10:31:20,182 [salt.state       :1813][INFO    ][14966] Executing state pkg.installed for [python-netaddr]
2019-04-25 10:31:20,191 [salt.state       :300 ][INFO    ][14966] All specified packages are already installed
2019-04-25 10:31:20,192 [salt.state       :1951][INFO    ][14966] Completed state [python-netaddr] at time 10:31:20.191989 duration_in_ms=9.891
2019-04-25 10:31:20,195 [salt.state       :1780][INFO    ][14966] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 10:31:20.195278
2019-04-25 10:31:20,195 [salt.state       :1813][INFO    ][14966] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-25 10:31:20,205 [salt.state       :300 ][INFO    ][14966] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-25 10:31:20,205 [salt.state       :1951][INFO    ][14966] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 10:31:20.205503 duration_in_ms=10.225
2019-04-25 10:31:20,206 [salt.state       :1780][INFO    ][14966] Running state [salt-minion] at time 10:31:20.206707
2019-04-25 10:31:20,207 [salt.state       :1813][INFO    ][14966] Executing state service.running for [salt-minion]
2019-04-25 10:31:20,208 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14966] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-25 10:31:20,253 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14966] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-25 10:31:20,276 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14966] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-25 10:31:20,293 [salt.state       :300 ][INFO    ][14966] The service salt-minion is already running
2019-04-25 10:31:20,294 [salt.state       :1951][INFO    ][14966] Completed state [salt-minion] at time 10:31:20.294315 duration_in_ms=87.606
2019-04-25 10:31:20,298 [salt.state       :1780][INFO    ][14966] Running state [/etc/salt/grains.d] at time 10:31:20.298418
2019-04-25 10:31:20,299 [salt.state       :1813][INFO    ][14966] Executing state file.directory for [/etc/salt/grains.d]
2019-04-25 10:31:20,300 [salt.state       :300 ][INFO    ][14966] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-25 10:31:20,301 [salt.state       :1951][INFO    ][14966] Completed state [/etc/salt/grains.d] at time 10:31:20.301001 duration_in_ms=2.583
2019-04-25 10:31:20,302 [salt.state       :1780][INFO    ][14966] Running state [/etc/salt/grains] at time 10:31:20.302078
2019-04-25 10:31:20,302 [salt.state       :1813][INFO    ][14966] Executing state file.managed for [/etc/salt/grains]
2019-04-25 10:31:20,304 [salt.state       :300 ][INFO    ][14966] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-25 10:31:20,305 [salt.state       :1951][INFO    ][14966] Completed state [/etc/salt/grains] at time 10:31:20.305167 duration_in_ms=3.089
2019-04-25 10:31:20,306 [salt.state       :1780][INFO    ][14966] Running state [/etc/salt/grains.d/placeholder] at time 10:31:20.305934
2019-04-25 10:31:20,306 [salt.state       :1813][INFO    ][14966] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-25 10:31:20,309 [salt.state       :300 ][INFO    ][14966] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-25 10:31:20,309 [salt.state       :1951][INFO    ][14966] Completed state [/etc/salt/grains.d/placeholder] at time 10:31:20.309383 duration_in_ms=3.448
2019-04-25 10:31:20,309 [salt.state       :1780][INFO    ][14966] Running state [/etc/salt/grains.d/sphinx] at time 10:31:20.309899
2019-04-25 10:31:20,310 [salt.state       :1813][INFO    ][14966] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-25 10:31:20,312 [salt.state       :300 ][INFO    ][14966] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-25 10:31:20,312 [salt.state       :1951][INFO    ][14966] Completed state [/etc/salt/grains.d/sphinx] at time 10:31:20.312521 duration_in_ms=2.621
2019-04-25 10:31:20,315 [salt.state       :1780][INFO    ][14966] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 10:31:20.315675
2019-04-25 10:31:20,316 [salt.state       :1813][INFO    ][14966] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-25 10:31:20,316 [salt.state       :300 ][INFO    ][14966] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-25 10:31:20,317 [salt.state       :1951][INFO    ][14966] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 10:31:20.317029 duration_in_ms=1.355
2019-04-25 10:31:20,317 [salt.state       :1780][INFO    ][14966] Running state [/etc/salt/grains.d/dns_records] at time 10:31:20.317806
2019-04-25 10:31:20,318 [salt.state       :1813][INFO    ][14966] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-25 10:31:20,320 [salt.state       :300 ][INFO    ][14966] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-25 10:31:20,321 [salt.state       :1951][INFO    ][14966] Completed state [/etc/salt/grains.d/dns_records] at time 10:31:20.321165 duration_in_ms=3.359
2019-04-25 10:31:20,322 [salt.state       :1780][INFO    ][14966] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 10:31:20.322120
2019-04-25 10:31:20,322 [salt.state       :1813][INFO    ][14966] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-04-25 10:31:20,322 [salt.state       :300 ][INFO    ][14966] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-25 10:31:20,323 [salt.state       :1951][INFO    ][14966] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 10:31:20.322962 duration_in_ms=0.842
2019-04-25 10:31:20,323 [salt.state       :1780][INFO    ][14966] Running state [/etc/salt/grains.d/salt] at time 10:31:20.323475
2019-04-25 10:31:20,323 [salt.state       :1813][INFO    ][14966] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-25 10:31:20,324 [salt.state       :300 ][INFO    ][14966] File /etc/salt/grains.d/salt is in the correct state
2019-04-25 10:31:20,325 [salt.state       :1951][INFO    ][14966] Completed state [/etc/salt/grains.d/salt] at time 10:31:20.325023 duration_in_ms=1.548
2019-04-25 10:31:20,325 [salt.state       :1780][INFO    ][14966] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 10:31:20.325926
2019-04-25 10:31:20,326 [salt.state       :1813][INFO    ][14966] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-25 10:31:20,326 [salt.state       :300 ][INFO    ][14966] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-25 10:31:20,326 [salt.state       :1951][INFO    ][14966] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 10:31:20.326746 duration_in_ms=0.82
2019-04-25 10:31:20,328 [salt.state       :1780][INFO    ][14966] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 10:31:20.328676
2019-04-25 10:31:20,329 [salt.state       :1813][INFO    ][14966] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-25 10:31:20,329 [salt.state       :300 ][INFO    ][14966] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-25 10:31:20,329 [salt.state       :1951][INFO    ][14966] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 10:31:20.329531 duration_in_ms=0.855
2019-04-25 10:31:20,330 [salt.state       :1780][INFO    ][14966] Running state [mine.update] at time 10:31:20.330223
2019-04-25 10:31:20,330 [salt.state       :1813][INFO    ][14966] Executing state module.wait for [mine.update]
2019-04-25 10:31:20,330 [salt.state       :300 ][INFO    ][14966] No changes made for mine.update
2019-04-25 10:31:20,331 [salt.state       :1951][INFO    ][14966] Completed state [mine.update] at time 10:31:20.331864 duration_in_ms=1.641
2019-04-25 10:31:20,332 [salt.state       :1780][INFO    ][14966] Running state [ca-certificates] at time 10:31:20.332141
2019-04-25 10:31:20,332 [salt.state       :1813][INFO    ][14966] Executing state pkg.installed for [ca-certificates]
2019-04-25 10:31:20,342 [salt.state       :300 ][INFO    ][14966] All specified packages are already installed
2019-04-25 10:31:20,342 [salt.state       :1951][INFO    ][14966] Completed state [ca-certificates] at time 10:31:20.342378 duration_in_ms=10.237
2019-04-25 10:31:20,344 [salt.state       :1780][INFO    ][14966] Running state [update-ca-certificates] at time 10:31:20.344142
2019-04-25 10:31:20,344 [salt.state       :1813][INFO    ][14966] Executing state cmd.wait for [update-ca-certificates]
2019-04-25 10:31:20,344 [salt.state       :300 ][INFO    ][14966] No changes made for update-ca-certificates
2019-04-25 10:31:20,345 [salt.state       :1951][INFO    ][14966] Completed state [update-ca-certificates] at time 10:31:20.344972 duration_in_ms=0.831
2019-04-25 10:31:20,345 [salt.state       :1780][INFO    ][14966] Running state [iptables] at time 10:31:20.345245
2019-04-25 10:31:20,345 [salt.state       :1813][INFO    ][14966] Executing state pkg.installed for [iptables]
2019-04-25 10:31:20,354 [salt.state       :300 ][INFO    ][14966] All specified packages are already installed
2019-04-25 10:31:20,356 [salt.state       :1951][INFO    ][14966] Completed state [iptables] at time 10:31:20.356126 duration_in_ms=10.881
2019-04-25 10:31:20,356 [salt.state       :1780][INFO    ][14966] Running state [iptables-persistent] at time 10:31:20.356410
2019-04-25 10:31:20,356 [salt.state       :1813][INFO    ][14966] Executing state pkg.installed for [iptables-persistent]
2019-04-25 10:31:20,366 [salt.state       :300 ][INFO    ][14966] All specified packages are already installed
2019-04-25 10:31:20,366 [salt.state       :1951][INFO    ][14966] Completed state [iptables-persistent] at time 10:31:20.366240 duration_in_ms=9.83
2019-04-25 10:31:20,368 [salt.state       :1780][INFO    ][14966] Running state [iptables_modules_v4_load] at time 10:31:20.368380
2019-04-25 10:31:20,368 [salt.state       :1813][INFO    ][14966] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-25 10:31:20,369 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14966] Executing command 'lsmod' in directory '/root'
2019-04-25 10:31:20,383 [salt.state       :300 ][INFO    ][14966] Kernel modules iptable_filter, ip_tables are already present
2019-04-25 10:31:20,384 [salt.state       :1951][INFO    ][14966] Completed state [iptables_modules_v4_load] at time 10:31:20.384065 duration_in_ms=15.685
2019-04-25 10:31:20,384 [salt.state       :1780][INFO    ][14966] Running state [/etc/iptables/rules.v4] at time 10:31:20.384737
2019-04-25 10:31:20,385 [salt.state       :1813][INFO    ][14966] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-25 10:31:20,480 [salt.state       :300 ][INFO    ][14966] File /etc/iptables/rules.v4 is in the correct state
2019-04-25 10:31:20,481 [salt.state       :1951][INFO    ][14966] Completed state [/etc/iptables/rules.v4] at time 10:31:20.481169 duration_in_ms=96.432
2019-04-25 10:31:20,482 [salt.state       :1780][INFO    ][14966] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 10:31:20.482314
2019-04-25 10:31:20,482 [salt.state       :1813][INFO    ][14966] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-25 10:31:20,483 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14966] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-25 10:31:20,497 [salt.state       :300 ][INFO    ][14966] onlyif execution failed
2019-04-25 10:31:20,498 [salt.state       :1951][INFO    ][14966] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 10:31:20.498038 duration_in_ms=15.724
2019-04-25 10:31:20,501 [salt.state       :1780][INFO    ][14966] Running state [netfilter-persistent] at time 10:31:20.501064
2019-04-25 10:31:20,501 [salt.state       :1813][INFO    ][14966] Executing state service.running for [netfilter-persistent]
2019-04-25 10:31:20,502 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14966] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-25 10:31:20,521 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14966] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-25 10:31:20,544 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14966] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-25 10:31:20,561 [salt.state       :300 ][INFO    ][14966] The service netfilter-persistent is already running
2019-04-25 10:31:20,562 [salt.state       :1951][INFO    ][14966] Completed state [netfilter-persistent] at time 10:31:20.561895 duration_in_ms=60.831
2019-04-25 10:31:20,563 [salt.state       :1780][INFO    ][14966] Running state [iptables_extra.remove_stale_tables] at time 10:31:20.563611
2019-04-25 10:31:20,564 [salt.state       :1813][INFO    ][14966] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-25 10:31:20,564 [salt.state       :300 ][INFO    ][14966] No changes made for iptables_extra.remove_stale_tables
2019-04-25 10:31:20,565 [salt.state       :1951][INFO    ][14966] Completed state [iptables_extra.remove_stale_tables] at time 10:31:20.565297 duration_in_ms=1.686
2019-04-25 10:31:20,565 [salt.state       :1780][INFO    ][14966] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 10:31:20.565824
2019-04-25 10:31:20,566 [salt.state       :1813][INFO    ][14966] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-25 10:31:20,567 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14966] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-25 10:31:20,586 [salt.state       :300 ][INFO    ][14966] onlyif execution failed
2019-04-25 10:31:20,587 [salt.state       :1951][INFO    ][14966] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 10:31:20.587348 duration_in_ms=21.524
2019-04-25 10:31:20,589 [salt.state       :1780][INFO    ][14966] Running state [/etc/iptables/rules.v6] at time 10:31:20.589304
2019-04-25 10:31:20,589 [salt.state       :1813][INFO    ][14966] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-25 10:31:20,590 [salt.state       :300 ][INFO    ][14966] File /etc/iptables/rules.v6 is not present
2019-04-25 10:31:20,591 [salt.state       :1951][INFO    ][14966] Completed state [/etc/iptables/rules.v6] at time 10:31:20.591340 duration_in_ms=2.036
2019-04-25 10:31:20,592 [salt.state       :1780][INFO    ][14966] Running state [iptables_extra.flush_all] at time 10:31:20.592616
2019-04-25 10:31:20,593 [salt.state       :1813][INFO    ][14966] Executing state module.wait for [iptables_extra.flush_all]
2019-04-25 10:31:20,593 [salt.state       :300 ][INFO    ][14966] No changes made for iptables_extra.flush_all
2019-04-25 10:31:20,594 [salt.state       :1951][INFO    ][14966] Completed state [iptables_extra.flush_all] at time 10:31:20.594093 duration_in_ms=1.477
2019-04-25 10:31:20,600 [salt.minion      :1711][INFO    ][14966] Returning information for job: 20190425103109155150
2019-04-25 10:31:21,284 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command state.apply with jid 20190425103121273933
2019-04-25 10:31:21,308 [salt.minion      :1432][INFO    ][15056] Starting a new job with PID 15056
2019-04-25 10:31:22,247 [salt.state       :915 ][INFO    ][15056] Loading fresh modules for state activity
2019-04-25 10:31:23,460 [salt.state       :1780][INFO    ][15056] Running state [maas-rack-controller] at time 10:31:23.460703
2019-04-25 10:31:23,461 [salt.state       :1813][INFO    ][15056] Executing state pkg.installed for [maas-rack-controller]
2019-04-25 10:31:23,461 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15056] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-25 10:31:23,558 [salt.state       :300 ][INFO    ][15056] All specified packages are already installed
2019-04-25 10:31:23,558 [salt.state       :1951][INFO    ][15056] Completed state [maas-rack-controller] at time 10:31:23.558670 duration_in_ms=97.967
2019-04-25 10:31:23,559 [salt.state       :1780][INFO    ][15056] Running state [ipmitool] at time 10:31:23.559598
2019-04-25 10:31:23,559 [salt.state       :1813][INFO    ][15056] Executing state pkg.installed for [ipmitool]
2019-04-25 10:31:23,569 [salt.state       :300 ][INFO    ][15056] All specified packages are already installed
2019-04-25 10:31:23,569 [salt.state       :1951][INFO    ][15056] Completed state [ipmitool] at time 10:31:23.569225 duration_in_ms=9.627
2019-04-25 10:31:23,573 [salt.state       :1780][INFO    ][15056] Running state [/etc/maas/rackd.conf] at time 10:31:23.573299
2019-04-25 10:31:23,573 [salt.state       :1813][INFO    ][15056] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-25 10:31:23,574 [salt.state       :300 ][INFO    ][15056] No changes needed to be made
2019-04-25 10:31:23,574 [salt.state       :1951][INFO    ][15056] Completed state [/etc/maas/rackd.conf] at time 10:31:23.574779 duration_in_ms=1.479
2019-04-25 10:31:23,575 [salt.state       :1780][INFO    ][15056] Running state [/etc/maas/rackd.conf] at time 10:31:23.575078
2019-04-25 10:31:23,575 [salt.state       :1813][INFO    ][15056] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-25 10:31:23,575 [salt.loaded.int.states.file:2298][WARNING ][15056] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-25 10:31:23,576 [salt.state       :300 ][INFO    ][15056] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-25 10:31:23,576 [salt.state       :1951][INFO    ][15056] Completed state [/etc/maas/rackd.conf] at time 10:31:23.576696 duration_in_ms=1.618
2019-04-25 10:31:23,577 [salt.state       :1780][INFO    ][15056] Running state [maas-rackd] at time 10:31:23.577732
2019-04-25 10:31:23,578 [salt.state       :1813][INFO    ][15056] Executing state service.running for [maas-rackd]
2019-04-25 10:31:23,578 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15056] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-25 10:31:23,612 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15056] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-25 10:31:23,629 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15056] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-25 10:31:23,649 [salt.state       :300 ][INFO    ][15056] The service maas-rackd is already running
2019-04-25 10:31:23,649 [salt.state       :1951][INFO    ][15056] Completed state [maas-rackd] at time 10:31:23.649814 duration_in_ms=72.08
2019-04-25 10:31:23,651 [salt.minion      :1711][INFO    ][15056] Returning information for job: 20190425103121273933
2019-04-25 10:31:24,331 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command state.apply with jid 20190425103124320180
2019-04-25 10:31:24,348 [salt.minion      :1432][INFO    ][15081] Starting a new job with PID 15081
2019-04-25 10:31:25,305 [salt.state       :915 ][INFO    ][15081] Loading fresh modules for state activity
2019-04-25 10:31:26,664 [salt.state       :1780][INFO    ][15081] Running state [maas-region-controller] at time 10:31:26.664129
2019-04-25 10:31:26,664 [salt.state       :1813][INFO    ][15081] Executing state pkg.installed for [maas-region-controller]
2019-04-25 10:31:26,665 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15081] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-25 10:31:26,780 [salt.state       :300 ][INFO    ][15081] All specified packages are already installed
2019-04-25 10:31:26,781 [salt.state       :1951][INFO    ][15081] Completed state [maas-region-controller] at time 10:31:26.781216 duration_in_ms=117.088
2019-04-25 10:31:26,781 [salt.state       :1780][INFO    ][15081] Running state [python-oauth] at time 10:31:26.781574
2019-04-25 10:31:26,781 [salt.state       :1813][INFO    ][15081] Executing state pkg.installed for [python-oauth]
2019-04-25 10:31:26,791 [salt.state       :300 ][INFO    ][15081] All specified packages are already installed
2019-04-25 10:31:26,792 [salt.state       :1951][INFO    ][15081] Completed state [python-oauth] at time 10:31:26.792063 duration_in_ms=10.489
2019-04-25 10:31:26,795 [salt.state       :1780][INFO    ][15081] Running state [/etc/maas/regiond.conf] at time 10:31:26.795342
2019-04-25 10:31:26,795 [salt.state       :1813][INFO    ][15081] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-25 10:31:26,800 [salt.state       :300 ][INFO    ][15081] No changes needed to be made
2019-04-25 10:31:26,801 [salt.state       :1951][INFO    ][15081] Completed state [/etc/maas/regiond.conf] at time 10:31:26.801047 duration_in_ms=5.705
2019-04-25 10:31:26,801 [salt.state       :1780][INFO    ][15081] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 10:31:26.801565
2019-04-25 10:31:26,801 [salt.state       :1813][INFO    ][15081] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-25 10:31:26,857 [salt.state       :300 ][INFO    ][15081] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-25 10:31:26,857 [salt.state       :1951][INFO    ][15081] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 10:31:26.857856 duration_in_ms=56.291
2019-04-25 10:31:26,858 [salt.state       :1780][INFO    ][15081] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 10:31:26.858379
2019-04-25 10:31:26,858 [salt.state       :1813][INFO    ][15081] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-25 10:31:26,864 [salt.state       :300 ][INFO    ][15081] No changes needed to be made
2019-04-25 10:31:26,864 [salt.state       :1951][INFO    ][15081] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 10:31:26.864275 duration_in_ms=5.896
2019-04-25 10:31:26,864 [salt.state       :1780][INFO    ][15081] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 10:31:26.864790
2019-04-25 10:31:26,865 [salt.state       :1813][INFO    ][15081] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-25 10:31:26,869 [salt.state       :300 ][INFO    ][15081] No changes needed to be made
2019-04-25 10:31:26,869 [salt.state       :1951][INFO    ][15081] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 10:31:26.869481 duration_in_ms=4.691
2019-04-25 10:31:26,870 [salt.state       :1780][INFO    ][15081] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 10:31:26.869996
2019-04-25 10:31:26,870 [salt.state       :1813][INFO    ][15081] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-25 10:31:26,896 [salt.state       :300 ][INFO    ][15081] No changes needed to be made
2019-04-25 10:31:26,896 [salt.state       :1951][INFO    ][15081] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 10:31:26.896705 duration_in_ms=26.709
2019-04-25 10:31:26,897 [salt.state       :1780][INFO    ][15081] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 10:31:26.897215
2019-04-25 10:31:26,897 [salt.state       :1813][INFO    ][15081] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-25 10:31:26,909 [salt.state       :300 ][INFO    ][15081] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-25 10:31:26,909 [salt.state       :1951][INFO    ][15081] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 10:31:26.909501 duration_in_ms=12.286
2019-04-25 10:31:26,910 [salt.state       :1780][INFO    ][15081] Running state [a2enmod headers] at time 10:31:26.910874
2019-04-25 10:31:26,911 [salt.state       :1813][INFO    ][15081] Executing state cmd.run for [a2enmod headers]
2019-04-25 10:31:26,911 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15081] Executing command 'a2enmod headers' in directory '/root'
2019-04-25 10:31:26,982 [salt.state       :300 ][INFO    ][15081] {'pid': 15100, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-25 10:31:26,983 [salt.state       :1951][INFO    ][15081] Completed state [a2enmod headers] at time 10:31:26.983241 duration_in_ms=72.367
2019-04-25 10:31:26,983 [salt.state       :1780][INFO    ][15081] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 10:31:26.983822
2019-04-25 10:31:26,984 [salt.state       :1813][INFO    ][15081] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-25 10:31:27,002 [salt.state       :300 ][INFO    ][15081] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-25 10:31:27,002 [salt.state       :1951][INFO    ][15081] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 10:31:27.002318 duration_in_ms=18.496
2019-04-25 10:31:27,006 [salt.state       :1780][INFO    ][15081] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 10:31:27.006094
2019-04-25 10:31:27,006 [salt.state       :1813][INFO    ][15081] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-25 10:31:27,062 [salt.state       :300 ][INFO    ][15081] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-25 10:31:27,062 [salt.state       :1951][INFO    ][15081] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 10:31:27.062420 duration_in_ms=56.326
2019-04-25 10:31:27,064 [salt.state       :1780][INFO    ][15081] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 10:31:27.064374
2019-04-25 10:31:27,064 [salt.state       :1813][INFO    ][15081] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-25 10:31:27,120 [salt.state       :300 ][INFO    ][15081] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-25 10:31:27,120 [salt.state       :1951][INFO    ][15081] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 10:31:27.120450 duration_in_ms=56.077
2019-04-25 10:31:27,121 [salt.state       :1780][INFO    ][15081] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 10:31:27.121000
2019-04-25 10:31:27,121 [salt.state       :1813][INFO    ][15081] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-25 10:31:27,196 [salt.state       :300 ][INFO    ][15081] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-25 10:31:27,196 [salt.state       :1951][INFO    ][15081] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 10:31:27.196697 duration_in_ms=75.697
2019-04-25 10:31:27,197 [salt.state       :1780][INFO    ][15081] Running state [/root/.pgpass] at time 10:31:27.197021
2019-04-25 10:31:27,197 [salt.state       :1813][INFO    ][15081] Executing state file.managed for [/root/.pgpass]
2019-04-25 10:31:27,241 [salt.state       :300 ][INFO    ][15081] File /root/.pgpass is in the correct state
2019-04-25 10:31:27,242 [salt.state       :1951][INFO    ][15081] Completed state [/root/.pgpass] at time 10:31:27.241989 duration_in_ms=44.967
2019-04-25 10:31:27,250 [salt.state       :1780][INFO    ][15081] Running state [maas-region syncdb --noinput] at time 10:31:27.250156
2019-04-25 10:31:27,250 [salt.state       :1813][INFO    ][15081] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-25 10:31:27,251 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15081] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-25 10:31:29,918 [salt.state       :300 ][INFO    ][15081] {'pid': 15113, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: contenttypes, piston3, maasserver, metadataserver, auth, sites, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-25 10:31:29,919 [salt.state       :1951][INFO    ][15081] Completed state [maas-region syncdb --noinput] at time 10:31:29.919866 duration_in_ms=2669.71
2019-04-25 10:31:29,920 [salt.state       :2022][WARNING ][15081] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-25 10:31:29,922 [salt.state       :1780][INFO    ][15081] Running state [maas-regiond] at time 10:31:29.922491
2019-04-25 10:31:29,923 [salt.state       :1813][INFO    ][15081] Executing state service.running for [maas-regiond]
2019-04-25 10:31:29,924 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15081] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-25 10:31:29,964 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15081] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-25 10:31:29,984 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15081] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-25 10:31:30,006 [salt.state       :300 ][INFO    ][15081] The service maas-regiond is already running
2019-04-25 10:31:30,007 [salt.state       :1951][INFO    ][15081] Completed state [maas-regiond] at time 10:31:30.007529 duration_in_ms=85.038
2019-04-25 10:31:30,010 [salt.state       :1780][INFO    ][15081] Running state [bind9] at time 10:31:30.010407
2019-04-25 10:31:30,011 [salt.state       :1813][INFO    ][15081] Executing state service.running for [bind9]
2019-04-25 10:31:30,012 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15081] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-25 10:31:30,035 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15081] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-25 10:31:30,055 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15081] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-25 10:31:30,073 [salt.state       :300 ][INFO    ][15081] The service bind9 is already running
2019-04-25 10:31:30,073 [salt.state       :1951][INFO    ][15081] Completed state [bind9] at time 10:31:30.073703 duration_in_ms=63.296
2019-04-25 10:31:30,077 [salt.state       :1780][INFO    ][15081] Running state [apache2] at time 10:31:30.077823
2019-04-25 10:31:30,078 [salt.state       :1813][INFO    ][15081] Executing state service.running for [apache2]
2019-04-25 10:31:30,079 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15081] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-25 10:31:30,098 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15081] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-25 10:31:30,116 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15081] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-25 10:31:30,140 [salt.state       :300 ][INFO    ][15081] The service apache2 is already running
2019-04-25 10:31:30,140 [salt.state       :1951][INFO    ][15081] Completed state [apache2] at time 10:31:30.140839 duration_in_ms=63.016
2019-04-25 10:31:30,142 [salt.state       :1780][INFO    ][15081] Running state [maasng.wait_for_http_code] at time 10:31:30.142546
2019-04-25 10:31:30,143 [salt.state       :1813][INFO    ][15081] Executing state module.run for [maasng.wait_for_http_code]
2019-04-25 10:31:30,143 [salt.utils.decorators:613 ][WARNING ][15081] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 10:31:30,242 [salt.state       :300 ][INFO    ][15081] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-25 10:31:30,243 [salt.state       :1951][INFO    ][15081] Completed state [maasng.wait_for_http_code] at time 10:31:30.243861 duration_in_ms=101.316
2019-04-25 10:31:30,245 [salt.state       :1780][INFO    ][15081] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 10:31:30.244986
2019-04-25 10:31:30,245 [salt.state       :1813][INFO    ][15081] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-25 10:31:30,246 [salt.state       :300 ][INFO    ][15081] /var/lib/maas/.setup_admin exists
2019-04-25 10:31:30,247 [salt.state       :1951][INFO    ][15081] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 10:31:30.247471 duration_in_ms=2.486
2019-04-25 10:31:30,248 [salt.state       :1780][INFO    ][15081] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:31:30.248544
2019-04-25 10:31:30,249 [salt.state       :1813][INFO    ][15081] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-25 10:31:30,250 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15081] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-25 10:31:32,072 [salt.state       :300 ][INFO    ][15081] {'pid': 15134, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-25 10:31:32,074 [salt.state       :1951][INFO    ][15081] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:31:32.073973 duration_in_ms=1825.427
2019-04-25 10:31:32,083 [salt.state       :1780][INFO    ][15081] Running state [maas_region_boot_source_resources_mirror] at time 10:31:32.083001
2019-04-25 10:31:32,083 [salt.state       :1813][INFO    ][15081] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-25 10:31:32,167 [salt.state       :300 ][INFO    ][15081] {'changes': {}}
2019-04-25 10:31:32,167 [salt.state       :1951][INFO    ][15081] Completed state [maas_region_boot_source_resources_mirror] at time 10:31:32.167496 duration_in_ms=84.494
2019-04-25 10:31:32,168 [salt.state       :1780][INFO    ][15081] Running state [maasng.boot_resources_import] at time 10:31:32.168602
2019-04-25 10:31:32,169 [salt.state       :1813][INFO    ][15081] Executing state module.run for [maasng.boot_resources_import]
2019-04-25 10:31:32,169 [salt.utils.decorators:613 ][WARNING ][15081] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 10:31:32,252 [salt.loaded.ext.module.maasng:1600][INFO    ][15081] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-25 10:31:37,452 [salt.loaded.ext.module.maasng:1600][INFO    ][15081] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-25 10:31:39,437 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425103139429459
2019-04-25 10:31:39,457 [salt.minion      :1432][INFO    ][15169] Starting a new job with PID 15169
2019-04-25 10:31:39,488 [salt.minion      :1711][INFO    ][15169] Returning information for job: 20190425103139429459
2019-04-25 10:31:42,550 [salt.state       :300 ][INFO    ][15081] {'ret': True}
2019-04-25 10:31:42,551 [salt.state       :1951][INFO    ][15081] Completed state [maasng.boot_resources_import] at time 10:31:42.550930 duration_in_ms=10382.327
2019-04-25 10:31:42,552 [salt.state       :1780][INFO    ][15081] Running state [maas_region_boot_sources_selection_xenial] at time 10:31:42.552195
2019-04-25 10:31:42,552 [salt.state       :1813][INFO    ][15081] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-25 10:31:42,720 [salt.state       :300 ][INFO    ][15081] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-25 10:31:42,720 [salt.state       :1951][INFO    ][15081] Completed state [maas_region_boot_sources_selection_xenial] at time 10:31:42.720651 duration_in_ms=168.455
2019-04-25 10:31:42,722 [salt.state       :1780][INFO    ][15081] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 10:31:42.721998
2019-04-25 10:31:42,722 [salt.state       :1813][INFO    ][15081] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-25 10:31:42,724 [salt.utils.decorators:613 ][WARNING ][15081] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 10:31:42,725 [salt.loaded.ext.module.maasng:1771][INFO    ][15081] boot-sources sync initiated for ALL Rack's
2019-04-25 10:31:43,661 [salt.state       :300 ][INFO    ][15081] {'ret': True}
2019-04-25 10:31:43,661 [salt.state       :1951][INFO    ][15081] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 10:31:43.661670 duration_in_ms=939.672
2019-04-25 10:31:43,664 [salt.state       :1780][INFO    ][15081] Running state [maas.process_maas_config] at time 10:31:43.664412
2019-04-25 10:31:43,664 [salt.state       :1813][INFO    ][15081] Executing state module.run for [maas.process_maas_config]
2019-04-25 10:31:43,665 [salt.utils.decorators:613 ][WARNING ][15081] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 10:31:43,665 [salt.loaded.ext.module.maas:92  ][INFO    ][15081] maasconfig name=enable_http_proxy value=True
2019-04-25 10:31:43,715 [salt.loaded.ext.module.maas:92  ][INFO    ][15081] maasconfig name=upstream_dns value=8.8.8.8
2019-04-25 10:31:45,204 [salt.loaded.ext.module.maas:92  ][INFO    ][15081] maasconfig name=commissioning_distro_series value=xenial
2019-04-25 10:31:45,252 [salt.loaded.ext.module.maas:92  ][INFO    ][15081] maasconfig name=default_osystem value=ubuntu
2019-04-25 10:31:45,294 [salt.loaded.ext.module.maas:92  ][INFO    ][15081] maasconfig name=active_discovery_interval value=600
2019-04-25 10:31:45,341 [salt.loaded.ext.module.maas:92  ][INFO    ][15081] maasconfig name=dnssec_validation value=no
2019-04-25 10:31:45,382 [salt.loaded.ext.module.maas:92  ][INFO    ][15081] maasconfig name=maas_name value=mas01
2019-04-25 10:31:45,420 [salt.loaded.ext.module.maas:92  ][INFO    ][15081] maasconfig name=network_discovery value=enabled
2019-04-25 10:31:45,500 [salt.loaded.ext.module.maas:92  ][INFO    ][15081] maasconfig name=enable_third_party_drivers value=True
2019-04-25 10:31:45,538 [salt.loaded.ext.module.maas:92  ][INFO    ][15081] maasconfig name=default_storage_layout value=lvm
2019-04-25 10:31:45,585 [salt.loaded.ext.module.maas:92  ][INFO    ][15081] maasconfig name=ntp_external_only value=True
2019-04-25 10:31:45,623 [salt.loaded.ext.module.maas:92  ][INFO    ][15081] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-25 10:31:45,667 [salt.loaded.ext.module.maas:92  ][INFO    ][15081] maasconfig name=default_distro_series value=xenial
2019-04-25 10:31:45,716 [salt.loaded.ext.module.maas:92  ][INFO    ][15081] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-04-25 10:31:45,812 [salt.state       :300 ][INFO    ][15081] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-04-25 10:31:45,813 [salt.state       :1951][INFO    ][15081] Completed state [maas.process_maas_config] at time 10:31:45.812976 duration_in_ms=2148.564
2019-04-25 10:31:45,813 [salt.state       :1780][INFO    ][15081] Running state [pxe_admin] at time 10:31:45.813583
2019-04-25 10:31:45,813 [salt.state       :1813][INFO    ][15081] Executing state maasng.fabric_present for [pxe_admin]
2019-04-25 10:31:45,863 [salt.loaded.ext.module.maasng:945 ][INFO    ][15081] [{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': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cqxxgq', 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-04-25 10:31:45,917 [salt.loaded.ext.module.maasng:1008][WARNING ][15081] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-25 10:31:45,917 [salt.loaded.ext.module.maasng:1011][WARNING ][15081] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-25 10:31:45,965 [salt.state       :300 ][INFO    ][15081] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-25 10:31:45,965 [salt.state       :1951][INFO    ][15081] Completed state [pxe_admin] at time 10:31:45.965405 duration_in_ms=151.822
2019-04-25 10:31:45,965 [salt.state       :1780][INFO    ][15081] Running state [vlan 0] at time 10:31:45.965732
2019-04-25 10:31:45,966 [salt.state       :1813][INFO    ][15081] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-25 10:31:46,016 [salt.loaded.ext.module.maasng:945 ][INFO    ][15081] [{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'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], 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'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'cqxxgq', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'id': 1}]
2019-04-25 10:31:46,097 [salt.loaded.ext.module.maasng:945 ][INFO    ][15081] [{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'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], 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'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'cqxxgq', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'id': 1}]
2019-04-25 10:31:46,307 [salt.loaded.ext.module.maasng:945 ][INFO    ][15081] [{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'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], 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'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'cqxxgq', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'id': 1}]
2019-04-25 10:31:46,382 [salt.state       :300 ][INFO    ][15081] {'new': 'Vlan untagged was updated'}
2019-04-25 10:31:46,382 [salt.state       :1951][INFO    ][15081] Completed state [vlan 0] at time 10:31:46.382587 duration_in_ms=416.853
2019-04-25 10:31:46,385 [salt.state       :1780][INFO    ][15081] Running state [192.168.11.0/24] at time 10:31:46.385637
2019-04-25 10:31:46,386 [salt.state       :1813][INFO    ][15081] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-25 10:31:46,542 [salt.loaded.ext.module.maasng:945 ][INFO    ][15081] [{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'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], 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'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], 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'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'cqxxgq', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'id': 1}]
2019-04-25 10:31:46,542 [salt.loaded.ext.module.maasng:1235][WARNING ][15081] Ignoring parameter vlan:0
2019-04-25 10:31:46,598 [salt.state       :300 ][INFO    ][15081] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-25 10:31:46,598 [salt.state       :1951][INFO    ][15081] Completed state [192.168.11.0/24] at time 10:31:46.598428 duration_in_ms=212.79
2019-04-25 10:31:46,600 [salt.state       :1780][INFO    ][15081] Running state [maas_create_iprange_1] at time 10:31:46.600625
2019-04-25 10:31:46,600 [salt.state       :1813][INFO    ][15081] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-25 10:31:46,646 [salt.state       :300 ][INFO    ][15081] Iprange maas_create_iprange_1 already exist.
2019-04-25 10:31:46,648 [salt.state       :1951][INFO    ][15081] Completed state [maas_create_iprange_1] at time 10:31:46.648293 duration_in_ms=47.667
2019-04-25 10:31:46,648 [salt.state       :1780][INFO    ][15081] Running state [vlan 0] at time 10:31:46.648720
2019-04-25 10:31:46,649 [salt.state       :1813][INFO    ][15081] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-25 10:31:46,693 [salt.loaded.ext.module.maasng:945 ][INFO    ][15081] [{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'cqxxgq', 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-04-25 10:31:46,790 [salt.loaded.ext.module.maasng:945 ][INFO    ][15081] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cqxxgq', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-04-25 10:31:47,012 [salt.loaded.ext.module.maasng:945 ][INFO    ][15081] [{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'cqxxgq', 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-04-25 10:31:47,096 [salt.state       :300 ][INFO    ][15081] {'new': 'Vlan untagged was updated'}
2019-04-25 10:31:47,097 [salt.state       :1951][INFO    ][15081] Completed state [vlan 0] at time 10:31:47.097035 duration_in_ms=448.314
2019-04-25 10:31:47,097 [salt.state       :1780][INFO    ][15081] Running state [opnfv] at time 10:31:47.097636
2019-04-25 10:31:47,097 [salt.state       :1813][INFO    ][15081] Executing state maasng.sshkey_present for [opnfv]
2019-04-25 10:31:47,158 [salt.loaded.ext.module.maasng:1903][INFO    ][15081] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-04-25 10:31:47,159 [salt.state       :300 ][INFO    ][15081] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-04-25 10:31:47,159 [salt.state       :1951][INFO    ][15081] Completed state [opnfv] at time 10:31:47.159447 duration_in_ms=61.81
2019-04-25 10:31:47,162 [salt.minion      :1711][INFO    ][15081] Returning information for job: 20190425103124320180
2019-04-25 10:31:47,922 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command state.apply with jid 20190425103147912733
2019-04-25 10:31:47,938 [salt.minion      :1432][INFO    ][15523] Starting a new job with PID 15523
2019-04-25 10:31:53,844 [salt.state       :915 ][INFO    ][15523] Loading fresh modules for state activity
2019-04-25 10:31:53,941 [salt.state       :1780][INFO    ][15523] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:31:53.941014
2019-04-25 10:31:53,941 [salt.state       :1813][INFO    ][15523] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-25 10:31:53,944 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15523] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-25 10:31:55,767 [salt.state       :300 ][INFO    ][15523] {'pid': 15559, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-25 10:31:55,768 [salt.state       :1951][INFO    ][15523] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:31:55.768290 duration_in_ms=1827.277
2019-04-25 10:31:55,769 [salt.state       :1780][INFO    ][15523] Running state [maas.process_machines] at time 10:31:55.769752
2019-04-25 10:31:55,770 [salt.state       :1813][INFO    ][15523] Executing state module.run for [maas.process_machines]
2019-04-25 10:31:55,770 [salt.utils.decorators:613 ][WARNING ][15523] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 10:31:56,321 [salt.loaded.ext.module.maas:412 ][WARNING ][15523] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-25 10:31:56,322 [salt.loaded.ext.module.maas:92  ][INFO    ][15523] 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=emsqep architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-25 10:31:57,445 [salt.loaded.ext.module.maas:412 ][WARNING ][15523] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-25 10:31:57,448 [salt.loaded.ext.module.maas:92  ][INFO    ][15523] 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=cyq8w6 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-25 10:31:58,543 [salt.loaded.ext.module.maas:412 ][WARNING ][15523] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-25 10:31:58,544 [salt.loaded.ext.module.maas:92  ][INFO    ][15523] 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=ryq7cc architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-25 10:31:59,775 [salt.loaded.ext.module.maas:412 ][WARNING ][15523] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-25 10:31:59,776 [salt.loaded.ext.module.maas:92  ][INFO    ][15523] 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=nxp34g architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-25 10:32:00,845 [salt.loaded.ext.module.maas:412 ][WARNING ][15523] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-25 10:32:00,846 [salt.loaded.ext.module.maas:92  ][INFO    ][15523] 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=a4x87s architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-25 10:32:01,915 [salt.state       :300 ][INFO    ][15523] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-25 10:32:01,916 [salt.state       :1951][INFO    ][15523] Completed state [maas.process_machines] at time 10:32:01.916200 duration_in_ms=6146.446
2019-04-25 10:32:01,920 [salt.minion      :1711][INFO    ][15523] Returning information for job: 20190425103147912733
2019-04-25 10:32:35,742 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command state.apply with jid 20190425103235731603
2019-04-25 10:32:35,759 [salt.minion      :1432][INFO    ][15841] Starting a new job with PID 15841
2019-04-25 10:32:41,639 [salt.state       :915 ][INFO    ][15841] Loading fresh modules for state activity
2019-04-25 10:32:41,740 [salt.state       :1780][INFO    ][15841] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:32:41.740313
2019-04-25 10:32:41,740 [salt.state       :1813][INFO    ][15841] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-25 10:32:41,742 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15841] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-25 10:32:43,555 [salt.state       :300 ][INFO    ][15841] {'pid': 15849, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-25 10:32:43,556 [salt.state       :1951][INFO    ][15841] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:32:43.556776 duration_in_ms=1816.463
2019-04-25 10:32:43,559 [salt.state       :1780][INFO    ][15841] Running state [maas.wait_for_machine_status] at time 10:32:43.559658
2019-04-25 10:32:43,560 [salt.state       :1813][INFO    ][15841] Executing state module.run for [maas.wait_for_machine_status]
2019-04-25 10:32:43,561 [salt.utils.decorators:613 ][WARNING ][15841] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 10:32:46,464 [salt.state       :300 ][INFO    ][15841] {'ret': True}
2019-04-25 10:32:46,464 [salt.state       :1951][INFO    ][15841] Completed state [maas.wait_for_machine_status] at time 10:32:46.464567 duration_in_ms=2904.908
2019-04-25 10:32:46,469 [salt.minion      :1711][INFO    ][15841] Returning information for job: 20190425103235731603
2019-04-25 10:32:47,133 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command state.apply with jid 20190425103247125943
2019-04-25 10:32:47,153 [salt.minion      :1432][INFO    ][15863] Starting a new job with PID 15863
2019-04-25 10:32:48,154 [salt.state       :915 ][INFO    ][15863] Loading fresh modules for state activity
2019-04-25 10:32:48,302 [salt.state       :1780][INFO    ][15863] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:32:48.302466
2019-04-25 10:32:48,303 [salt.state       :1813][INFO    ][15863] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-25 10:32:48,305 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15863] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-25 10:32:50,130 [salt.state       :300 ][INFO    ][15863] {'pid': 15870, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-25 10:32:50,131 [salt.state       :1951][INFO    ][15863] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:32:50.130702 duration_in_ms=1828.234
2019-04-25 10:32:50,135 [salt.state       :1780][INFO    ][15863] Running state [maas_machines_storage_cmp002_lvm] at time 10:32:50.135630
2019-04-25 10:32:50,136 [salt.state       :1813][INFO    ][15863] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-25 10:32:50,613 [salt.state       :300 ][INFO    ][15863] Machine cmp002 is not in Ready state.
2019-04-25 10:32:50,613 [salt.state       :1951][INFO    ][15863] Completed state [maas_machines_storage_cmp002_lvm] at time 10:32:50.613474 duration_in_ms=477.843
2019-04-25 10:32:50,613 [salt.state       :1780][INFO    ][15863] Running state [maas_machines_storage_cmp001_lvm] at time 10:32:50.613871
2019-04-25 10:32:50,614 [salt.state       :1813][INFO    ][15863] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-25 10:32:51,092 [salt.state       :300 ][INFO    ][15863] Machine cmp001 is not in Ready state.
2019-04-25 10:32:51,093 [salt.state       :1951][INFO    ][15863] Completed state [maas_machines_storage_cmp001_lvm] at time 10:32:51.092975 duration_in_ms=479.103
2019-04-25 10:32:51,095 [salt.minion      :1711][INFO    ][15863] Returning information for job: 20190425103247125943
2019-04-25 10:32:51,792 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command state.apply with jid 20190425103251785116
2019-04-25 10:32:51,804 [salt.minion      :1432][INFO    ][15895] Starting a new job with PID 15895
2019-04-25 10:32:52,747 [salt.state       :915 ][INFO    ][15895] Loading fresh modules for state activity
2019-04-25 10:32:52,833 [salt.state       :1780][INFO    ][15895] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:32:52.833829
2019-04-25 10:32:52,834 [salt.state       :1813][INFO    ][15895] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-25 10:32:52,836 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15895] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-25 10:32:54,650 [salt.state       :300 ][INFO    ][15895] {'pid': 15902, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-25 10:32:54,651 [salt.state       :1951][INFO    ][15895] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:32:54.650754 duration_in_ms=1816.926
2019-04-25 10:32:54,653 [salt.state       :1780][INFO    ][15895] Running state [maas.deploy_machines] at time 10:32:54.653068
2019-04-25 10:32:54,653 [salt.state       :1813][INFO    ][15895] Executing state module.run for [maas.deploy_machines]
2019-04-25 10:32:54,653 [salt.utils.decorators:613 ][WARNING ][15895] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 10:32:55,121 [salt.loaded.ext.module.maas:684 ][INFO    ][15895] deploymachines hwe_kernel=ga-16.04 system_id=a4x87s distro_series=xenial
2019-04-25 10:32:57,297 [salt.state       :300 ][INFO    ][15895] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-04-25 10:32:57,298 [salt.state       :1951][INFO    ][15895] Completed state [maas.deploy_machines] at time 10:32:57.298355 duration_in_ms=2645.285
2019-04-25 10:32:57,301 [salt.minion      :1711][INFO    ][15895] Returning information for job: 20190425103251785116
2019-04-25 10:32:58,022 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command state.apply with jid 20190425103257979064
2019-04-25 10:32:58,049 [salt.minion      :1432][INFO    ][15964] Starting a new job with PID 15964
2019-04-25 10:33:03,901 [salt.state       :915 ][INFO    ][15964] Loading fresh modules for state activity
2019-04-25 10:33:04,000 [salt.state       :1780][INFO    ][15964] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:33:03.998752
2019-04-25 10:33:04,000 [salt.state       :1813][INFO    ][15964] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-25 10:33:04,002 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15964] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-25 10:33:06,098 [salt.state       :300 ][INFO    ][15964] {'pid': 15975, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-25 10:33:06,100 [salt.state       :1951][INFO    ][15964] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:33:06.100509 duration_in_ms=2101.758
2019-04-25 10:33:06,103 [salt.state       :1780][INFO    ][15964] Running state [maas.wait_for_machine_status] at time 10:33:06.103565
2019-04-25 10:33:06,104 [salt.state       :1813][INFO    ][15964] Executing state module.run for [maas.wait_for_machine_status]
2019-04-25 10:33:06,104 [salt.utils.decorators:613 ][WARNING ][15964] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-25 10:33:08,913 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.20640802s left)
2019-04-25 10:33:13,066 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425103313051470
2019-04-25 10:33:13,082 [salt.minion      :1432][INFO    ][16045] Starting a new job with PID 16045
2019-04-25 10:33:13,111 [salt.minion      :1711][INFO    ][16045] Returning information for job: 20190425103313051470
2019-04-25 10:33:41,587 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.53299093s left)
2019-04-25 10:33:43,262 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425103343253197
2019-04-25 10:33:43,282 [salt.minion      :1432][INFO    ][16069] Starting a new job with PID 16069
2019-04-25 10:33:43,309 [salt.minion      :1711][INFO    ][16069] Returning information for job: 20190425103343253197
2019-04-25 10:34:13,285 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425103413277054
2019-04-25 10:34:13,303 [salt.minion      :1432][INFO    ][16145] Starting a new job with PID 16145
2019-04-25 10:34:13,331 [salt.minion      :1711][INFO    ][16145] Returning information for job: 20190425103413277054
2019-04-25 10:34:14,272 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.84780192s left)
2019-04-25 10:34:43,493 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425103443481099
2019-04-25 10:34:43,511 [salt.minion      :1432][INFO    ][16166] Starting a new job with PID 16166
2019-04-25 10:34:43,544 [salt.minion      :1711][INFO    ][16166] Returning information for job: 20190425103443481099
2019-04-25 10:34:46,966 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.15431595s left)
2019-04-25 10:35:13,533 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425103513525102
2019-04-25 10:35:13,553 [salt.minion      :1432][INFO    ][16261] Starting a new job with PID 16261
2019-04-25 10:35:13,583 [salt.minion      :1711][INFO    ][16261] Returning information for job: 20190425103513525102
2019-04-25 10:35:19,877 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.24319696s left)
2019-04-25 10:35:43,617 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425103543601452
2019-04-25 10:35:43,634 [salt.minion      :1432][INFO    ][16289] Starting a new job with PID 16289
2019-04-25 10:35:43,664 [salt.minion      :1711][INFO    ][16289] Returning information for job: 20190425103543601452
2019-04-25 10:35:52,708 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.41150093s left)
2019-04-25 10:36:13,689 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425103613675324
2019-04-25 10:36:13,712 [salt.minion      :1432][INFO    ][16367] Starting a new job with PID 16367
2019-04-25 10:36:13,741 [salt.minion      :1711][INFO    ][16367] Returning information for job: 20190425103613675324
2019-04-25 10:36:25,381 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.73879004s left)
2019-04-25 10:36:43,772 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425103643761878
2019-04-25 10:36:43,788 [salt.minion      :1432][INFO    ][16394] Starting a new job with PID 16394
2019-04-25 10:36:43,821 [salt.minion      :1711][INFO    ][16394] Returning information for job: 20190425103643761878
2019-04-25 10:36:58,138 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.98226094s left)
2019-04-25 10:37:13,853 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425103713844985
2019-04-25 10:37:13,867 [salt.minion      :1432][INFO    ][16472] Starting a new job with PID 16472
2019-04-25 10:37:13,892 [salt.minion      :1711][INFO    ][16472] Returning information for job: 20190425103713844985
2019-04-25 10:37:30,835 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1985.28495002s left)
2019-04-25 10:37:43,881 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425103743868604
2019-04-25 10:37:43,896 [salt.minion      :1432][INFO    ][16549] Starting a new job with PID 16549
2019-04-25 10:37:43,925 [salt.minion      :1711][INFO    ][16549] Returning information for job: 20190425103743868604
2019-04-25 10:38:03,292 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1952.82821894s left)
2019-04-25 10:38:13,930 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425103813922119
2019-04-25 10:38:13,944 [salt.minion      :1432][INFO    ][16742] Starting a new job with PID 16742
2019-04-25 10:38:13,972 [salt.minion      :1711][INFO    ][16742] Returning information for job: 20190425103813922119
2019-04-25 10:38:35,930 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1920.18977499s left)
2019-04-25 10:38:44,141 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425103844132479
2019-04-25 10:38:44,157 [salt.minion      :1432][INFO    ][16808] Starting a new job with PID 16808
2019-04-25 10:38:44,185 [salt.minion      :1711][INFO    ][16808] Returning information for job: 20190425103844132479
2019-04-25 10:39:08,300 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1887.81947207s left)
2019-04-25 10:39:14,361 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425103914354263
2019-04-25 10:39:14,376 [salt.minion      :1432][INFO    ][16895] Starting a new job with PID 16895
2019-04-25 10:39:14,406 [salt.minion      :1711][INFO    ][16895] Returning information for job: 20190425103914354263
2019-04-25 10:39:40,764 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1855.35587692s left)
2019-04-25 10:39:44,574 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425103944566352
2019-04-25 10:39:44,587 [salt.minion      :1432][INFO    ][16931] Starting a new job with PID 16931
2019-04-25 10:39:44,613 [salt.minion      :1711][INFO    ][16931] Returning information for job: 20190425103944566352
2019-04-25 10:40:13,327 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1822.79341793s left)
2019-04-25 10:40:14,592 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425104014584901
2019-04-25 10:40:14,605 [salt.minion      :1432][INFO    ][17076] Starting a new job with PID 17076
2019-04-25 10:40:14,635 [salt.minion      :1711][INFO    ][17076] Returning information for job: 20190425104014584901
2019-04-25 10:40:44,798 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425104044791094
2019-04-25 10:40:44,813 [salt.minion      :1432][INFO    ][17121] Starting a new job with PID 17121
2019-04-25 10:40:44,841 [salt.minion      :1711][INFO    ][17121] Returning information for job: 20190425104044791094
2019-04-25 10:40:45,845 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1790.27476907s left)
2019-04-25 10:41:14,822 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425104114814430
2019-04-25 10:41:14,839 [salt.minion      :1432][INFO    ][17265] Starting a new job with PID 17265
2019-04-25 10:41:14,866 [salt.minion      :1711][INFO    ][17265] Returning information for job: 20190425104114814430
2019-04-25 10:41:18,189 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1757.93039012s left)
2019-04-25 10:41:45,041 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425104145034395
2019-04-25 10:41:45,055 [salt.minion      :1432][INFO    ][17315] Starting a new job with PID 17315
2019-04-25 10:41:45,081 [salt.minion      :1711][INFO    ][17315] Returning information for job: 20190425104145034395
2019-04-25 10:41:50,644 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1725.47572803s left)
2019-04-25 10:42:15,056 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425104215048489
2019-04-25 10:42:15,072 [salt.minion      :1432][INFO    ][17395] Starting a new job with PID 17395
2019-04-25 10:42:15,103 [salt.minion      :1711][INFO    ][17395] Returning information for job: 20190425104215048489
2019-04-25 10:42:23,118 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1693.00179291s left)
2019-04-25 10:42:45,074 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425104245067519
2019-04-25 10:42:45,090 [salt.minion      :1432][INFO    ][17420] Starting a new job with PID 17420
2019-04-25 10:42:45,118 [salt.minion      :1711][INFO    ][17420] Returning information for job: 20190425104245067519
2019-04-25 10:42:55,455 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1660.66540313s left)
2019-04-25 10:43:15,106 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425104315099612
2019-04-25 10:43:15,122 [salt.minion      :1432][INFO    ][17497] Starting a new job with PID 17497
2019-04-25 10:43:15,150 [salt.minion      :1711][INFO    ][17497] Returning information for job: 20190425104315099612
2019-04-25 10:43:27,922 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1628.19794202s left)
2019-04-25 10:43:45,140 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425104345133222
2019-04-25 10:43:45,154 [salt.minion      :1432][INFO    ][17524] Starting a new job with PID 17524
2019-04-25 10:43:45,181 [salt.minion      :1711][INFO    ][17524] Returning information for job: 20190425104345133222
2019-04-25 10:44:00,319 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1595.80138707s left)
2019-04-25 10:44:15,178 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425104415170336
2019-04-25 10:44:15,194 [salt.minion      :1432][INFO    ][17602] Starting a new job with PID 17602
2019-04-25 10:44:15,223 [salt.minion      :1711][INFO    ][17602] Returning information for job: 20190425104415170336
2019-04-25 10:44:32,664 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1563.45575809s left)
2019-04-25 10:44:45,248 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425104445241476
2019-04-25 10:44:45,262 [salt.minion      :1432][INFO    ][17627] Starting a new job with PID 17627
2019-04-25 10:44:45,289 [salt.minion      :1711][INFO    ][17627] Returning information for job: 20190425104445241476
2019-04-25 10:45:07,031 [salt.loaded.ext.module.maas:1023][INFO    ][15964] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1529.08846092s left)
2019-04-25 10:45:15,374 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command saltutil.find_job with jid 20190425104515361625
2019-04-25 10:45:15,394 [salt.minion      :1432][INFO    ][17903] Starting a new job with PID 17903
2019-04-25 10:45:15,422 [salt.minion      :1711][INFO    ][17903] Returning information for job: 20190425104515361625
2019-04-25 10:45:39,905 [salt.state       :300 ][INFO    ][15964] {'ret': True}
2019-04-25 10:45:39,905 [salt.state       :1951][INFO    ][15964] Completed state [maas.wait_for_machine_status] at time 10:45:39.905586 duration_in_ms=753802.02
2019-04-25 10:45:39,908 [salt.minion      :1711][INFO    ][15964] Returning information for job: 20190425103257979064
2019-04-25 10:46:05,326 [salt.utils.schedule:1377][INFO    ][5831] Running scheduled job: __mine_interval
2019-04-25 11:46:05,326 [salt.utils.schedule:1377][INFO    ][5831] Running scheduled job: __mine_interval
2019-04-25 12:24:35,210 [salt.minion      :1308][INFO    ][5831] User sudo_ubuntu Executing command cp.push_dir with jid 20190425122435200420
2019-04-25 12:24:35,229 [salt.minion      :1432][INFO    ][28088] Starting a new job with PID 28088
