2019-06-18 20:11:28,667 [salt.minion      :870 ][ERROR   ][387] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-18 20:12:54,419 [salt.utils.decorators:613 ][WARNING ][2145] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 20:12:55,172 [salt.utils.decorators:613 ][WARNING ][2145] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 20:12:58,255 [salt.loaded.int.states.file:2298][WARNING ][2490] 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-06-18 20:13:25,040 [salt.state       :2022][WARNING ][2886] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-18 20:13:28,173 [salt.utils.decorators:613 ][WARNING ][2886] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 20:13:42,149 [salt.utils.decorators:613 ][WARNING ][2886] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 20:14:09,032 [salt.utils.decorators:613 ][WARNING ][2886] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 20:14:10,029 [salt.utils.decorators:613 ][WARNING ][2886] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 20:14:12,676 [salt.loaded.ext.module.maasng:1008][WARNING ][2886] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-06-18 20:14:12,676 [salt.loaded.ext.module.maasng:1011][WARNING ][2886] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-06-18 20:14:13,539 [salt.loaded.ext.module.maasng:1235][WARNING ][2886] Ignoring parameter vlan:0
2019-06-18 20:14:25,583 [salt.utils.decorators:613 ][WARNING ][5246] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 20:14:25,639 [salt.loaded.ext.module.maas:412 ][WARNING ][5246] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 20:14:27,035 [salt.loaded.ext.module.maas:412 ][WARNING ][5246] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 20:14:28,220 [salt.loaded.ext.module.maas:412 ][WARNING ][5246] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 20:14:29,508 [salt.loaded.ext.module.maas:412 ][WARNING ][5246] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 20:14:30,761 [salt.loaded.ext.module.maas:412 ][WARNING ][5246] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 20:14:34,774 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5849] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-18 20:14:34,808 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5849] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-18 20:14:34,865 [salt.utils.parsers:1051][WARNING ][387] Minion received a SIGTERM. Exiting.
2019-06-18 20:14:35,904 [salt.cli.daemons :293 ][INFO    ][5906] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-06-18 20:14:36,027 [salt.cli.daemons :82  ][INFO    ][5906] Starting up the Salt Minion
2019-06-18 20:14:36,028 [salt.utils.event :1017][INFO    ][5906] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-06-18 20:14:37,260 [salt.minion      :976 ][INFO    ][5906] Creating minion process manager
2019-06-18 20:14:39,429 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5906] Executing command ['date', '+%z'] in directory '/root'
2019-06-18 20:14:39,460 [salt.utils.schedule:568 ][INFO    ][5906] Updating job settings for scheduled job: __mine_interval
2019-06-18 20:14:39,463 [salt.minion      :1108][INFO    ][5906] Added mine.update to scheduler
2019-06-18 20:14:39,467 [salt.minion      :1975][INFO    ][5906] Minion is starting as user 'root'
2019-06-18 20:14:39,488 [salt.minion      :2336][INFO    ][5906] Minion is ready to receive requests!
2019-06-18 20:15:03,083 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command state.apply with jid 20190618201503061074
2019-06-18 20:15:03,111 [salt.minion      :1432][INFO    ][5999] Starting a new job with PID 5999
2019-06-18 20:15:11,062 [salt.state       :915 ][INFO    ][5999] Loading fresh modules for state activity
2019-06-18 20:15:11,123 [salt.fileclient  :1219][INFO    ][5999] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-18 20:15:11,178 [salt.state       :1780][INFO    ][5999] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:11.178386
2019-06-18 20:15:11,178 [salt.state       :1813][INFO    ][5999] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 20:15:11,180 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5999] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 20:15:12,962 [salt.state       :300 ][INFO    ][5999] {'pid': 6006, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 20:15:12,964 [salt.state       :1951][INFO    ][5999] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:12.963990 duration_in_ms=1785.603
2019-06-18 20:15:12,968 [salt.state       :1780][INFO    ][5999] Running state [maas.wait_for_machine_status] at time 20:15:12.968288
2019-06-18 20:15:12,969 [salt.state       :1813][INFO    ][5999] Executing state module.run for [maas.wait_for_machine_status]
2019-06-18 20:15:12,970 [salt.utils.decorators:613 ][WARNING ][5999] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 20:15:13,901 [salt.loaded.ext.module.maas:1023][INFO    ][5999] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.08164001s left)
2019-06-18 20:15:18,156 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618201518139774
2019-06-18 20:15:18,186 [salt.minion      :1432][INFO    ][6031] Starting a new job with PID 6031
2019-06-18 20:15:18,209 [salt.minion      :1711][INFO    ][6031] Returning information for job: 20190618201518139774
2019-06-18 20:15:44,796 [salt.loaded.ext.module.maas:1023][INFO    ][5999] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.18660283s left)
2019-06-18 20:15:48,236 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618201548222704
2019-06-18 20:15:48,269 [salt.minion      :1432][INFO    ][6076] Starting a new job with PID 6076
2019-06-18 20:15:48,293 [salt.minion      :1711][INFO    ][6076] Returning information for job: 20190618201548222704
2019-06-18 20:16:15,610 [salt.loaded.ext.module.maas:1023][INFO    ][5999] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.37199092s left)
2019-06-18 20:16:18,333 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618201618317363
2019-06-18 20:16:18,361 [salt.minion      :1432][INFO    ][6105] Starting a new job with PID 6105
2019-06-18 20:16:18,384 [salt.minion      :1711][INFO    ][6105] Returning information for job: 20190618201618317363
2019-06-18 20:16:46,484 [salt.loaded.ext.module.maas:1023][INFO    ][5999] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.49849701s left)
2019-06-18 20:16:48,413 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618201648400518
2019-06-18 20:16:48,442 [salt.minion      :1432][INFO    ][6154] Starting a new job with PID 6154
2019-06-18 20:16:48,461 [salt.minion      :1711][INFO    ][6154] Returning information for job: 20190618201648400518
2019-06-18 20:17:17,619 [salt.loaded.ext.module.maas:1023][INFO    ][5999] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.3637898s left)
2019-06-18 20:17:18,513 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618201718499500
2019-06-18 20:17:18,539 [salt.minion      :1432][INFO    ][6218] Starting a new job with PID 6218
2019-06-18 20:17:18,560 [salt.minion      :1711][INFO    ][6218] Returning information for job: 20190618201718499500
2019-06-18 20:17:48,632 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618201748619942
2019-06-18 20:17:48,643 [salt.loaded.ext.module.maas:1023][INFO    ][5999] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.33978701s left)
2019-06-18 20:17:48,656 [salt.minion      :1432][INFO    ][6374] Starting a new job with PID 6374
2019-06-18 20:17:48,681 [salt.minion      :1711][INFO    ][6374] Returning information for job: 20190618201748619942
2019-06-18 20:18:18,750 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618201818732601
2019-06-18 20:18:18,774 [salt.minion      :1432][INFO    ][6440] Starting a new job with PID 6440
2019-06-18 20:18:18,799 [salt.minion      :1711][INFO    ][6440] Returning information for job: 20190618201818732601
2019-06-18 20:18:19,927 [salt.loaded.ext.module.maas:1023][INFO    ][5999] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.05549192s left)
2019-06-18 20:18:48,919 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618201848903841
2019-06-18 20:18:48,943 [salt.minion      :1432][INFO    ][6787] Starting a new job with PID 6787
2019-06-18 20:18:48,968 [salt.minion      :1711][INFO    ][6787] Returning information for job: 20190618201848903841
2019-06-18 20:18:51,123 [salt.loaded.ext.module.maas:1023][INFO    ][5999] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.85950685s left)
2019-06-18 20:19:19,017 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618201919004829
2019-06-18 20:19:19,051 [salt.minion      :1432][INFO    ][6878] Starting a new job with PID 6878
2019-06-18 20:19:19,078 [salt.minion      :1711][INFO    ][6878] Returning information for job: 20190618201919004829
2019-06-18 20:19:22,983 [salt.loaded.ext.module.maas:1023][INFO    ][5999] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.99955201s left)
2019-06-18 20:19:49,196 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618201949174825
2019-06-18 20:19:49,223 [salt.minion      :1432][INFO    ][7244] Starting a new job with PID 7244
2019-06-18 20:19:49,247 [salt.minion      :1711][INFO    ][7244] Returning information for job: 20190618201949174825
2019-06-18 20:19:55,472 [salt.loaded.ext.module.maas:1023][INFO    ][5999] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.50992584s left)
2019-06-18 20:20:19,327 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202019313536
2019-06-18 20:20:19,355 [salt.minion      :1432][INFO    ][7409] Starting a new job with PID 7409
2019-06-18 20:20:19,375 [salt.minion      :1711][INFO    ][7409] Returning information for job: 20190618202019313536
2019-06-18 20:20:27,759 [salt.loaded.ext.module.maas:1023][INFO    ][5999] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.22513199s left)
2019-06-18 20:20:49,473 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202049453029
2019-06-18 20:20:49,501 [salt.minion      :1432][INFO    ][7606] Starting a new job with PID 7606
2019-06-18 20:20:49,524 [salt.minion      :1711][INFO    ][7606] Returning information for job: 20190618202049453029
2019-06-18 20:21:00,042 [salt.loaded.ext.module.maas:1023][INFO    ][5999] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1152.94073296s left)
2019-06-18 20:21:19,615 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202119598529
2019-06-18 20:21:19,644 [salt.minion      :1432][INFO    ][7760] Starting a new job with PID 7760
2019-06-18 20:21:19,668 [salt.minion      :1711][INFO    ][7760] Returning information for job: 20190618202119598529
2019-06-18 20:21:33,068 [salt.state       :300 ][INFO    ][5999] {'ret': True}
2019-06-18 20:21:33,068 [salt.state       :1951][INFO    ][5999] Completed state [maas.wait_for_machine_status] at time 20:21:33.068546 duration_in_ms=380100.256
2019-06-18 20:21:33,073 [salt.minion      :1711][INFO    ][5999] Returning information for job: 20190618201503061074
2019-06-18 20:21:33,660 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command state.apply with jid 20190618202133646361
2019-06-18 20:21:33,684 [salt.minion      :1432][INFO    ][7869] Starting a new job with PID 7869
2019-06-18 20:21:41,835 [salt.state       :915 ][INFO    ][7869] Loading fresh modules for state activity
2019-06-18 20:21:41,892 [salt.fileclient  :1219][INFO    ][7869] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-18 20:21:41,998 [salt.state       :1780][INFO    ][7869] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:21:41.997933
2019-06-18 20:21:41,998 [salt.state       :1813][INFO    ][7869] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 20:21:41,999 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7869] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 20:21:43,866 [salt.state       :300 ][INFO    ][7869] {'pid': 7877, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 20:21:43,868 [salt.state       :1951][INFO    ][7869] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:21:43.867910 duration_in_ms=1869.977
2019-06-18 20:21:43,871 [salt.state       :1780][INFO    ][7869] Running state [maas_machines_storage_cmp002_lvm] at time 20:21:43.871457
2019-06-18 20:21:43,872 [salt.state       :1813][INFO    ][7869] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-18 20:21:45,094 [salt.loaded.ext.module.maasng:610 ][INFO    ][7869] ftwfwe
2019-06-18 20:21:45,095 [salt.loaded.ext.module.maasng:626 ][INFO    ][7869] sda
2019-06-18 20:21:45,690 [salt.loaded.ext.module.maasng:361 ][INFO    ][7869] ftwfwe
2019-06-18 20:21:45,784 [salt.loaded.ext.module.maasng:367 ][INFO    ][7869] [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'04fc9396-afdb-47fb-9532-d97c1bc0d5a4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ftwfwe', u'filesystem': {u'uuid': u'f5d27388-8a30-449e-bb43-a7a6797195c1', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/ftwfwe/blockdevices/1/partition/1'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/ftwfwe/blockdevices/1/', u'system_id': u'ftwfwe', 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'model': u'LOGICAL VOLUME', u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'block_size': 4096, u'available_size': 0, u'uuid': u'016d888d-2db6-4b69-b2f4-cda96387bcd3', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/ftwfwe/blockdevices/3/', u'system_id': u'ftwfwe', u'partition_table_type': None, u'filesystem': {u'uuid': u'4d50c169-83b4-46ed-9156-a1c8c6be2ae0', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'serial': None, u'size': 800097042432}]
2019-06-18 20:21:45,785 [salt.loaded.ext.module.maasng:632 ][INFO    ][7869] vgroot
2019-06-18 20:21:45,785 [salt.loaded.ext.module.maasng:635 ][INFO    ][7869] lvroot
2019-06-18 20:21:45,786 [salt.loaded.ext.module.maasng:639 ][INFO    ][7869] 107374182400
2019-06-18 20:21:46,415 [salt.loaded.ext.module.maasng:645 ][INFO    ][7869] {u'hwe_kernel': u'', u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ea8fns', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ea8fns', 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'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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ea8fns', 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'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'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'ftwfwe', 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/ftwfwe/interfaces/4/'}, u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/ftwfwe/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ftwfwe', 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'id': 1, u'partitions': [{u'size': 800101236736, u'uuid': u'b4fe8421-28fe-4831-b2fb-b99fe27cb83f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ftwfwe', u'filesystem': {u'mount_options': None, u'uuid': u'61a2e1b0-b116-4b4a-8fbc-bb4ef2b22b5b', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/ftwfwe/blockdevices/1/partition/6'}]}, {u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ftwfwe/blockdevices/11/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'76343536-623c-421d-9cc0-724ca58e7891', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ftwfwe', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'7040dac9-c8bf-4638-9ad1-23d1defd9f6c', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'partitions': []}], 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/ftwfwe/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'ftwfwe', 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'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ftwfwe/blockdevices/11/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'76343536-623c-421d-9cc0-724ca58e7891', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ftwfwe', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'7040dac9-c8bf-4638-9ad1-23d1defd9f6c', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'partitions': []}], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ftwfwe', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/ftwfwe/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ftwfwe', 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'id': 1, u'partitions': [{u'size': 800101236736, u'uuid': u'b4fe8421-28fe-4831-b2fb-b99fe27cb83f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ftwfwe', u'filesystem': {u'mount_options': None, u'uuid': u'61a2e1b0-b116-4b4a-8fbc-bb4ef2b22b5b', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/ftwfwe/blockdevices/1/partition/6'}]}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ea8fns', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ea8fns', 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'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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ea8fns', 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'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'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'ftwfwe', 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/ftwfwe/interfaces/4/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'ftwfwe', 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/ftwfwe/interfaces/14/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ea8fns', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ea8fns', 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'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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ea8fns', 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'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'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'ftwfwe', 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/ftwfwe/interfaces/13/'}, {u'name': u'ens2f1', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'ftwfwe', 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/ftwfwe/interfaces/15/'}, {u'name': u'ens2f0', u'links': [{u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'ftwfwe', 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/ftwfwe/interfaces/11/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'ftwfwe', 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/ftwfwe/interfaces/12/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/ftwfwe/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ftwfwe', 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'id': 1, u'partitions': [{u'size': 800101236736, u'uuid': u'b4fe8421-28fe-4831-b2fb-b99fe27cb83f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ftwfwe', u'filesystem': {u'mount_options': None, u'uuid': u'61a2e1b0-b116-4b4a-8fbc-bb4ef2b22b5b', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/ftwfwe/blockdevices/1/partition/6'}]}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-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'memory_test_status': -1}
2019-06-18 20:21:46,419 [salt.state       :300 ][INFO    ][7869] {'new': {'storage_layout': 'lvm'}}
2019-06-18 20:21:46,419 [salt.state       :1951][INFO    ][7869] Completed state [maas_machines_storage_cmp002_lvm] at time 20:21:46.419762 duration_in_ms=2548.305
2019-06-18 20:21:46,420 [salt.state       :1780][INFO    ][7869] Running state [maas_machines_storage_cmp001_lvm] at time 20:21:46.420124
2019-06-18 20:21:46,420 [salt.state       :1813][INFO    ][7869] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-18 20:21:47,540 [salt.loaded.ext.module.maasng:610 ][INFO    ][7869] s7bkpy
2019-06-18 20:21:47,541 [salt.loaded.ext.module.maasng:626 ][INFO    ][7869] sda
2019-06-18 20:21:48,096 [salt.loaded.ext.module.maasng:361 ][INFO    ][7869] s7bkpy
2019-06-18 20:21:48,187 [salt.loaded.ext.module.maasng:367 ][INFO    ][7869] [{u'size': 800109715456, u'partition_table_type': u'MBR', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u's7bkpy', u'resource_uri': u'/MAAS/api/2.0/nodes/s7bkpy/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'8f6f3c56-5930-409f-89e3-754d2ddf57a9', u'resource_uri': u'/MAAS/api/2.0/nodes/s7bkpy/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u's7bkpy', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'1f19b2ad-d919-4f75-8883-fc00dc28e07b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'device_id': 2}]}, {u'size': 800097042432, u'partition_table_type': None, u'uuid': u'a1d6a655-ac66-47ae-9eb0-403fdc9c7691', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u's7bkpy', u'resource_uri': u'/MAAS/api/2.0/nodes/s7bkpy/blockdevices/4/', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'81f97719-f8d2-4849-99c8-5817e9b18514', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 4, u'serial': None, u'partitions': []}]
2019-06-18 20:21:48,188 [salt.loaded.ext.module.maasng:632 ][INFO    ][7869] vgroot
2019-06-18 20:21:48,188 [salt.loaded.ext.module.maasng:635 ][INFO    ][7869] lvroot
2019-06-18 20:21:48,188 [salt.loaded.ext.module.maasng:639 ][INFO    ][7869] 107374182400
2019-06-18 20:21:48,692 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202148682779
2019-06-18 20:21:48,718 [salt.minion      :1432][INFO    ][7896] Starting a new job with PID 7896
2019-06-18 20:21:48,740 [salt.minion      :1711][INFO    ][7896] Returning information for job: 20190618202148682779
2019-06-18 20:21:48,815 [salt.loaded.ext.module.maasng:645 ][INFO    ][7869] {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'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], 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'relay_vlan': None, u'primary_rack': u'ea8fns', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'relay_vlan': None, u'primary_rack': u'ea8fns', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'mode': u'dhcp', u'id': 25}], u'tags': [u'sriov'], u'enabled': True, u'mac_address': u'9c:b6:54:8a:95:a0', u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'relay_vlan': None, u'primary_rack': u'ea8fns', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'parents': [], u'params': u'', u'system_id': u's7bkpy', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/s7bkpy/interfaces/5/'}, u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/s7bkpy/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u's7bkpy', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'4a2e378f-b46a-4c23-9d84-2339d0ab9dfb', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u's7bkpy', u'filesystem': {u'uuid': u'036bb815-24a2-4812-899e-d026e45e2556', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/s7bkpy/blockdevices/2/partition/7'}], u'type': u'physical', u'id': 2, u'size': 800109715456}, u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'4a2e378f-b46a-4c23-9d84-2339d0ab9dfb', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u's7bkpy', u'filesystem': {u'uuid': u'036bb815-24a2-4812-899e-d026e45e2556', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/s7bkpy/blockdevices/2/partition/7'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/s7bkpy/blockdevices/2/', u'system_id': u's7bkpy', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'block_size': 4096, u'available_size': 0, u'uuid': u'a5ec4bed-c10a-44ac-a0c4-471abca4aea4', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/s7bkpy/blockdevices/12/', u'system_id': u's7bkpy', u'partition_table_type': None, u'filesystem': {u'uuid': u'6184260e-4aa0-489a-8493-2b0c05a20f83', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/s7bkpy/', u'current_commissioning_result_id': 4, u'hostname': u'cmp001', u'storage': 800109.715456, u'node_type': 0, u'owner_data': {}, u'system_id': u's7bkpy', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'a5ec4bed-c10a-44ac-a0c4-471abca4aea4', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/s7bkpy/blockdevices/12/', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u's7bkpy', u'partition_table_type': None, u'filesystem': {u'uuid': u'6184260e-4aa0-489a-8493-2b0c05a20f83', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 12, u'size': 107374182400}], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/s7bkpy/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u's7bkpy', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'4a2e378f-b46a-4c23-9d84-2339d0ab9dfb', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u's7bkpy', u'filesystem': {u'uuid': u'036bb815-24a2-4812-899e-d026e45e2556', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/s7bkpy/blockdevices/2/partition/7'}], u'type': u'physical', u'id': 2, u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u's7bkpy', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'disable_ipv4': False, u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', 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'relay_vlan': None, u'primary_rack': u'ea8fns', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'relay_vlan': None, u'primary_rack': u'ea8fns', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'mode': u'dhcp', u'id': 25}], u'tags': [u'sriov'], u'enabled': True, u'mac_address': u'9c:b6:54:8a:95:a0', u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'relay_vlan': None, u'primary_rack': u'ea8fns', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'parents': [], u'params': u'', u'system_id': u's7bkpy', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/s7bkpy/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'mac_address': u'38:ea:a7:8f:1f:d5', u'effective_mtu': 1500, u'id': 16, u'discovered': None, u'parents': [], u'params': u'', u'system_id': u's7bkpy', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/s7bkpy/interfaces/16/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'mac_address': u'38:ea:a7:8f:1f:d4', u'effective_mtu': 1500, u'id': 18, u'discovered': None, u'parents': [], u'params': u'', u'system_id': u's7bkpy', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/s7bkpy/interfaces/18/'}, {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'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'ens2f1', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'mac_address': u'38:ea:a7:8f:52:cd', u'effective_mtu': 1500, u'id': 17, u'discovered': None, u'parents': [], u'params': u'', u'system_id': u's7bkpy', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/s7bkpy/interfaces/17/'}, {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'relay_vlan': None, u'primary_rack': u'ea8fns', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'relay_vlan': None, u'primary_rack': u'ea8fns', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'mac_address': u'9c:b6:54:8a:95:a4', u'effective_mtu': 1500, u'id': 19, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'relay_vlan': None, u'primary_rack': u'ea8fns', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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.44'}], u'parents': [], u'params': u'', u'system_id': u's7bkpy', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/s7bkpy/interfaces/19/'}, {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'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'ens2f0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'mac_address': u'38:ea:a7:8f:52:cc', u'effective_mtu': 1500, u'id': 20, u'discovered': None, u'parents': [], u'params': u'', u'system_id': u's7bkpy', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/s7bkpy/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-06-18 20:21:48,819 [salt.state       :300 ][INFO    ][7869] {'new': {'storage_layout': 'lvm'}}
2019-06-18 20:21:48,819 [salt.state       :1951][INFO    ][7869] Completed state [maas_machines_storage_cmp001_lvm] at time 20:21:48.819885 duration_in_ms=2399.759
2019-06-18 20:21:48,823 [salt.minion      :1711][INFO    ][7869] Returning information for job: 20190618202133646361
2019-06-18 20:21:49,400 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command state.apply with jid 20190618202149386469
2019-06-18 20:21:49,426 [salt.minion      :1432][INFO    ][7903] Starting a new job with PID 7903
2019-06-18 20:21:50,522 [salt.state       :915 ][INFO    ][7903] Loading fresh modules for state activity
2019-06-18 20:21:50,587 [salt.fileclient  :1219][INFO    ][7903] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-18 20:21:50,634 [salt.state       :1780][INFO    ][7903] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:21:50.634487
2019-06-18 20:21:50,634 [salt.state       :1813][INFO    ][7903] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 20:21:50,636 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7903] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 20:21:52,321 [salt.state       :300 ][INFO    ][7903] {'pid': 7910, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 20:21:52,323 [salt.state       :1951][INFO    ][7903] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:21:52.322905 duration_in_ms=1688.418
2019-06-18 20:21:52,326 [salt.state       :1780][INFO    ][7903] Running state [maas.deploy_machines] at time 20:21:52.326441
2019-06-18 20:21:52,326 [salt.state       :1813][INFO    ][7903] Executing state module.run for [maas.deploy_machines]
2019-06-18 20:21:52,328 [salt.utils.decorators:613 ][WARNING ][7903] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 20:21:52,912 [salt.loaded.ext.module.maas:684 ][INFO    ][7903] deploymachines hwe_kernel=hwe-16.04 system_id=ftwfwe distro_series=xenial
2019-06-18 20:21:55,380 [salt.loaded.ext.module.maas:684 ][INFO    ][7903] deploymachines hwe_kernel=hwe-16.04 system_id=s7bkpy distro_series=xenial
2019-06-18 20:21:57,897 [salt.loaded.ext.module.maas:684 ][INFO    ][7903] deploymachines hwe_kernel=hwe-16.04 system_id=tcewwe distro_series=xenial
2019-06-18 20:22:00,491 [salt.loaded.ext.module.maas:684 ][INFO    ][7903] deploymachines hwe_kernel=hwe-16.04 system_id=srk4nq distro_series=xenial
2019-06-18 20:22:03,140 [salt.loaded.ext.module.maas:684 ][INFO    ][7903] deploymachines hwe_kernel=hwe-16.04 system_id=m3nkxy distro_series=xenial
2019-06-18 20:22:04,498 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202204484086
2019-06-18 20:22:04,524 [salt.minion      :1432][INFO    ][8172] Starting a new job with PID 8172
2019-06-18 20:22:04,554 [salt.minion      :1711][INFO    ][8172] Returning information for job: 20190618202204484086
2019-06-18 20:22:05,744 [salt.state       :300 ][INFO    ][7903] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-18 20:22:05,744 [salt.state       :1951][INFO    ][7903] Completed state [maas.deploy_machines] at time 20:22:05.744723 duration_in_ms=13418.28
2019-06-18 20:22:05,749 [salt.minion      :1711][INFO    ][7903] Returning information for job: 20190618202149386469
2019-06-18 20:22:06,332 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command state.apply with jid 20190618202206312271
2019-06-18 20:22:06,356 [salt.minion      :1432][INFO    ][8188] Starting a new job with PID 8188
2019-06-18 20:22:14,561 [salt.state       :915 ][INFO    ][8188] Loading fresh modules for state activity
2019-06-18 20:22:14,625 [salt.fileclient  :1219][INFO    ][8188] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-18 20:22:14,672 [salt.state       :1780][INFO    ][8188] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:22:14.672840
2019-06-18 20:22:14,673 [salt.state       :1813][INFO    ][8188] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 20:22:14,675 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8188] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 20:22:16,387 [salt.state       :300 ][INFO    ][8188] {'pid': 8211, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 20:22:16,388 [salt.state       :1951][INFO    ][8188] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:22:16.388579 duration_in_ms=1715.738
2019-06-18 20:22:16,392 [salt.state       :1780][INFO    ][8188] Running state [maas.wait_for_machine_status] at time 20:22:16.392753
2019-06-18 20:22:16,393 [salt.state       :1813][INFO    ][8188] Executing state module.run for [maas.wait_for_machine_status]
2019-06-18 20:22:16,395 [salt.utils.decorators:613 ][WARNING ][8188] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 20:22:19,361 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.04200101s left)
2019-06-18 20:22:21,428 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202221409076
2019-06-18 20:22:21,460 [salt.minion      :1432][INFO    ][8223] Starting a new job with PID 8223
2019-06-18 20:22:21,483 [salt.minion      :1711][INFO    ][8223] Returning information for job: 20190618202221409076
2019-06-18 20:22:51,508 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202251497397
2019-06-18 20:22:51,535 [salt.minion      :1432][INFO    ][8268] Starting a new job with PID 8268
2019-06-18 20:22:51,556 [salt.minion      :1711][INFO    ][8268] Returning information for job: 20190618202251497397
2019-06-18 20:22:52,252 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.15075898s left)
2019-06-18 20:23:21,631 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202321619225
2019-06-18 20:23:21,659 [salt.minion      :1432][INFO    ][8303] Starting a new job with PID 8303
2019-06-18 20:23:21,683 [salt.minion      :1711][INFO    ][8303] Returning information for job: 20190618202321619225
2019-06-18 20:23:25,114 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.28864789s left)
2019-06-18 20:23:51,716 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202351702164
2019-06-18 20:23:51,744 [salt.minion      :1432][INFO    ][8372] Starting a new job with PID 8372
2019-06-18 20:23:51,765 [salt.minion      :1711][INFO    ][8372] Returning information for job: 20190618202351702164
2019-06-18 20:23:58,228 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.17481399s left)
2019-06-18 20:24:21,798 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202421782792
2019-06-18 20:24:21,817 [salt.minion      :1432][INFO    ][8402] Starting a new job with PID 8402
2019-06-18 20:24:21,839 [salt.minion      :1711][INFO    ][8402] Returning information for job: 20190618202421782792
2019-06-18 20:24:31,073 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.33017302s left)
2019-06-18 20:24:51,888 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202451874858
2019-06-18 20:24:51,910 [salt.minion      :1432][INFO    ][8493] Starting a new job with PID 8493
2019-06-18 20:24:51,931 [salt.minion      :1711][INFO    ][8493] Returning information for job: 20190618202451874858
2019-06-18 20:25:04,273 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.12988687s left)
2019-06-18 20:25:21,979 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202521963113
2019-06-18 20:25:22,006 [salt.minion      :1432][INFO    ][8577] Starting a new job with PID 8577
2019-06-18 20:25:22,031 [salt.minion      :1711][INFO    ][8577] Returning information for job: 20190618202521963113
2019-06-18 20:25:37,097 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.30607796s left)
2019-06-18 20:25:52,109 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202552096825
2019-06-18 20:25:52,133 [salt.minion      :1432][INFO    ][8819] Starting a new job with PID 8819
2019-06-18 20:25:52,157 [salt.minion      :1711][INFO    ][8819] Returning information for job: 20190618202552096825
2019-06-18 20:26:09,965 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.43792605s left)
2019-06-18 20:26:22,196 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202622185071
2019-06-18 20:26:22,221 [salt.minion      :1432][INFO    ][8886] Starting a new job with PID 8886
2019-06-18 20:26:22,244 [salt.minion      :1711][INFO    ][8886] Returning information for job: 20190618202622185071
2019-06-18 20:26:42,887 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.51622295s left)
2019-06-18 20:26:52,337 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202652320155
2019-06-18 20:26:52,368 [salt.minion      :1432][INFO    ][9112] Starting a new job with PID 9112
2019-06-18 20:26:52,392 [salt.minion      :1711][INFO    ][9112] Returning information for job: 20190618202652320155
2019-06-18 20:27:15,844 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.55868888s left)
2019-06-18 20:27:22,482 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202722461570
2019-06-18 20:27:22,512 [salt.minion      :1432][INFO    ][9158] Starting a new job with PID 9158
2019-06-18 20:27:22,535 [salt.minion      :1711][INFO    ][9158] Returning information for job: 20190618202722461570
2019-06-18 20:27:49,195 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1917.20821285s left)
2019-06-18 20:27:52,655 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202752647981
2019-06-18 20:27:52,679 [salt.minion      :1432][INFO    ][9552] Starting a new job with PID 9552
2019-06-18 20:27:52,702 [salt.minion      :1711][INFO    ][9552] Returning information for job: 20190618202752647981
2019-06-18 20:28:22,374 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1884.02934003s left)
2019-06-18 20:28:22,789 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202822775119
2019-06-18 20:28:22,818 [salt.minion      :1432][INFO    ][9600] Starting a new job with PID 9600
2019-06-18 20:28:22,843 [salt.minion      :1711][INFO    ][9600] Returning information for job: 20190618202822775119
2019-06-18 20:28:52,968 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202852952696
2019-06-18 20:28:52,991 [salt.minion      :1432][INFO    ][10013] Starting a new job with PID 10013
2019-06-18 20:28:53,015 [salt.minion      :1711][INFO    ][10013] Returning information for job: 20190618202852952696
2019-06-18 20:28:55,313 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1851.09014392s left)
2019-06-18 20:29:23,122 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202923104930
2019-06-18 20:29:23,145 [salt.minion      :1432][INFO    ][10048] Starting a new job with PID 10048
2019-06-18 20:29:23,168 [salt.minion      :1711][INFO    ][10048] Returning information for job: 20190618202923104930
2019-06-18 20:29:28,353 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1818.05010796s left)
2019-06-18 20:29:53,323 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618202953308919
2019-06-18 20:29:53,344 [salt.minion      :1432][INFO    ][10375] Starting a new job with PID 10375
2019-06-18 20:29:53,367 [salt.minion      :1711][INFO    ][10375] Returning information for job: 20190618202953308919
2019-06-18 20:30:01,264 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1785.13895202s left)
2019-06-18 20:30:23,465 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618203023452473
2019-06-18 20:30:23,497 [salt.minion      :1432][INFO    ][10432] Starting a new job with PID 10432
2019-06-18 20:30:23,523 [salt.minion      :1711][INFO    ][10432] Returning information for job: 20190618203023452473
2019-06-18 20:30:34,344 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1752.05935001s left)
2019-06-18 20:30:53,674 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618203053657403
2019-06-18 20:30:53,698 [salt.minion      :1432][INFO    ][10689] Starting a new job with PID 10689
2019-06-18 20:30:53,723 [salt.minion      :1711][INFO    ][10689] Returning information for job: 20190618203053657403
2019-06-18 20:31:07,338 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1719.06501198s left)
2019-06-18 20:31:23,748 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618203123734824
2019-06-18 20:31:23,775 [salt.minion      :1432][INFO    ][10757] Starting a new job with PID 10757
2019-06-18 20:31:23,798 [salt.minion      :1711][INFO    ][10757] Returning information for job: 20190618203123734824
2019-06-18 20:31:40,292 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1686.11141992s left)
2019-06-18 20:31:53,947 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618203153931250
2019-06-18 20:31:53,975 [salt.minion      :1432][INFO    ][10926] Starting a new job with PID 10926
2019-06-18 20:31:54,001 [salt.minion      :1711][INFO    ][10926] Returning information for job: 20190618203153931250
2019-06-18 20:32:13,419 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1652.98372388s left)
2019-06-18 20:32:24,180 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618203224163490
2019-06-18 20:32:24,209 [salt.minion      :1432][INFO    ][10968] Starting a new job with PID 10968
2019-06-18 20:32:24,232 [salt.minion      :1711][INFO    ][10968] Returning information for job: 20190618203224163490
2019-06-18 20:32:46,304 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1620.09889102s left)
2019-06-18 20:32:54,377 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618203254355604
2019-06-18 20:32:54,406 [salt.minion      :1432][INFO    ][11137] Starting a new job with PID 11137
2019-06-18 20:32:54,429 [salt.minion      :1711][INFO    ][11137] Returning information for job: 20190618203254355604
2019-06-18 20:33:19,115 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1587.28794599s left)
2019-06-18 20:33:24,556 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618203324541704
2019-06-18 20:33:24,585 [salt.minion      :1432][INFO    ][11301] Starting a new job with PID 11301
2019-06-18 20:33:24,613 [salt.minion      :1711][INFO    ][11301] Returning information for job: 20190618203324541704
2019-06-18 20:33:52,146 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1554.25671005s left)
2019-06-18 20:33:54,761 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618203354743499
2019-06-18 20:33:54,791 [salt.minion      :1432][INFO    ][11361] Starting a new job with PID 11361
2019-06-18 20:33:54,823 [salt.minion      :1711][INFO    ][11361] Returning information for job: 20190618203354743499
2019-06-18 20:34:24,979 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618203424963305
2019-06-18 20:34:25,002 [salt.minion      :1432][INFO    ][11435] Starting a new job with PID 11435
2019-06-18 20:34:25,027 [salt.minion      :1711][INFO    ][11435] Returning information for job: 20190618203424963305
2019-06-18 20:34:25,159 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1521.24364686s left)
2019-06-18 20:34:55,101 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618203455086547
2019-06-18 20:34:55,134 [salt.minion      :1432][INFO    ][11514] Starting a new job with PID 11514
2019-06-18 20:34:55,158 [salt.minion      :1711][INFO    ][11514] Returning information for job: 20190618203455086547
2019-06-18 20:34:58,243 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1488.15957689s left)
2019-06-18 20:35:25,124 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618203525110111
2019-06-18 20:35:25,154 [salt.minion      :1432][INFO    ][11570] Starting a new job with PID 11570
2019-06-18 20:35:25,180 [salt.minion      :1711][INFO    ][11570] Returning information for job: 20190618203525110111
2019-06-18 20:35:32,341 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1454.06225586s left)
2019-06-18 20:35:55,208 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618203555196112
2019-06-18 20:35:55,232 [salt.minion      :1432][INFO    ][11857] Starting a new job with PID 11857
2019-06-18 20:35:55,268 [salt.minion      :1711][INFO    ][11857] Returning information for job: 20190618203555196112
2019-06-18 20:36:05,227 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1421.176373s left)
2019-06-18 20:36:25,241 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618203625232645
2019-06-18 20:36:25,268 [salt.minion      :1432][INFO    ][11884] Starting a new job with PID 11884
2019-06-18 20:36:25,292 [salt.minion      :1711][INFO    ][11884] Returning information for job: 20190618203625232645
2019-06-18 20:36:38,528 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1387.87522888s left)
2019-06-18 20:36:55,282 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618203655265525
2019-06-18 20:36:55,313 [salt.minion      :1432][INFO    ][11932] Starting a new job with PID 11932
2019-06-18 20:36:55,336 [salt.minion      :1711][INFO    ][11932] Returning information for job: 20190618203655265525
2019-06-18 20:37:11,397 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1355.00624585s left)
2019-06-18 20:37:25,343 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618203725327593
2019-06-18 20:37:25,373 [salt.minion      :1432][INFO    ][11961] Starting a new job with PID 11961
2019-06-18 20:37:25,398 [salt.minion      :1711][INFO    ][11961] Returning information for job: 20190618203725327593
2019-06-18 20:37:44,264 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1322.13920689s left)
2019-06-18 20:37:55,420 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618203755403607
2019-06-18 20:37:55,448 [salt.minion      :1432][INFO    ][12008] Starting a new job with PID 12008
2019-06-18 20:37:55,480 [salt.minion      :1711][INFO    ][12008] Returning information for job: 20190618203755403607
2019-06-18 20:38:16,983 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1289.41971588s left)
2019-06-18 20:38:25,516 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618203825499546
2019-06-18 20:38:25,544 [salt.minion      :1432][INFO    ][12044] Starting a new job with PID 12044
2019-06-18 20:38:25,567 [salt.minion      :1711][INFO    ][12044] Returning information for job: 20190618203825499546
2019-06-18 20:38:50,011 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1256.391927s left)
2019-06-18 20:38:55,593 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618203855576482
2019-06-18 20:38:55,625 [salt.minion      :1432][INFO    ][12093] Starting a new job with PID 12093
2019-06-18 20:38:55,651 [salt.minion      :1711][INFO    ][12093] Returning information for job: 20190618203855576482
2019-06-18 20:39:22,773 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1223.62961698s left)
2019-06-18 20:39:25,699 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618203925685613
2019-06-18 20:39:25,727 [salt.minion      :1432][INFO    ][12129] Starting a new job with PID 12129
2019-06-18 20:39:25,749 [salt.minion      :1711][INFO    ][12129] Returning information for job: 20190618203925685613
2019-06-18 20:39:55,580 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1190.82297707s left)
2019-06-18 20:39:55,816 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618203955799842
2019-06-18 20:39:55,848 [salt.minion      :1432][INFO    ][12179] Starting a new job with PID 12179
2019-06-18 20:39:55,868 [salt.minion      :1711][INFO    ][12179] Returning information for job: 20190618203955799842
2019-06-18 20:40:25,956 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618204025942347
2019-06-18 20:40:25,979 [salt.minion      :1432][INFO    ][12202] Starting a new job with PID 12202
2019-06-18 20:40:26,004 [salt.minion      :1711][INFO    ][12202] Returning information for job: 20190618204025942347
2019-06-18 20:40:28,560 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1157.84326386s left)
2019-06-18 20:40:56,084 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618204056068047
2019-06-18 20:40:56,117 [salt.minion      :1432][INFO    ][12251] Starting a new job with PID 12251
2019-06-18 20:40:56,140 [salt.minion      :1711][INFO    ][12251] Returning information for job: 20190618204056068047
2019-06-18 20:41:01,433 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1124.96971393s left)
2019-06-18 20:41:26,265 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618204126250954
2019-06-18 20:41:26,292 [salt.minion      :1432][INFO    ][12281] Starting a new job with PID 12281
2019-06-18 20:41:26,315 [salt.minion      :1711][INFO    ][12281] Returning information for job: 20190618204126250954
2019-06-18 20:41:34,435 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1091.96809697s left)
2019-06-18 20:41:56,428 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618204156414806
2019-06-18 20:41:56,447 [salt.minion      :1432][INFO    ][12326] Starting a new job with PID 12326
2019-06-18 20:41:56,469 [salt.minion      :1711][INFO    ][12326] Returning information for job: 20190618204156414806
2019-06-18 20:42:07,192 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1059.21133399s left)
2019-06-18 20:42:26,604 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618204226595368
2019-06-18 20:42:26,634 [salt.minion      :1432][INFO    ][12355] Starting a new job with PID 12355
2019-06-18 20:42:26,659 [salt.minion      :1711][INFO    ][12355] Returning information for job: 20190618204226595368
2019-06-18 20:42:39,931 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1026.47214293s left)
2019-06-18 20:42:56,816 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618204256800539
2019-06-18 20:42:56,839 [salt.minion      :1432][INFO    ][12403] Starting a new job with PID 12403
2019-06-18 20:42:56,865 [salt.minion      :1711][INFO    ][12403] Returning information for job: 20190618204256800539
2019-06-18 20:43:13,035 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (993.368151903s left)
2019-06-18 20:43:27,004 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618204326987041
2019-06-18 20:43:27,033 [salt.minion      :1432][INFO    ][12448] Starting a new job with PID 12448
2019-06-18 20:43:27,057 [salt.minion      :1711][INFO    ][12448] Returning information for job: 20190618204326987041
2019-06-18 20:43:45,767 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (960.635695934s left)
2019-06-18 20:43:57,034 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618204357018800
2019-06-18 20:43:57,063 [salt.minion      :1432][INFO    ][12507] Starting a new job with PID 12507
2019-06-18 20:43:57,088 [salt.minion      :1711][INFO    ][12507] Returning information for job: 20190618204357018800
2019-06-18 20:44:18,580 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (927.82314992s left)
2019-06-18 20:44:27,260 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618204427250217
2019-06-18 20:44:27,283 [salt.minion      :1432][INFO    ][12536] Starting a new job with PID 12536
2019-06-18 20:44:27,307 [salt.minion      :1711][INFO    ][12536] Returning information for job: 20190618204427250217
2019-06-18 20:44:51,340 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (895.062734842s left)
2019-06-18 20:44:57,319 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618204457304510
2019-06-18 20:44:57,347 [salt.minion      :1432][INFO    ][12585] Starting a new job with PID 12585
2019-06-18 20:44:57,371 [salt.minion      :1711][INFO    ][12585] Returning information for job: 20190618204457304510
2019-06-18 20:45:24,192 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (862.211868048s left)
2019-06-18 20:45:27,551 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618204527536495
2019-06-18 20:45:27,577 [salt.minion      :1432][INFO    ][12620] Starting a new job with PID 12620
2019-06-18 20:45:27,604 [salt.minion      :1711][INFO    ][12620] Returning information for job: 20190618204527536495
2019-06-18 20:45:57,177 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (829.226297855s left)
2019-06-18 20:45:57,625 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618204557617321
2019-06-18 20:45:57,653 [salt.minion      :1432][INFO    ][12666] Starting a new job with PID 12666
2019-06-18 20:45:57,674 [salt.minion      :1711][INFO    ][12666] Returning information for job: 20190618204557617321
2019-06-18 20:46:27,696 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618204627684552
2019-06-18 20:46:27,721 [salt.minion      :1432][INFO    ][12689] Starting a new job with PID 12689
2019-06-18 20:46:27,746 [salt.minion      :1711][INFO    ][12689] Returning information for job: 20190618204627684552
2019-06-18 20:46:30,055 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (796.348088026s left)
2019-06-18 20:46:57,820 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618204657804150
2019-06-18 20:46:57,845 [salt.minion      :1432][INFO    ][12739] Starting a new job with PID 12739
2019-06-18 20:46:57,872 [salt.minion      :1711][INFO    ][12739] Returning information for job: 20190618204657804150
2019-06-18 20:47:02,931 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (763.471655846s left)
2019-06-18 20:47:27,928 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618204727912524
2019-06-18 20:47:27,955 [salt.minion      :1432][INFO    ][12769] Starting a new job with PID 12769
2019-06-18 20:47:27,978 [salt.minion      :1711][INFO    ][12769] Returning information for job: 20190618204727912524
2019-06-18 20:47:35,936 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (730.467123032s left)
2019-06-18 20:47:58,100 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618204758083807
2019-06-18 20:47:58,127 [salt.minion      :1432][INFO    ][12823] Starting a new job with PID 12823
2019-06-18 20:47:58,157 [salt.minion      :1711][INFO    ][12823] Returning information for job: 20190618204758083807
2019-06-18 20:48:08,664 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (697.739112854s left)
2019-06-18 20:48:28,265 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618204828252931
2019-06-18 20:48:28,281 [salt.minion      :1432][INFO    ][12857] Starting a new job with PID 12857
2019-06-18 20:48:28,305 [salt.minion      :1711][INFO    ][12857] Returning information for job: 20190618204828252931
2019-06-18 20:48:41,543 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (664.860444069s left)
2019-06-18 20:48:58,487 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618204858472146
2019-06-18 20:48:58,516 [salt.minion      :1432][INFO    ][12900] Starting a new job with PID 12900
2019-06-18 20:48:58,540 [salt.minion      :1711][INFO    ][12900] Returning information for job: 20190618204858472146
2019-06-18 20:49:14,348 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (632.054946899s left)
2019-06-18 20:49:28,524 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618204928509095
2019-06-18 20:49:28,553 [salt.minion      :1432][INFO    ][12938] Starting a new job with PID 12938
2019-06-18 20:49:28,588 [salt.minion      :1711][INFO    ][12938] Returning information for job: 20190618204928509095
2019-06-18 20:49:47,233 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (599.170459032s left)
2019-06-18 20:49:58,572 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618204958556837
2019-06-18 20:49:58,601 [salt.minion      :1432][INFO    ][12981] Starting a new job with PID 12981
2019-06-18 20:49:58,632 [salt.minion      :1711][INFO    ][12981] Returning information for job: 20190618204958556837
2019-06-18 20:50:20,002 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (566.401154995s left)
2019-06-18 20:50:28,626 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618205028613049
2019-06-18 20:50:28,654 [salt.minion      :1432][INFO    ][13021] Starting a new job with PID 13021
2019-06-18 20:50:28,689 [salt.minion      :1711][INFO    ][13021] Returning information for job: 20190618205028613049
2019-06-18 20:50:52,734 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (533.669446945s left)
2019-06-18 20:50:58,708 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618205058694994
2019-06-18 20:50:58,732 [salt.minion      :1432][INFO    ][13056] Starting a new job with PID 13056
2019-06-18 20:50:58,758 [salt.minion      :1711][INFO    ][13056] Returning information for job: 20190618205058694994
2019-06-18 20:51:25,587 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (500.816115856s left)
2019-06-18 20:51:28,772 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618205128762531
2019-06-18 20:51:28,800 [salt.minion      :1432][INFO    ][13101] Starting a new job with PID 13101
2019-06-18 20:51:28,822 [salt.minion      :1711][INFO    ][13101] Returning information for job: 20190618205128762531
2019-06-18 20:51:58,603 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (467.800091028s left)
2019-06-18 20:51:58,832 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618205158821274
2019-06-18 20:51:58,855 [salt.minion      :1432][INFO    ][13135] Starting a new job with PID 13135
2019-06-18 20:51:58,891 [salt.minion      :1711][INFO    ][13135] Returning information for job: 20190618205158821274
2019-06-18 20:52:28,989 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618205228977923
2019-06-18 20:52:29,011 [salt.minion      :1432][INFO    ][13171] Starting a new job with PID 13171
2019-06-18 20:52:29,040 [salt.minion      :1711][INFO    ][13171] Returning information for job: 20190618205228977923
2019-06-18 20:52:31,492 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (434.911159992s left)
2019-06-18 20:52:59,099 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618205259084568
2019-06-18 20:52:59,124 [salt.minion      :1432][INFO    ][13209] Starting a new job with PID 13209
2019-06-18 20:52:59,151 [salt.minion      :1711][INFO    ][13209] Returning information for job: 20190618205259084568
2019-06-18 20:53:04,219 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (402.184202909s left)
2019-06-18 20:53:29,253 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618205329239087
2019-06-18 20:53:29,279 [salt.minion      :1432][INFO    ][13401] Starting a new job with PID 13401
2019-06-18 20:53:29,308 [salt.minion      :1711][INFO    ][13401] Returning information for job: 20190618205329239087
2019-06-18 20:53:37,122 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (369.280667067s left)
2019-06-18 20:53:59,391 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618205359380515
2019-06-18 20:53:59,420 [salt.minion      :1432][INFO    ][13435] Starting a new job with PID 13435
2019-06-18 20:53:59,446 [salt.minion      :1711][INFO    ][13435] Returning information for job: 20190618205359380515
2019-06-18 20:54:10,018 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (336.384924889s left)
2019-06-18 20:54:29,612 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618205429595584
2019-06-18 20:54:29,639 [salt.minion      :1432][INFO    ][13486] Starting a new job with PID 13486
2019-06-18 20:54:29,663 [salt.minion      :1711][INFO    ][13486] Returning information for job: 20190618205429595584
2019-06-18 20:54:42,739 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (303.664274931s left)
2019-06-18 20:54:59,653 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618205459644202
2019-06-18 20:54:59,675 [salt.minion      :1432][INFO    ][13511] Starting a new job with PID 13511
2019-06-18 20:54:59,700 [salt.minion      :1711][INFO    ][13511] Returning information for job: 20190618205459644202
2019-06-18 20:55:15,496 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (270.90724206s left)
2019-06-18 20:55:29,857 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618205529842103
2019-06-18 20:55:29,882 [salt.minion      :1432][INFO    ][13570] Starting a new job with PID 13570
2019-06-18 20:55:29,909 [salt.minion      :1711][INFO    ][13570] Returning information for job: 20190618205529842103
2019-06-18 20:55:48,440 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (237.963511944s left)
2019-06-18 20:55:59,946 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618205559937035
2019-06-18 20:55:59,973 [salt.minion      :1432][INFO    ][13595] Starting a new job with PID 13595
2019-06-18 20:55:59,996 [salt.minion      :1711][INFO    ][13595] Returning information for job: 20190618205559937035
2019-06-18 20:56:21,388 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (205.015094995s left)
2019-06-18 20:56:29,984 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618205629972082
2019-06-18 20:56:30,008 [salt.minion      :1432][INFO    ][13643] Starting a new job with PID 13643
2019-06-18 20:56:30,033 [salt.minion      :1711][INFO    ][13643] Returning information for job: 20190618205629972082
2019-06-18 20:56:54,160 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (172.242765903s left)
2019-06-18 20:57:00,101 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618205700086735
2019-06-18 20:57:00,130 [salt.minion      :1432][INFO    ][13666] Starting a new job with PID 13666
2019-06-18 20:57:00,153 [salt.minion      :1711][INFO    ][13666] Returning information for job: 20190618205700086735
2019-06-18 20:57:27,033 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (139.370298862s left)
2019-06-18 20:57:30,139 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618205730123027
2019-06-18 20:57:30,162 [salt.minion      :1432][INFO    ][13716] Starting a new job with PID 13716
2019-06-18 20:57:30,192 [salt.minion      :1711][INFO    ][13716] Returning information for job: 20190618205730123027
2019-06-18 20:57:59,844 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (106.559329987s left)
2019-06-18 20:58:00,312 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618205800298178
2019-06-18 20:58:00,341 [salt.minion      :1432][INFO    ][13746] Starting a new job with PID 13746
2019-06-18 20:58:00,367 [salt.minion      :1711][INFO    ][13746] Returning information for job: 20190618205800298178
2019-06-18 20:58:30,443 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618205830431925
2019-06-18 20:58:30,466 [salt.minion      :1432][INFO    ][13797] Starting a new job with PID 13797
2019-06-18 20:58:30,492 [salt.minion      :1711][INFO    ][13797] Returning information for job: 20190618205830431925
2019-06-18 20:58:32,746 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (73.657189846s left)
2019-06-18 20:59:00,632 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618205900618806
2019-06-18 20:59:00,660 [salt.minion      :1432][INFO    ][13819] Starting a new job with PID 13819
2019-06-18 20:59:00,688 [salt.minion      :1711][INFO    ][13819] Returning information for job: 20190618205900618806
2019-06-18 20:59:05,466 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (40.9370369911s left)
2019-06-18 20:59:30,775 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618205930760891
2019-06-18 20:59:30,803 [salt.minion      :1432][INFO    ][13875] Starting a new job with PID 13875
2019-06-18 20:59:30,827 [salt.minion      :1711][INFO    ][13875] Returning information for job: 20190618205930760891
2019-06-18 20:59:38,334 [salt.loaded.ext.module.maas:1023][INFO    ][8188] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (8.0699660778s left)
2019-06-18 21:00:01,005 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618210000993670
2019-06-18 21:00:01,033 [salt.minion      :1432][INFO    ][13897] Starting a new job with PID 13897
2019-06-18 21:00:01,059 [salt.minion      :1711][INFO    ][13897] Returning information for job: 20190618210000993670
2019-06-18 21:00:11,333 [salt.state       :302 ][ERROR   ][8188] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-06-18 21:00:11,334 [salt.state       :1951][INFO    ][8188] Completed state [maas.wait_for_machine_status] at time 21:00:11.334760 duration_in_ms=2274942.005
2019-06-18 21:00:11,339 [salt.minion      :1711][INFO    ][8188] Returning information for job: 20190618202206312271
2019-06-18 21:00:22,127 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command pillar.get with jid 20190618210022115516
2019-06-18 21:00:22,158 [salt.minion      :1432][INFO    ][13924] Starting a new job with PID 13924
2019-06-18 21:00:22,167 [salt.minion      :1711][INFO    ][13924] Returning information for job: 20190618210022115516
2019-06-18 21:00:22,731 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command service.status with jid 20190618210022719014
2019-06-18 21:00:22,759 [salt.minion      :1432][INFO    ][13929] Starting a new job with PID 13929
2019-06-18 21:00:23,301 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13929] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-18 21:00:23,343 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13929] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-18 21:00:23,362 [salt.minion      :1711][INFO    ][13929] Returning information for job: 20190618210022719014
2019-06-18 21:00:23,949 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command state.apply with jid 20190618210023936672
2019-06-18 21:00:23,977 [salt.minion      :1432][INFO    ][13940] Starting a new job with PID 13940
2019-06-18 21:00:32,244 [salt.state       :915 ][INFO    ][13940] Loading fresh modules for state activity
2019-06-18 21:00:32,793 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13940] Executing command 'salt-minion --version' in directory '/root'
2019-06-18 21:00:33,117 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13940] Executing command 'salt-minion --version' in directory '/root'
2019-06-18 21:00:34,168 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13940] Executing command 'salt-minion --version' in directory '/root'
2019-06-18 21:00:34,486 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13940] Executing command 'salt-minion --version' in directory '/root'
2019-06-18 21:00:36,375 [salt.state       :1780][INFO    ][13940] Running state [salt-minion] at time 21:00:36.375274
2019-06-18 21:00:36,375 [salt.state       :1813][INFO    ][13940] Executing state pkg.installed for [salt-minion]
2019-06-18 21:00:36,376 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13940] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-18 21:00:36,488 [salt.state       :300 ][INFO    ][13940] All specified packages are already installed
2019-06-18 21:00:36,488 [salt.state       :1951][INFO    ][13940] Completed state [salt-minion] at time 21:00:36.488499 duration_in_ms=113.226
2019-06-18 21:00:36,488 [salt.state       :1780][INFO    ][13940] Running state [salt_minion_dependency_packages] at time 21:00:36.488841
2019-06-18 21:00:36,489 [salt.state       :1813][INFO    ][13940] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-18 21:00:36,499 [salt.state       :300 ][INFO    ][13940] All specified packages are already installed
2019-06-18 21:00:36,499 [salt.state       :1951][INFO    ][13940] Completed state [salt_minion_dependency_packages] at time 21:00:36.499464 duration_in_ms=10.623
2019-06-18 21:00:36,502 [salt.state       :1780][INFO    ][13940] Running state [/etc/salt/minion.d/minion.conf] at time 21:00:36.502406
2019-06-18 21:00:36,502 [salt.state       :1813][INFO    ][13940] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-18 21:00:36,774 [salt.state       :300 ][INFO    ][13940] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-18 21:00:36,774 [salt.state       :1951][INFO    ][13940] Completed state [/etc/salt/minion.d/minion.conf] at time 21:00:36.774376 duration_in_ms=271.97
2019-06-18 21:00:36,774 [salt.state       :1780][INFO    ][13940] Running state [python-netaddr] at time 21:00:36.774633
2019-06-18 21:00:36,774 [salt.state       :1813][INFO    ][13940] Executing state pkg.installed for [python-netaddr]
2019-06-18 21:00:36,784 [salt.state       :300 ][INFO    ][13940] All specified packages are already installed
2019-06-18 21:00:36,784 [salt.state       :1951][INFO    ][13940] Completed state [python-netaddr] at time 21:00:36.784412 duration_in_ms=9.779
2019-06-18 21:00:36,787 [salt.state       :1780][INFO    ][13940] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 21:00:36.787485
2019-06-18 21:00:36,787 [salt.state       :1813][INFO    ][13940] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-18 21:00:36,807 [salt.state       :300 ][INFO    ][13940] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-18 21:00:36,808 [salt.state       :1951][INFO    ][13940] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 21:00:36.808099 duration_in_ms=20.614
2019-06-18 21:00:36,809 [salt.state       :1780][INFO    ][13940] Running state [salt-minion] at time 21:00:36.809487
2019-06-18 21:00:36,810 [salt.state       :1813][INFO    ][13940] Executing state service.running for [salt-minion]
2019-06-18 21:00:36,810 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13940] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-18 21:00:36,853 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13940] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-18 21:00:36,880 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13940] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-18 21:00:36,905 [salt.state       :300 ][INFO    ][13940] The service salt-minion is already running
2019-06-18 21:00:36,906 [salt.state       :1951][INFO    ][13940] Completed state [salt-minion] at time 21:00:36.906276 duration_in_ms=96.788
2019-06-18 21:00:36,909 [salt.state       :1780][INFO    ][13940] Running state [/etc/salt/grains.d] at time 21:00:36.909159
2019-06-18 21:00:36,909 [salt.state       :1813][INFO    ][13940] Executing state file.directory for [/etc/salt/grains.d]
2019-06-18 21:00:36,911 [salt.state       :300 ][INFO    ][13940] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-18 21:00:36,911 [salt.state       :1951][INFO    ][13940] Completed state [/etc/salt/grains.d] at time 21:00:36.911687 duration_in_ms=2.527
2019-06-18 21:00:36,913 [salt.state       :1780][INFO    ][13940] Running state [/etc/salt/grains] at time 21:00:36.912913
2019-06-18 21:00:36,913 [salt.state       :1813][INFO    ][13940] Executing state file.managed for [/etc/salt/grains]
2019-06-18 21:00:36,916 [salt.state       :300 ][INFO    ][13940] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-18 21:00:36,916 [salt.state       :1951][INFO    ][13940] Completed state [/etc/salt/grains] at time 21:00:36.916301 duration_in_ms=3.39
2019-06-18 21:00:36,916 [salt.state       :1780][INFO    ][13940] Running state [/etc/salt/grains.d/placeholder] at time 21:00:36.916771
2019-06-18 21:00:36,917 [salt.state       :1813][INFO    ][13940] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-18 21:00:36,917 [salt.state       :300 ][INFO    ][13940] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-18 21:00:36,918 [salt.state       :1951][INFO    ][13940] Completed state [/etc/salt/grains.d/placeholder] at time 21:00:36.917721 duration_in_ms=0.951
2019-06-18 21:00:36,918 [salt.state       :1780][INFO    ][13940] Running state [/etc/salt/grains.d/sphinx] at time 21:00:36.918501
2019-06-18 21:00:36,918 [salt.state       :1813][INFO    ][13940] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-18 21:00:36,919 [salt.state       :300 ][INFO    ][13940] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-18 21:00:36,920 [salt.state       :1951][INFO    ][13940] Completed state [/etc/salt/grains.d/sphinx] at time 21:00:36.920103 duration_in_ms=1.602
2019-06-18 21:00:36,922 [salt.state       :1780][INFO    ][13940] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 21:00:36.922077
2019-06-18 21:00:36,922 [salt.state       :1813][INFO    ][13940] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-18 21:00:36,922 [salt.state       :300 ][INFO    ][13940] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-18 21:00:36,922 [salt.state       :1951][INFO    ][13940] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 21:00:36.922845 duration_in_ms=0.768
2019-06-18 21:00:36,923 [salt.state       :1780][INFO    ][13940] Running state [/etc/salt/grains.d/dns_records] at time 21:00:36.923322
2019-06-18 21:00:36,923 [salt.state       :1813][INFO    ][13940] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-18 21:00:36,924 [salt.state       :300 ][INFO    ][13940] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-18 21:00:36,924 [salt.state       :1951][INFO    ][13940] Completed state [/etc/salt/grains.d/dns_records] at time 21:00:36.924705 duration_in_ms=1.383
2019-06-18 21:00:36,925 [salt.state       :1780][INFO    ][13940] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 21:00:36.925631
2019-06-18 21:00:36,928 [salt.state       :1813][INFO    ][13940] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-18 21:00:36,928 [salt.state       :300 ][INFO    ][13940] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-18 21:00:36,928 [salt.state       :1951][INFO    ][13940] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 21:00:36.928725 duration_in_ms=3.093
2019-06-18 21:00:36,929 [salt.state       :1780][INFO    ][13940] Running state [/etc/salt/grains.d/salt] at time 21:00:36.929202
2019-06-18 21:00:36,929 [salt.state       :1813][INFO    ][13940] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-18 21:00:36,930 [salt.state       :300 ][INFO    ][13940] File /etc/salt/grains.d/salt is in the correct state
2019-06-18 21:00:36,930 [salt.state       :1951][INFO    ][13940] Completed state [/etc/salt/grains.d/salt] at time 21:00:36.930560 duration_in_ms=1.357
2019-06-18 21:00:36,931 [salt.state       :1780][INFO    ][13940] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 21:00:36.931435
2019-06-18 21:00:36,931 [salt.state       :1813][INFO    ][13940] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-18 21:00:36,932 [salt.state       :300 ][INFO    ][13940] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-18 21:00:36,932 [salt.state       :1951][INFO    ][13940] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 21:00:36.932178 duration_in_ms=0.743
2019-06-18 21:00:36,934 [salt.state       :1780][INFO    ][13940] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 21:00:36.934100
2019-06-18 21:00:36,934 [salt.state       :1813][INFO    ][13940] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-18 21:00:36,934 [salt.state       :300 ][INFO    ][13940] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-18 21:00:36,934 [salt.state       :1951][INFO    ][13940] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 21:00:36.934869 duration_in_ms=0.769
2019-06-18 21:00:36,935 [salt.state       :1780][INFO    ][13940] Running state [mine.update] at time 21:00:36.935527
2019-06-18 21:00:36,935 [salt.state       :1813][INFO    ][13940] Executing state module.wait for [mine.update]
2019-06-18 21:00:36,936 [salt.state       :300 ][INFO    ][13940] No changes made for mine.update
2019-06-18 21:00:36,936 [salt.state       :1951][INFO    ][13940] Completed state [mine.update] at time 21:00:36.936228 duration_in_ms=0.7
2019-06-18 21:00:36,936 [salt.state       :1780][INFO    ][13940] Running state [ca-certificates] at time 21:00:36.936466
2019-06-18 21:00:36,936 [salt.state       :1813][INFO    ][13940] Executing state pkg.installed for [ca-certificates]
2019-06-18 21:00:36,946 [salt.state       :300 ][INFO    ][13940] All specified packages are already installed
2019-06-18 21:00:36,947 [salt.state       :1951][INFO    ][13940] Completed state [ca-certificates] at time 21:00:36.947106 duration_in_ms=10.639
2019-06-18 21:00:36,947 [salt.state       :1780][INFO    ][13940] Running state [update-ca-certificates] at time 21:00:36.947789
2019-06-18 21:00:36,948 [salt.state       :1813][INFO    ][13940] Executing state cmd.wait for [update-ca-certificates]
2019-06-18 21:00:36,948 [salt.state       :300 ][INFO    ][13940] No changes made for update-ca-certificates
2019-06-18 21:00:36,948 [salt.state       :1951][INFO    ][13940] Completed state [update-ca-certificates] at time 21:00:36.948515 duration_in_ms=0.726
2019-06-18 21:00:36,948 [salt.state       :1780][INFO    ][13940] Running state [iptables] at time 21:00:36.948749
2019-06-18 21:00:36,949 [salt.state       :1813][INFO    ][13940] Executing state pkg.installed for [iptables]
2019-06-18 21:00:36,958 [salt.state       :300 ][INFO    ][13940] All specified packages are already installed
2019-06-18 21:00:36,958 [salt.state       :1951][INFO    ][13940] Completed state [iptables] at time 21:00:36.958827 duration_in_ms=10.077
2019-06-18 21:00:36,959 [salt.state       :1780][INFO    ][13940] Running state [iptables-persistent] at time 21:00:36.959067
2019-06-18 21:00:36,959 [salt.state       :1813][INFO    ][13940] Executing state pkg.installed for [iptables-persistent]
2019-06-18 21:00:36,968 [salt.state       :300 ][INFO    ][13940] All specified packages are already installed
2019-06-18 21:00:36,968 [salt.state       :1951][INFO    ][13940] Completed state [iptables-persistent] at time 21:00:36.968925 duration_in_ms=9.857
2019-06-18 21:00:36,969 [salt.state       :1780][INFO    ][13940] Running state [iptables_modules_v4_load] at time 21:00:36.969909
2019-06-18 21:00:36,970 [salt.state       :1813][INFO    ][13940] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-18 21:00:36,970 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13940] Executing command 'lsmod' in directory '/root'
2019-06-18 21:00:36,991 [salt.state       :300 ][INFO    ][13940] Kernel modules iptable_filter, ip_tables are already present
2019-06-18 21:00:36,992 [salt.state       :1951][INFO    ][13940] Completed state [iptables_modules_v4_load] at time 21:00:36.992294 duration_in_ms=22.384
2019-06-18 21:00:36,993 [salt.state       :1780][INFO    ][13940] Running state [/etc/iptables/rules.v4] at time 21:00:36.993578
2019-06-18 21:00:36,994 [salt.state       :1813][INFO    ][13940] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-18 21:00:37,111 [salt.state       :300 ][INFO    ][13940] File /etc/iptables/rules.v4 is in the correct state
2019-06-18 21:00:37,112 [salt.state       :1951][INFO    ][13940] Completed state [/etc/iptables/rules.v4] at time 21:00:37.112052 duration_in_ms=118.474
2019-06-18 21:00:37,113 [salt.state       :1780][INFO    ][13940] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 21:00:37.113201
2019-06-18 21:00:37,113 [salt.state       :1813][INFO    ][13940] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-18 21:00:37,114 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13940] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-18 21:00:37,136 [salt.state       :300 ][INFO    ][13940] onlyif execution failed
2019-06-18 21:00:37,136 [salt.state       :1951][INFO    ][13940] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 21:00:37.136648 duration_in_ms=23.446
2019-06-18 21:00:37,138 [salt.state       :1780][INFO    ][13940] Running state [netfilter-persistent] at time 21:00:37.138445
2019-06-18 21:00:37,139 [salt.state       :1813][INFO    ][13940] Executing state service.running for [netfilter-persistent]
2019-06-18 21:00:37,140 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13940] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-18 21:00:37,170 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13940] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-18 21:00:37,194 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13940] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-18 21:00:37,218 [salt.state       :300 ][INFO    ][13940] The service netfilter-persistent is already running
2019-06-18 21:00:37,218 [salt.state       :1951][INFO    ][13940] Completed state [netfilter-persistent] at time 21:00:37.218578 duration_in_ms=80.133
2019-06-18 21:00:37,220 [salt.state       :1780][INFO    ][13940] Running state [iptables_extra.remove_stale_tables] at time 21:00:37.220017
2019-06-18 21:00:37,220 [salt.state       :1813][INFO    ][13940] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-18 21:00:37,221 [salt.state       :300 ][INFO    ][13940] No changes made for iptables_extra.remove_stale_tables
2019-06-18 21:00:37,221 [salt.state       :1951][INFO    ][13940] Completed state [iptables_extra.remove_stale_tables] at time 21:00:37.221366 duration_in_ms=1.349
2019-06-18 21:00:37,221 [salt.state       :1780][INFO    ][13940] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 21:00:37.221792
2019-06-18 21:00:37,222 [salt.state       :1813][INFO    ][13940] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-18 21:00:37,223 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13940] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-18 21:00:37,243 [salt.state       :300 ][INFO    ][13940] onlyif execution failed
2019-06-18 21:00:37,244 [salt.state       :1951][INFO    ][13940] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 21:00:37.244405 duration_in_ms=22.612
2019-06-18 21:00:37,246 [salt.state       :1780][INFO    ][13940] Running state [/etc/iptables/rules.v6] at time 21:00:37.246053
2019-06-18 21:00:37,246 [salt.state       :1813][INFO    ][13940] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-18 21:00:37,247 [salt.state       :300 ][INFO    ][13940] File /etc/iptables/rules.v6 is not present
2019-06-18 21:00:37,247 [salt.state       :1951][INFO    ][13940] Completed state [/etc/iptables/rules.v6] at time 21:00:37.247654 duration_in_ms=1.601
2019-06-18 21:00:37,248 [salt.state       :1780][INFO    ][13940] Running state [iptables_extra.flush_all] at time 21:00:37.248848
2019-06-18 21:00:37,249 [salt.state       :1813][INFO    ][13940] Executing state module.wait for [iptables_extra.flush_all]
2019-06-18 21:00:37,251 [salt.state       :300 ][INFO    ][13940] No changes made for iptables_extra.flush_all
2019-06-18 21:00:37,252 [salt.state       :1951][INFO    ][13940] Completed state [iptables_extra.flush_all] at time 21:00:37.252011 duration_in_ms=3.164
2019-06-18 21:00:37,256 [salt.minion      :1711][INFO    ][13940] Returning information for job: 20190618210023936672
2019-06-18 21:00:37,832 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command state.apply with jid 20190618210037816316
2019-06-18 21:00:37,859 [salt.minion      :1432][INFO    ][14047] Starting a new job with PID 14047
2019-06-18 21:00:39,007 [salt.state       :915 ][INFO    ][14047] Loading fresh modules for state activity
2019-06-18 21:00:39,950 [salt.state       :1780][INFO    ][14047] Running state [maas-rack-controller] at time 21:00:39.950864
2019-06-18 21:00:39,951 [salt.state       :1813][INFO    ][14047] Executing state pkg.installed for [maas-rack-controller]
2019-06-18 21:00:39,952 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14047] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-18 21:00:40,067 [salt.state       :300 ][INFO    ][14047] All specified packages are already installed
2019-06-18 21:00:40,068 [salt.state       :1951][INFO    ][14047] Completed state [maas-rack-controller] at time 21:00:40.068074 duration_in_ms=117.211
2019-06-18 21:00:40,068 [salt.state       :1780][INFO    ][14047] Running state [ipmitool] at time 21:00:40.068398
2019-06-18 21:00:40,068 [salt.state       :1813][INFO    ][14047] Executing state pkg.installed for [ipmitool]
2019-06-18 21:00:40,078 [salt.state       :300 ][INFO    ][14047] All specified packages are already installed
2019-06-18 21:00:40,078 [salt.state       :1951][INFO    ][14047] Completed state [ipmitool] at time 21:00:40.078651 duration_in_ms=10.252
2019-06-18 21:00:40,081 [salt.state       :1780][INFO    ][14047] Running state [/etc/maas/rackd.conf] at time 21:00:40.081688
2019-06-18 21:00:40,082 [salt.state       :1813][INFO    ][14047] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-18 21:00:40,083 [salt.state       :300 ][INFO    ][14047] No changes needed to be made
2019-06-18 21:00:40,084 [salt.state       :1951][INFO    ][14047] Completed state [/etc/maas/rackd.conf] at time 21:00:40.083958 duration_in_ms=2.27
2019-06-18 21:00:40,084 [salt.state       :1780][INFO    ][14047] Running state [/etc/maas/rackd.conf] at time 21:00:40.084221
2019-06-18 21:00:40,084 [salt.state       :1813][INFO    ][14047] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-18 21:00:40,084 [salt.loaded.int.states.file:2298][WARNING ][14047] 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-06-18 21:00:40,085 [salt.state       :300 ][INFO    ][14047] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-18 21:00:40,085 [salt.state       :1951][INFO    ][14047] Completed state [/etc/maas/rackd.conf] at time 21:00:40.085697 duration_in_ms=1.475
2019-06-18 21:00:40,086 [salt.state       :1780][INFO    ][14047] Running state [maas-rackd] at time 21:00:40.086725
2019-06-18 21:00:40,087 [salt.state       :1813][INFO    ][14047] Executing state service.running for [maas-rackd]
2019-06-18 21:00:40,087 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14047] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-18 21:00:40,132 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14047] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-18 21:00:40,156 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14047] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-18 21:00:40,180 [salt.state       :300 ][INFO    ][14047] The service maas-rackd is already running
2019-06-18 21:00:40,180 [salt.state       :1951][INFO    ][14047] Completed state [maas-rackd] at time 21:00:40.180617 duration_in_ms=93.891
2019-06-18 21:00:40,183 [salt.minion      :1711][INFO    ][14047] Returning information for job: 20190618210037816316
2019-06-18 21:00:40,750 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command state.apply with jid 20190618210040733883
2019-06-18 21:00:40,780 [salt.minion      :1432][INFO    ][14070] Starting a new job with PID 14070
2019-06-18 21:00:41,905 [salt.state       :915 ][INFO    ][14070] Loading fresh modules for state activity
2019-06-18 21:00:43,020 [salt.state       :1780][INFO    ][14070] Running state [maas-region-controller] at time 21:00:43.020505
2019-06-18 21:00:43,020 [salt.state       :1813][INFO    ][14070] Executing state pkg.installed for [maas-region-controller]
2019-06-18 21:00:43,021 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14070] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-18 21:00:43,137 [salt.state       :300 ][INFO    ][14070] All specified packages are already installed
2019-06-18 21:00:43,138 [salt.state       :1951][INFO    ][14070] Completed state [maas-region-controller] at time 21:00:43.137727 duration_in_ms=117.222
2019-06-18 21:00:43,138 [salt.state       :1780][INFO    ][14070] Running state [python-oauth] at time 21:00:43.138392
2019-06-18 21:00:43,138 [salt.state       :1813][INFO    ][14070] Executing state pkg.installed for [python-oauth]
2019-06-18 21:00:43,148 [salt.state       :300 ][INFO    ][14070] All specified packages are already installed
2019-06-18 21:00:43,148 [salt.state       :1951][INFO    ][14070] Completed state [python-oauth] at time 21:00:43.148343 duration_in_ms=9.95
2019-06-18 21:00:43,151 [salt.state       :1780][INFO    ][14070] Running state [/etc/maas/regiond.conf] at time 21:00:43.151381
2019-06-18 21:00:43,151 [salt.state       :1813][INFO    ][14070] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-18 21:00:43,157 [salt.state       :300 ][INFO    ][14070] No changes needed to be made
2019-06-18 21:00:43,157 [salt.state       :1951][INFO    ][14070] Completed state [/etc/maas/regiond.conf] at time 21:00:43.157575 duration_in_ms=6.194
2019-06-18 21:00:43,158 [salt.state       :1780][INFO    ][14070] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:00:43.158065
2019-06-18 21:00:43,158 [salt.state       :1813][INFO    ][14070] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-18 21:00:43,223 [salt.state       :300 ][INFO    ][14070] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-18 21:00:43,223 [salt.state       :1951][INFO    ][14070] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:00:43.223906 duration_in_ms=65.841
2019-06-18 21:00:43,224 [salt.state       :1780][INFO    ][14070] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:00:43.224450
2019-06-18 21:00:43,224 [salt.state       :1813][INFO    ][14070] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-18 21:00:43,229 [salt.state       :300 ][INFO    ][14070] No changes needed to be made
2019-06-18 21:00:43,229 [salt.state       :1951][INFO    ][14070] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:00:43.229521 duration_in_ms=5.071
2019-06-18 21:00:43,230 [salt.state       :1780][INFO    ][14070] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:00:43.229997
2019-06-18 21:00:43,230 [salt.state       :1813][INFO    ][14070] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-18 21:00:43,233 [salt.state       :300 ][INFO    ][14070] No changes needed to be made
2019-06-18 21:00:43,233 [salt.state       :1951][INFO    ][14070] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:00:43.233854 duration_in_ms=3.857
2019-06-18 21:00:43,234 [salt.state       :1780][INFO    ][14070] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:00:43.234329
2019-06-18 21:00:43,234 [salt.state       :1813][INFO    ][14070] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-18 21:00:43,263 [salt.state       :300 ][INFO    ][14070] No changes needed to be made
2019-06-18 21:00:43,264 [salt.state       :1951][INFO    ][14070] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:00:43.264092 duration_in_ms=29.763
2019-06-18 21:00:43,264 [salt.state       :1780][INFO    ][14070] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:00:43.264666
2019-06-18 21:00:43,264 [salt.state       :1813][INFO    ][14070] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-18 21:00:43,284 [salt.state       :300 ][INFO    ][14070] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-18 21:00:43,284 [salt.state       :1951][INFO    ][14070] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:00:43.284706 duration_in_ms=20.037
2019-06-18 21:00:43,289 [salt.state       :1780][INFO    ][14070] Running state [a2enmod headers] at time 21:00:43.288947
2019-06-18 21:00:43,289 [salt.state       :1813][INFO    ][14070] Executing state cmd.run for [a2enmod headers]
2019-06-18 21:00:43,290 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14070] Executing command 'a2enmod headers' in directory '/root'
2019-06-18 21:00:43,357 [salt.state       :300 ][INFO    ][14070] {'pid': 14093, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-18 21:00:43,359 [salt.state       :1951][INFO    ][14070] Completed state [a2enmod headers] at time 21:00:43.359544 duration_in_ms=70.595
2019-06-18 21:00:43,360 [salt.state       :1780][INFO    ][14070] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:00:43.360158
2019-06-18 21:00:43,360 [salt.state       :1813][INFO    ][14070] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-18 21:00:43,385 [salt.state       :300 ][INFO    ][14070] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-18 21:00:43,385 [salt.state       :1951][INFO    ][14070] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:00:43.385535 duration_in_ms=25.377
2019-06-18 21:00:43,388 [salt.state       :1780][INFO    ][14070] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:00:43.388326
2019-06-18 21:00:43,388 [salt.state       :1813][INFO    ][14070] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-18 21:00:43,449 [salt.state       :300 ][INFO    ][14070] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-18 21:00:43,450 [salt.state       :1951][INFO    ][14070] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:00:43.450068 duration_in_ms=61.742
2019-06-18 21:00:43,450 [salt.state       :1780][INFO    ][14070] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:00:43.450600
2019-06-18 21:00:43,450 [salt.state       :1813][INFO    ][14070] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-18 21:00:43,508 [salt.state       :300 ][INFO    ][14070] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-18 21:00:43,508 [salt.state       :1951][INFO    ][14070] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:00:43.508385 duration_in_ms=57.785
2019-06-18 21:00:43,508 [salt.state       :1780][INFO    ][14070] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:00:43.508887
2019-06-18 21:00:43,509 [salt.state       :1813][INFO    ][14070] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-18 21:00:43,589 [salt.state       :300 ][INFO    ][14070] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-18 21:00:43,589 [salt.state       :1951][INFO    ][14070] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:00:43.589410 duration_in_ms=80.523
2019-06-18 21:00:43,589 [salt.state       :1780][INFO    ][14070] Running state [/root/.pgpass] at time 21:00:43.589660
2019-06-18 21:00:43,589 [salt.state       :1813][INFO    ][14070] Executing state file.managed for [/root/.pgpass]
2019-06-18 21:00:43,634 [salt.state       :300 ][INFO    ][14070] File /root/.pgpass is in the correct state
2019-06-18 21:00:43,635 [salt.state       :1951][INFO    ][14070] Completed state [/root/.pgpass] at time 21:00:43.635126 duration_in_ms=45.466
2019-06-18 21:00:43,640 [salt.state       :1780][INFO    ][14070] Running state [maas-region syncdb --noinput] at time 21:00:43.640168
2019-06-18 21:00:43,640 [salt.state       :1813][INFO    ][14070] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-18 21:00:43,641 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14070] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-18 21:00:46,209 [salt.state       :300 ][INFO    ][14070] {'pid': 14106, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sites, contenttypes, auth, metadataserver, piston3, maasserver, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-18 21:00:46,210 [salt.state       :1951][INFO    ][14070] Completed state [maas-region syncdb --noinput] at time 21:00:46.210565 duration_in_ms=2570.396
2019-06-18 21:00:46,211 [salt.state       :2022][WARNING ][14070] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-18 21:00:46,214 [salt.state       :1780][INFO    ][14070] Running state [maas-regiond] at time 21:00:46.214505
2019-06-18 21:00:46,215 [salt.state       :1813][INFO    ][14070] Executing state service.running for [maas-regiond]
2019-06-18 21:00:46,216 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14070] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-18 21:00:46,264 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14070] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-18 21:00:46,283 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14070] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-18 21:00:46,306 [salt.state       :300 ][INFO    ][14070] The service maas-regiond is already running
2019-06-18 21:00:46,306 [salt.state       :1951][INFO    ][14070] Completed state [maas-regiond] at time 21:00:46.306848 duration_in_ms=92.343
2019-06-18 21:00:46,312 [salt.state       :1780][INFO    ][14070] Running state [bind9] at time 21:00:46.312088
2019-06-18 21:00:46,312 [salt.state       :1813][INFO    ][14070] Executing state service.running for [bind9]
2019-06-18 21:00:46,313 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14070] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-18 21:00:46,334 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14070] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-18 21:00:46,356 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14070] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-18 21:00:46,384 [salt.state       :300 ][INFO    ][14070] The service bind9 is already running
2019-06-18 21:00:46,385 [salt.state       :1951][INFO    ][14070] Completed state [bind9] at time 21:00:46.384934 duration_in_ms=72.845
2019-06-18 21:00:46,388 [salt.state       :1780][INFO    ][14070] Running state [apache2] at time 21:00:46.388536
2019-06-18 21:00:46,389 [salt.state       :1813][INFO    ][14070] Executing state service.running for [apache2]
2019-06-18 21:00:46,390 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14070] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-18 21:00:46,414 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14070] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-18 21:00:46,437 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14070] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-18 21:00:46,464 [salt.state       :300 ][INFO    ][14070] The service apache2 is already running
2019-06-18 21:00:46,465 [salt.state       :1951][INFO    ][14070] Completed state [apache2] at time 21:00:46.465372 duration_in_ms=76.835
2019-06-18 21:00:46,469 [salt.state       :1780][INFO    ][14070] Running state [maasng.wait_for_http_code] at time 21:00:46.469391
2019-06-18 21:00:46,472 [salt.state       :1813][INFO    ][14070] Executing state module.run for [maasng.wait_for_http_code]
2019-06-18 21:00:46,472 [salt.utils.decorators:613 ][WARNING ][14070] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 21:00:46,486 [salt.state       :300 ][INFO    ][14070] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-18 21:00:46,487 [salt.state       :1951][INFO    ][14070] Completed state [maasng.wait_for_http_code] at time 21:00:46.487020 duration_in_ms=17.629
2019-06-18 21:00:46,488 [salt.state       :1780][INFO    ][14070] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:00:46.488366
2019-06-18 21:00:46,488 [salt.state       :1813][INFO    ][14070] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-18 21:00:46,489 [salt.state       :300 ][INFO    ][14070] /var/lib/maas/.setup_admin exists
2019-06-18 21:00:46,494 [salt.state       :1951][INFO    ][14070] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:00:46.494272 duration_in_ms=5.907
2019-06-18 21:00:46,495 [salt.state       :1780][INFO    ][14070] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:00:46.495435
2019-06-18 21:00:46,495 [salt.state       :1813][INFO    ][14070] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 21:00:46,496 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14070] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 21:00:48,225 [salt.state       :300 ][INFO    ][14070] {'pid': 14125, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 21:00:48,226 [salt.state       :1951][INFO    ][14070] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:00:48.226668 duration_in_ms=1731.233
2019-06-18 21:00:48,237 [salt.state       :1780][INFO    ][14070] Running state [maas_region_boot_source_resources_mirror] at time 21:00:48.237157
2019-06-18 21:00:48,238 [salt.state       :1813][INFO    ][14070] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-18 21:00:48,326 [salt.state       :300 ][INFO    ][14070] {'changes': {}}
2019-06-18 21:00:48,327 [salt.state       :1951][INFO    ][14070] Completed state [maas_region_boot_source_resources_mirror] at time 21:00:48.326938 duration_in_ms=89.782
2019-06-18 21:00:48,328 [salt.state       :1780][INFO    ][14070] Running state [maasng.boot_resources_import] at time 21:00:48.328057
2019-06-18 21:00:48,328 [salt.state       :1813][INFO    ][14070] Executing state module.run for [maasng.boot_resources_import]
2019-06-18 21:00:48,329 [salt.utils.decorators:613 ][WARNING ][14070] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 21:00:48,431 [salt.loaded.ext.module.maasng:1600][INFO    ][14070] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-18 21:00:53,483 [salt.loaded.ext.module.maasng:1600][INFO    ][14070] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-18 21:00:55,882 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618210055847763
2019-06-18 21:00:55,930 [salt.minion      :1432][INFO    ][14280] Starting a new job with PID 14280
2019-06-18 21:00:55,977 [salt.minion      :1711][INFO    ][14280] Returning information for job: 20190618210055847763
2019-06-18 21:00:58,579 [salt.state       :300 ][INFO    ][14070] {'ret': True}
2019-06-18 21:00:58,579 [salt.state       :1951][INFO    ][14070] Completed state [maasng.boot_resources_import] at time 21:00:58.579654 duration_in_ms=10251.596
2019-06-18 21:00:58,580 [salt.state       :1780][INFO    ][14070] Running state [maas_region_boot_sources_selection_xenial] at time 21:00:58.580774
2019-06-18 21:00:58,581 [salt.state       :1813][INFO    ][14070] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-18 21:00:58,763 [salt.state       :300 ][INFO    ][14070] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-18 21:00:58,763 [salt.state       :1951][INFO    ][14070] Completed state [maas_region_boot_sources_selection_xenial] at time 21:00:58.763343 duration_in_ms=182.57
2019-06-18 21:00:58,764 [salt.state       :1780][INFO    ][14070] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 21:00:58.764572
2019-06-18 21:00:58,765 [salt.state       :1813][INFO    ][14070] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-18 21:00:58,766 [salt.utils.decorators:613 ][WARNING ][14070] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 21:00:58,766 [salt.loaded.ext.module.maasng:1771][INFO    ][14070] boot-sources sync initiated for ALL Rack's
2019-06-18 21:00:59,855 [salt.state       :300 ][INFO    ][14070] {'ret': True}
2019-06-18 21:00:59,856 [salt.state       :1951][INFO    ][14070] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 21:00:59.856069 duration_in_ms=1091.496
2019-06-18 21:00:59,858 [salt.state       :1780][INFO    ][14070] Running state [maas.process_maas_config] at time 21:00:59.857656
2019-06-18 21:00:59,859 [salt.state       :1813][INFO    ][14070] Executing state module.run for [maas.process_maas_config]
2019-06-18 21:00:59,859 [salt.utils.decorators:613 ][WARNING ][14070] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 21:00:59,860 [salt.loaded.ext.module.maas:92  ][INFO    ][14070] maasconfig name=enable_http_proxy value=True
2019-06-18 21:00:59,914 [salt.loaded.ext.module.maas:92  ][INFO    ][14070] maasconfig name=upstream_dns value=8.8.8.8
2019-06-18 21:00:59,968 [salt.loaded.ext.module.maas:92  ][INFO    ][14070] maasconfig name=commissioning_distro_series value=xenial
2019-06-18 21:01:00,032 [salt.loaded.ext.module.maas:92  ][INFO    ][14070] maasconfig name=default_osystem value=ubuntu
2019-06-18 21:01:00,091 [salt.loaded.ext.module.maas:92  ][INFO    ][14070] maasconfig name=active_discovery_interval value=600
2019-06-18 21:01:00,154 [salt.loaded.ext.module.maas:92  ][INFO    ][14070] maasconfig name=dnssec_validation value=no
2019-06-18 21:01:00,210 [salt.loaded.ext.module.maas:92  ][INFO    ][14070] maasconfig name=maas_name value=mas01
2019-06-18 21:01:00,263 [salt.loaded.ext.module.maas:92  ][INFO    ][14070] maasconfig name=network_discovery value=enabled
2019-06-18 21:01:00,369 [salt.loaded.ext.module.maas:92  ][INFO    ][14070] maasconfig name=enable_third_party_drivers value=True
2019-06-18 21:01:00,416 [salt.loaded.ext.module.maas:92  ][INFO    ][14070] maasconfig name=default_storage_layout value=lvm
2019-06-18 21:01:01,335 [salt.loaded.ext.module.maas:92  ][INFO    ][14070] maasconfig name=ntp_external_only value=True
2019-06-18 21:01:01,396 [salt.loaded.ext.module.maas:92  ][INFO    ][14070] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-18 21:01:01,461 [salt.loaded.ext.module.maas:92  ][INFO    ][14070] maasconfig name=default_distro_series value=xenial
2019-06-18 21:01:01,520 [salt.loaded.ext.module.maas:92  ][INFO    ][14070] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-06-18 21:01:01,645 [salt.state       :300 ][INFO    ][14070] {'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-06-18 21:01:01,647 [salt.state       :1951][INFO    ][14070] Completed state [maas.process_maas_config] at time 21:01:01.647514 duration_in_ms=1789.859
2019-06-18 21:01:01,648 [salt.state       :1780][INFO    ][14070] Running state [pxe_admin] at time 21:01:01.648354
2019-06-18 21:01:01,648 [salt.state       :1813][INFO    ][14070] Executing state maasng.fabric_present for [pxe_admin]
2019-06-18 21:01:01,712 [salt.loaded.ext.module.maasng:945 ][INFO    ][14070] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], 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'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'relay_vlan': None, u'primary_rack': u'ea8fns', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-06-18 21:01:01,784 [salt.loaded.ext.module.maasng:1008][WARNING ][14070] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-18 21:01:01,784 [salt.loaded.ext.module.maasng:1011][WARNING ][14070] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-18 21:01:01,848 [salt.state       :300 ][INFO    ][14070] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-18 21:01:01,848 [salt.state       :1951][INFO    ][14070] Completed state [pxe_admin] at time 21:01:01.848487 duration_in_ms=200.134
2019-06-18 21:01:01,848 [salt.state       :1780][INFO    ][14070] Running state [vlan 0] at time 21:01:01.848846
2019-06-18 21:01:01,849 [salt.state       :1813][INFO    ][14070] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-18 21:01:01,912 [salt.loaded.ext.module.maasng:945 ][INFO    ][14070] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ea8fns', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-06-18 21:01:02,031 [salt.loaded.ext.module.maasng:945 ][INFO    ][14070] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ea8fns', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-06-18 21:01:02,288 [salt.loaded.ext.module.maasng:945 ][INFO    ][14070] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ea8fns', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-06-18 21:01:02,365 [salt.state       :300 ][INFO    ][14070] {'new': 'Vlan untagged was updated'}
2019-06-18 21:01:02,367 [salt.state       :1951][INFO    ][14070] Completed state [vlan 0] at time 21:01:02.367263 duration_in_ms=518.417
2019-06-18 21:01:02,368 [salt.state       :1780][INFO    ][14070] Running state [192.168.11.0/24] at time 21:01:02.368685
2019-06-18 21:01:02,369 [salt.state       :1813][INFO    ][14070] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-18 21:01:02,586 [salt.loaded.ext.module.maasng:945 ][INFO    ][14070] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ea8fns', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-06-18 21:01:02,587 [salt.loaded.ext.module.maasng:1235][WARNING ][14070] Ignoring parameter vlan:0
2019-06-18 21:01:02,670 [salt.state       :300 ][INFO    ][14070] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-18 21:01:02,671 [salt.state       :1951][INFO    ][14070] Completed state [192.168.11.0/24] at time 21:01:02.671260 duration_in_ms=302.575
2019-06-18 21:01:02,672 [salt.state       :1780][INFO    ][14070] Running state [maas_create_iprange_1] at time 21:01:02.672394
2019-06-18 21:01:02,672 [salt.state       :1813][INFO    ][14070] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-18 21:01:02,723 [salt.state       :300 ][INFO    ][14070] Iprange maas_create_iprange_1 already exist.
2019-06-18 21:01:02,724 [salt.state       :1951][INFO    ][14070] Completed state [maas_create_iprange_1] at time 21:01:02.724141 duration_in_ms=51.747
2019-06-18 21:01:02,724 [salt.state       :1780][INFO    ][14070] Running state [vlan 0] at time 21:01:02.724463
2019-06-18 21:01:02,724 [salt.state       :1813][INFO    ][14070] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-18 21:01:02,774 [salt.loaded.ext.module.maasng:945 ][INFO    ][14070] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ea8fns', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-06-18 21:01:02,875 [salt.loaded.ext.module.maasng:945 ][INFO    ][14070] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ea8fns', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-06-18 21:01:03,165 [salt.loaded.ext.module.maasng:945 ][INFO    ][14070] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ea8fns', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-06-18 21:01:03,268 [salt.state       :300 ][INFO    ][14070] {'new': 'Vlan untagged was updated'}
2019-06-18 21:01:03,268 [salt.state       :1951][INFO    ][14070] Completed state [vlan 0] at time 21:01:03.268769 duration_in_ms=544.305
2019-06-18 21:01:03,269 [salt.state       :1780][INFO    ][14070] Running state [opnfv] at time 21:01:03.269607
2019-06-18 21:01:03,270 [salt.state       :1813][INFO    ][14070] Executing state maasng.sshkey_present for [opnfv]
2019-06-18 21:01:03,315 [salt.loaded.ext.module.maasng:1903][INFO    ][14070] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-06-18 21:01:03,315 [salt.state       :300 ][INFO    ][14070] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-06-18 21:01:03,316 [salt.state       :1951][INFO    ][14070] Completed state [opnfv] at time 21:01:03.315984 duration_in_ms=46.378
2019-06-18 21:01:03,320 [salt.minion      :1711][INFO    ][14070] Returning information for job: 20190618210040733883
2019-06-18 21:01:03,969 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command state.apply with jid 20190618210103954660
2019-06-18 21:01:03,993 [salt.minion      :1432][INFO    ][14511] Starting a new job with PID 14511
2019-06-18 21:01:11,895 [salt.state       :915 ][INFO    ][14511] Loading fresh modules for state activity
2019-06-18 21:01:12,004 [salt.state       :1780][INFO    ][14511] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:12.004693
2019-06-18 21:01:12,005 [salt.state       :1813][INFO    ][14511] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 21:01:12,006 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14511] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 21:01:13,771 [salt.state       :300 ][INFO    ][14511] {'pid': 14536, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 21:01:13,772 [salt.state       :1951][INFO    ][14511] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:01:13.772386 duration_in_ms=1767.692
2019-06-18 21:01:13,776 [salt.state       :1780][INFO    ][14511] Running state [maas.process_machines] at time 21:01:13.776283
2019-06-18 21:01:13,777 [salt.state       :1813][INFO    ][14511] Executing state module.run for [maas.process_machines]
2019-06-18 21:01:13,780 [salt.utils.decorators:613 ][WARNING ][14511] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 21:01:14,343 [salt.loaded.ext.module.maas:412 ][WARNING ][14511] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 21:01:14,344 [salt.loaded.ext.module.maas:92  ][INFO    ][14511] 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=ftwfwe architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-18 21:01:15,591 [salt.loaded.ext.module.maas:412 ][WARNING ][14511] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 21:01:15,592 [salt.loaded.ext.module.maas:92  ][INFO    ][14511] 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=s7bkpy architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-18 21:01:16,827 [salt.loaded.ext.module.maas:412 ][WARNING ][14511] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 21:01:16,828 [salt.loaded.ext.module.maas:92  ][INFO    ][14511] 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=tcewwe architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-18 21:01:18,026 [salt.loaded.ext.module.maas:412 ][WARNING ][14511] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 21:01:18,027 [salt.loaded.ext.module.maas:92  ][INFO    ][14511] 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=srk4nq architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-18 21:01:18,999 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618210118988496
2019-06-18 21:01:19,018 [salt.minion      :1432][INFO    ][14721] Starting a new job with PID 14721
2019-06-18 21:01:19,041 [salt.minion      :1711][INFO    ][14721] Returning information for job: 20190618210118988496
2019-06-18 21:01:19,236 [salt.loaded.ext.module.maas:412 ][WARNING ][14511] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-18 21:01:19,237 [salt.loaded.ext.module.maas:92  ][INFO    ][14511] 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=m3nkxy architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-18 21:01:20,415 [salt.state       :300 ][INFO    ][14511] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-18 21:01:20,415 [salt.state       :1951][INFO    ][14511] Completed state [maas.process_machines] at time 21:01:20.415686 duration_in_ms=6639.402
2019-06-18 21:01:20,420 [salt.minion      :1711][INFO    ][14511] Returning information for job: 20190618210103954660
2019-06-18 21:01:53,466 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command state.apply with jid 20190618210153452025
2019-06-18 21:01:53,494 [salt.minion      :1432][INFO    ][14809] Starting a new job with PID 14809
2019-06-18 21:02:01,376 [salt.state       :915 ][INFO    ][14809] Loading fresh modules for state activity
2019-06-18 21:02:01,479 [salt.state       :1780][INFO    ][14809] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:02:01.479164
2019-06-18 21:02:01,479 [salt.state       :1813][INFO    ][14809] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 21:02:01,481 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14809] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 21:02:03,220 [salt.state       :300 ][INFO    ][14809] {'pid': 14822, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 21:02:03,221 [salt.state       :1951][INFO    ][14809] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:02:03.220927 duration_in_ms=1741.764
2019-06-18 21:02:03,222 [salt.state       :1780][INFO    ][14809] Running state [maas.wait_for_machine_status] at time 21:02:03.222535
2019-06-18 21:02:03,222 [salt.state       :1813][INFO    ][14809] Executing state module.run for [maas.wait_for_machine_status]
2019-06-18 21:02:03,223 [salt.utils.decorators:613 ][WARNING ][14809] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 21:02:06,069 [salt.state       :300 ][INFO    ][14809] {'ret': True}
2019-06-18 21:02:06,070 [salt.state       :1951][INFO    ][14809] Completed state [maas.wait_for_machine_status] at time 21:02:06.070350 duration_in_ms=2847.814
2019-06-18 21:02:06,076 [salt.minion      :1711][INFO    ][14809] Returning information for job: 20190618210153452025
2019-06-18 21:02:06,633 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command state.apply with jid 20190618210206619631
2019-06-18 21:02:06,661 [salt.minion      :1432][INFO    ][14835] Starting a new job with PID 14835
2019-06-18 21:02:07,767 [salt.state       :915 ][INFO    ][14835] Loading fresh modules for state activity
2019-06-18 21:02:07,920 [salt.state       :1780][INFO    ][14835] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:02:07.920191
2019-06-18 21:02:07,920 [salt.state       :1813][INFO    ][14835] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 21:02:07,923 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14835] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 21:02:09,709 [salt.state       :300 ][INFO    ][14835] {'pid': 14842, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 21:02:09,710 [salt.state       :1951][INFO    ][14835] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:02:09.710575 duration_in_ms=1790.383
2019-06-18 21:02:09,715 [salt.state       :1780][INFO    ][14835] Running state [maas_machines_storage_cmp002_lvm] at time 21:02:09.715001
2019-06-18 21:02:09,715 [salt.state       :1813][INFO    ][14835] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-18 21:02:10,270 [salt.state       :300 ][INFO    ][14835] Machine cmp002 is not in Ready state.
2019-06-18 21:02:10,271 [salt.state       :1951][INFO    ][14835] Completed state [maas_machines_storage_cmp002_lvm] at time 21:02:10.271118 duration_in_ms=556.116
2019-06-18 21:02:10,271 [salt.state       :1780][INFO    ][14835] Running state [maas_machines_storage_cmp001_lvm] at time 21:02:10.271632
2019-06-18 21:02:10,272 [salt.state       :1813][INFO    ][14835] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-18 21:02:10,811 [salt.state       :300 ][INFO    ][14835] Machine cmp001 is not in Ready state.
2019-06-18 21:02:10,812 [salt.state       :1951][INFO    ][14835] Completed state [maas_machines_storage_cmp001_lvm] at time 21:02:10.812115 duration_in_ms=540.483
2019-06-18 21:02:10,816 [salt.minion      :1711][INFO    ][14835] Returning information for job: 20190618210206619631
2019-06-18 21:02:11,363 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command state.apply with jid 20190618210211349219
2019-06-18 21:02:11,387 [salt.minion      :1432][INFO    ][14854] Starting a new job with PID 14854
2019-06-18 21:02:12,571 [salt.state       :915 ][INFO    ][14854] Loading fresh modules for state activity
2019-06-18 21:02:12,663 [salt.state       :1780][INFO    ][14854] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:02:12.663252
2019-06-18 21:02:12,663 [salt.state       :1813][INFO    ][14854] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 21:02:12,665 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14854] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 21:02:14,390 [salt.state       :300 ][INFO    ][14854] {'pid': 14874, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 21:02:14,391 [salt.state       :1951][INFO    ][14854] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:02:14.391264 duration_in_ms=1728.012
2019-06-18 21:02:14,393 [salt.state       :1780][INFO    ][14854] Running state [maas.deploy_machines] at time 21:02:14.393356
2019-06-18 21:02:14,394 [salt.state       :1813][INFO    ][14854] Executing state module.run for [maas.deploy_machines]
2019-06-18 21:02:14,394 [salt.utils.decorators:613 ][WARNING ][14854] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 21:02:14,974 [salt.loaded.ext.module.maas:684 ][INFO    ][14854] deploymachines hwe_kernel=hwe-16.04 system_id=m3nkxy distro_series=xenial
2019-06-18 21:02:17,458 [salt.state       :300 ][INFO    ][14854] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-06-18 21:02:17,459 [salt.state       :1951][INFO    ][14854] Completed state [maas.deploy_machines] at time 21:02:17.459351 duration_in_ms=3065.994
2019-06-18 21:02:17,466 [salt.minion      :1711][INFO    ][14854] Returning information for job: 20190618210211349219
2019-06-18 21:02:18,022 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command state.apply with jid 20190618210218008152
2019-06-18 21:02:18,051 [salt.minion      :1432][INFO    ][14934] Starting a new job with PID 14934
2019-06-18 21:02:26,104 [salt.state       :915 ][INFO    ][14934] Loading fresh modules for state activity
2019-06-18 21:02:26,207 [salt.state       :1780][INFO    ][14934] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:02:26.207645
2019-06-18 21:02:26,207 [salt.state       :1813][INFO    ][14934] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-18 21:02:26,209 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14934] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-18 21:02:27,903 [salt.state       :300 ][INFO    ][14934] {'pid': 14944, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-18 21:02:27,904 [salt.state       :1951][INFO    ][14934] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:02:27.904172 duration_in_ms=1696.526
2019-06-18 21:02:27,908 [salt.state       :1780][INFO    ][14934] Running state [maas.wait_for_machine_status] at time 21:02:27.907910
2019-06-18 21:02:27,908 [salt.state       :1813][INFO    ][14934] Executing state module.run for [maas.wait_for_machine_status]
2019-06-18 21:02:27,909 [salt.utils.decorators:613 ][WARNING ][14934] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-18 21:02:30,993 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2246.93960905s left)
2019-06-18 21:02:33,080 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618210233067074
2019-06-18 21:02:33,106 [salt.minion      :1432][INFO    ][14986] Starting a new job with PID 14986
2019-06-18 21:02:33,130 [salt.minion      :1711][INFO    ][14986] Returning information for job: 20190618210233067074
2019-06-18 21:03:03,297 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618210303238234
2019-06-18 21:03:03,322 [salt.minion      :1432][INFO    ][15010] Starting a new job with PID 15010
2019-06-18 21:03:03,349 [salt.minion      :1711][INFO    ][15010] Returning information for job: 20190618210303238234
2019-06-18 21:03:03,815 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.11686301s left)
2019-06-18 21:03:33,397 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618210333382904
2019-06-18 21:03:33,424 [salt.minion      :1432][INFO    ][15077] Starting a new job with PID 15077
2019-06-18 21:03:33,449 [salt.minion      :1711][INFO    ][15077] Returning information for job: 20190618210333382904
2019-06-18 21:03:36,611 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.32131004s left)
2019-06-18 21:04:03,466 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618210403455425
2019-06-18 21:04:03,496 [salt.minion      :1432][INFO    ][15101] Starting a new job with PID 15101
2019-06-18 21:04:03,524 [salt.minion      :1711][INFO    ][15101] Returning information for job: 20190618210403455425
2019-06-18 21:04:09,318 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.61407304s left)
2019-06-18 21:04:33,560 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618210433548413
2019-06-18 21:04:33,589 [salt.minion      :1432][INFO    ][15161] Starting a new job with PID 15161
2019-06-18 21:04:33,613 [salt.minion      :1711][INFO    ][15161] Returning information for job: 20190618210433548413
2019-06-18 21:04:42,285 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.64712715s left)
2019-06-18 21:05:03,632 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618210503622256
2019-06-18 21:05:03,665 [salt.minion      :1432][INFO    ][15183] Starting a new job with PID 15183
2019-06-18 21:05:03,689 [salt.minion      :1711][INFO    ][15183] Returning information for job: 20190618210503622256
2019-06-18 21:05:15,179 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2082.75375414s left)
2019-06-18 21:05:33,727 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618210533713947
2019-06-18 21:05:33,757 [salt.minion      :1432][INFO    ][15241] Starting a new job with PID 15241
2019-06-18 21:05:33,784 [salt.minion      :1711][INFO    ][15241] Returning information for job: 20190618210533713947
2019-06-18 21:05:47,924 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.00787711s left)
2019-06-18 21:06:03,815 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618210603806455
2019-06-18 21:06:03,839 [salt.minion      :1432][INFO    ][15264] Starting a new job with PID 15264
2019-06-18 21:06:03,871 [salt.minion      :1711][INFO    ][15264] Returning information for job: 20190618210603806455
2019-06-18 21:06:20,772 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.16018414s left)
2019-06-18 21:06:33,921 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618210633909391
2019-06-18 21:06:33,947 [salt.minion      :1432][INFO    ][15344] Starting a new job with PID 15344
2019-06-18 21:06:33,976 [salt.minion      :1711][INFO    ][15344] Returning information for job: 20190618210633909391
2019-06-18 21:06:53,572 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.36046815s left)
2019-06-18 21:07:04,013 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618210704004291
2019-06-18 21:07:04,032 [salt.minion      :1432][INFO    ][15371] Starting a new job with PID 15371
2019-06-18 21:07:04,072 [salt.minion      :1711][INFO    ][15371] Returning information for job: 20190618210704004291
2019-06-18 21:07:26,680 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.25180411s left)
2019-06-18 21:07:34,145 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618210734130991
2019-06-18 21:07:34,171 [salt.minion      :1432][INFO    ][15527] Starting a new job with PID 15527
2019-06-18 21:07:34,195 [salt.minion      :1711][INFO    ][15527] Returning information for job: 20190618210734130991
2019-06-18 21:07:59,587 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.34486508s left)
2019-06-18 21:08:04,276 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618210804258197
2019-06-18 21:08:04,310 [salt.minion      :1432][INFO    ][15553] Starting a new job with PID 15553
2019-06-18 21:08:04,339 [salt.minion      :1711][INFO    ][15553] Returning information for job: 20190618210804258197
2019-06-18 21:08:32,536 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.39623404s left)
2019-06-18 21:08:34,420 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618210834407084
2019-06-18 21:08:34,450 [salt.minion      :1432][INFO    ][15681] Starting a new job with PID 15681
2019-06-18 21:08:34,476 [salt.minion      :1711][INFO    ][15681] Returning information for job: 20190618210834407084
2019-06-18 21:09:04,555 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618210904544403
2019-06-18 21:09:04,579 [salt.minion      :1432][INFO    ][15702] Starting a new job with PID 15702
2019-06-18 21:09:04,602 [salt.minion      :1711][INFO    ][15702] Returning information for job: 20190618210904544403
2019-06-18 21:09:05,550 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.38293409s left)
2019-06-18 21:09:34,675 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618210934663721
2019-06-18 21:09:34,702 [salt.minion      :1432][INFO    ][15798] Starting a new job with PID 15798
2019-06-18 21:09:34,725 [salt.minion      :1711][INFO    ][15798] Returning information for job: 20190618210934663721
2019-06-18 21:09:38,434 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.4981091s left)
2019-06-18 21:10:04,820 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618211004807379
2019-06-18 21:10:04,847 [salt.minion      :1432][INFO    ][15818] Starting a new job with PID 15818
2019-06-18 21:10:04,872 [salt.minion      :1711][INFO    ][15818] Returning information for job: 20190618211004807379
2019-06-18 21:10:11,416 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.51634216s left)
2019-06-18 21:10:34,975 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618211034958994
2019-06-18 21:10:35,003 [salt.minion      :1432][INFO    ][16024] Starting a new job with PID 16024
2019-06-18 21:10:35,025 [salt.minion      :1711][INFO    ][16024] Returning information for job: 20190618211034958994
2019-06-18 21:10:44,392 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1753.540519s left)
2019-06-18 21:11:05,141 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618211105127781
2019-06-18 21:11:05,171 [salt.minion      :1432][INFO    ][16045] Starting a new job with PID 16045
2019-06-18 21:11:05,194 [salt.minion      :1711][INFO    ][16045] Returning information for job: 20190618211105127781
2019-06-18 21:11:17,437 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.49515104s left)
2019-06-18 21:11:35,298 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618211135285599
2019-06-18 21:11:35,327 [salt.minion      :1432][INFO    ][16104] Starting a new job with PID 16104
2019-06-18 21:11:35,350 [salt.minion      :1711][INFO    ][16104] Returning information for job: 20190618211135285599
2019-06-18 21:11:50,327 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.60524106s left)
2019-06-18 21:12:05,480 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618211205463171
2019-06-18 21:12:05,512 [salt.minion      :1432][INFO    ][16124] Starting a new job with PID 16124
2019-06-18 21:12:05,537 [salt.minion      :1711][INFO    ][16124] Returning information for job: 20190618211205463171
2019-06-18 21:12:23,311 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1654.62102103s left)
2019-06-18 21:12:35,648 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618211235637461
2019-06-18 21:12:35,671 [salt.minion      :1432][INFO    ][16180] Starting a new job with PID 16180
2019-06-18 21:12:35,694 [salt.minion      :1711][INFO    ][16180] Returning information for job: 20190618211235637461
2019-06-18 21:12:56,263 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1621.66889215s left)
2019-06-18 21:13:05,828 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618211305810608
2019-06-18 21:13:05,856 [salt.minion      :1432][INFO    ][16202] Starting a new job with PID 16202
2019-06-18 21:13:05,881 [salt.minion      :1711][INFO    ][16202] Returning information for job: 20190618211305810608
2019-06-18 21:13:29,334 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1588.59783816s left)
2019-06-18 21:13:36,012 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618211336003073
2019-06-18 21:13:36,034 [salt.minion      :1432][INFO    ][16300] Starting a new job with PID 16300
2019-06-18 21:13:36,063 [salt.minion      :1711][INFO    ][16300] Returning information for job: 20190618211336003073
2019-06-18 21:14:02,349 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1555.58288503s left)
2019-06-18 21:14:06,207 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618211406194970
2019-06-18 21:14:06,237 [salt.minion      :1432][INFO    ][16451] Starting a new job with PID 16451
2019-06-18 21:14:06,261 [salt.minion      :1711][INFO    ][16451] Returning information for job: 20190618211406194970
2019-06-18 21:14:35,593 [salt.loaded.ext.module.maas:1023][INFO    ][14934] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1522.33911419s left)
2019-06-18 21:14:36,419 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618211436404109
2019-06-18 21:14:36,451 [salt.minion      :1432][INFO    ][16528] Starting a new job with PID 16528
2019-06-18 21:14:36,477 [salt.minion      :1711][INFO    ][16528] Returning information for job: 20190618211436404109
2019-06-18 21:14:40,492 [salt.utils.schedule:1377][INFO    ][5906] Running scheduled job: __mine_interval
2019-06-18 21:15:06,559 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command saltutil.find_job with jid 20190618211506547132
2019-06-18 21:15:06,583 [salt.minion      :1432][INFO    ][16573] Starting a new job with PID 16573
2019-06-18 21:15:06,607 [salt.minion      :1711][INFO    ][16573] Returning information for job: 20190618211506547132
2019-06-18 21:15:08,675 [salt.state       :300 ][INFO    ][14934] {'ret': True}
2019-06-18 21:15:08,676 [salt.state       :1951][INFO    ][14934] Completed state [maas.wait_for_machine_status] at time 21:15:08.676148 duration_in_ms=760768.231
2019-06-18 21:15:08,685 [salt.minion      :1711][INFO    ][14934] Returning information for job: 20190618210218008152
2019-06-18 22:14:40,491 [salt.utils.schedule:1377][INFO    ][5906] Running scheduled job: __mine_interval
2019-06-18 22:42:37,200 [salt.minion      :1308][INFO    ][5906] User sudo_ubuntu Executing command cp.push_dir with jid 20190618224237192122
2019-06-18 22:42:37,224 [salt.minion      :1432][INFO    ][22764] Starting a new job with PID 22764
