2019-03-13 08:02:20,425 [salt.utils.decorators:613 ][WARNING ][2315] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-13 08:02:21,560 [salt.utils.decorators:613 ][WARNING ][2315] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-13 08:02:25,013 [salt.loaded.int.states.file:2298][WARNING ][2488] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-03-13 08:02:46,621 [salt.state       :2022][WARNING ][2717] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-13 08:02:49,766 [salt.utils.decorators:613 ][WARNING ][2717] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-13 08:03:57,901 [salt.utils.decorators:613 ][WARNING ][2717] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-13 08:04:24,770 [salt.utils.decorators:613 ][WARNING ][2717] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-13 08:04:25,694 [salt.utils.decorators:613 ][WARNING ][2717] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-13 08:04:28,245 [salt.loaded.ext.module.maasng:1008][WARNING ][2717] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-03-13 08:04:28,245 [salt.loaded.ext.module.maasng:1011][WARNING ][2717] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-03-13 08:04:28,866 [salt.loaded.ext.module.maasng:1235][WARNING ][2717] Ignoring parameter vlan:0
2019-03-13 08:04:29,592 [salt.utils.decorators:613 ][WARNING ][2717] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-13 08:04:41,408 [salt.utils.decorators:613 ][WARNING ][6232] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-13 08:04:41,470 [salt.loaded.ext.module.maas:412 ][WARNING ][6232] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-13 08:04:42,913 [salt.loaded.ext.module.maas:412 ][WARNING ][6232] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-13 08:04:44,112 [salt.loaded.ext.module.maas:412 ][WARNING ][6232] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-13 08:04:45,298 [salt.loaded.ext.module.maas:412 ][WARNING ][6232] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-13 08:04:46,472 [salt.loaded.ext.module.maas:412 ][WARNING ][6232] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-13 08:04:51,020 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6864] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-13 08:04:51,054 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6864] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-03-13 08:04:51,109 [salt.utils.parsers:1051][WARNING ][388] Minion received a SIGTERM. Exiting.
2019-03-13 08:04:52,461 [salt.cli.daemons :293 ][INFO    ][6933] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-03-13 08:04:52,634 [salt.cli.daemons :82  ][INFO    ][6933] Starting up the Salt Minion
2019-03-13 08:04:52,637 [salt.utils.event :1017][INFO    ][6933] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-03-13 08:04:53,897 [salt.minion      :976 ][INFO    ][6933] Creating minion process manager
2019-03-13 08:04:55,793 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6933] Executing command ['date', '+%z'] in directory '/root'
2019-03-13 08:04:55,821 [salt.utils.schedule:568 ][INFO    ][6933] Updating job settings for scheduled job: __mine_interval
2019-03-13 08:04:55,823 [salt.minion      :1108][INFO    ][6933] Added mine.update to scheduler
2019-03-13 08:04:55,829 [salt.minion      :1975][INFO    ][6933] Minion is starting as user 'root'
2019-03-13 08:04:55,846 [salt.minion      :2336][INFO    ][6933] Minion is ready to receive requests!
2019-03-13 08:05:19,009 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command state.apply with jid 20190313080518995767
2019-03-13 08:05:19,030 [salt.minion      :1432][INFO    ][7025] Starting a new job with PID 7025
2019-03-13 08:05:26,778 [salt.state       :915 ][INFO    ][7025] Loading fresh modules for state activity
2019-03-13 08:05:26,837 [salt.fileclient  :1219][INFO    ][7025] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-03-13 08:05:26,884 [salt.state       :1780][INFO    ][7025] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:05:26.884171
2019-03-13 08:05:26,884 [salt.state       :1813][INFO    ][7025] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-13 08:05:26,886 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7025] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-13 08:05:28,737 [salt.state       :300 ][INFO    ][7025] {'pid': 7032, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-13 08:05:28,738 [salt.state       :1951][INFO    ][7025] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:05:28.738903 duration_in_ms=1854.733
2019-03-13 08:05:28,741 [salt.state       :1780][INFO    ][7025] Running state [maas.wait_for_machine_status] at time 08:05:28.741493
2019-03-13 08:05:28,742 [salt.state       :1813][INFO    ][7025] Executing state module.run for [maas.wait_for_machine_status]
2019-03-13 08:05:28,742 [salt.utils.decorators:613 ][WARNING ][7025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-13 08:05:29,649 [salt.loaded.ext.module.maas:1023][INFO    ][7025] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.11100101s left)
2019-03-13 08:05:34,092 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313080534082202
2019-03-13 08:05:34,111 [salt.minion      :1432][INFO    ][7070] Starting a new job with PID 7070
2019-03-13 08:05:34,139 [salt.minion      :1711][INFO    ][7070] Returning information for job: 20190313080534082202
2019-03-13 08:06:00,486 [salt.loaded.ext.module.maas:1023][INFO    ][7025] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.27447987s left)
2019-03-13 08:06:04,163 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313080604144719
2019-03-13 08:06:04,177 [salt.minion      :1432][INFO    ][7129] Starting a new job with PID 7129
2019-03-13 08:06:04,206 [salt.minion      :1711][INFO    ][7129] Returning information for job: 20190313080604144719
2019-03-13 08:06:31,293 [salt.loaded.ext.module.maas:1023][INFO    ][7025] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.46694303s left)
2019-03-13 08:06:34,210 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313080634201419
2019-03-13 08:06:34,233 [salt.minion      :1432][INFO    ][7170] Starting a new job with PID 7170
2019-03-13 08:06:34,264 [salt.minion      :1711][INFO    ][7170] Returning information for job: 20190313080634201419
2019-03-13 08:07:02,122 [salt.loaded.ext.module.maas:1023][INFO    ][7025] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.63800192s left)
2019-03-13 08:07:04,434 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313080704420620
2019-03-13 08:07:04,458 [salt.minion      :1432][INFO    ][7230] Starting a new job with PID 7230
2019-03-13 08:07:04,486 [salt.minion      :1711][INFO    ][7230] Returning information for job: 20190313080704420620
2019-03-13 08:07:33,155 [salt.loaded.ext.module.maas:1023][INFO    ][7025] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.60558891s left)
2019-03-13 08:07:34,489 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313080734479529
2019-03-13 08:07:34,509 [salt.minion      :1432][INFO    ][7296] Starting a new job with PID 7296
2019-03-13 08:07:34,536 [salt.minion      :1711][INFO    ][7296] Returning information for job: 20190313080734479529
2019-03-13 08:08:04,034 [salt.loaded.ext.module.maas:1023][INFO    ][7025] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.72671294s left)
2019-03-13 08:08:04,558 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313080804547467
2019-03-13 08:08:04,572 [salt.minion      :1432][INFO    ][7457] Starting a new job with PID 7457
2019-03-13 08:08:04,598 [salt.minion      :1711][INFO    ][7457] Returning information for job: 20190313080804547467
2019-03-13 08:08:34,632 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313080834621691
2019-03-13 08:08:34,645 [salt.minion      :1432][INFO    ][7546] Starting a new job with PID 7546
2019-03-13 08:08:34,680 [salt.minion      :1711][INFO    ][7546] Returning information for job: 20190313080834621691
2019-03-13 08:08:35,194 [salt.loaded.ext.module.maas:1023][INFO    ][7025] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.56668687s left)
2019-03-13 08:09:04,725 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313080904717495
2019-03-13 08:09:04,739 [salt.minion      :1432][INFO    ][7896] Starting a new job with PID 7896
2019-03-13 08:09:04,765 [salt.minion      :1711][INFO    ][7896] Returning information for job: 20190313080904717495
2019-03-13 08:09:06,350 [salt.loaded.ext.module.maas:1023][INFO    ][7025] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.41031599s left)
2019-03-13 08:09:34,810 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313080934795485
2019-03-13 08:09:34,835 [salt.minion      :1432][INFO    ][8037] Starting a new job with PID 8037
2019-03-13 08:09:34,869 [salt.minion      :1711][INFO    ][8037] Returning information for job: 20190313080934795485
2019-03-13 08:09:38,156 [salt.loaded.ext.module.maas:1023][INFO    ][7025] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.60426402s left)
2019-03-13 08:10:04,986 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081004973155
2019-03-13 08:10:05,009 [salt.minion      :1432][INFO    ][8388] Starting a new job with PID 8388
2019-03-13 08:10:05,037 [salt.minion      :1711][INFO    ][8388] Returning information for job: 20190313081004973155
2019-03-13 08:10:10,352 [salt.loaded.ext.module.maas:1023][INFO    ][7025] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.40850782s left)
2019-03-13 08:10:35,093 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081035080371
2019-03-13 08:10:35,112 [salt.minion      :1432][INFO    ][8560] Starting a new job with PID 8560
2019-03-13 08:10:35,141 [salt.minion      :1711][INFO    ][8560] Returning information for job: 20190313081035080371
2019-03-13 08:10:42,530 [salt.loaded.ext.module.maas:1023][INFO    ][7025] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1186.22994685s left)
2019-03-13 08:11:05,217 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081105206148
2019-03-13 08:11:05,239 [salt.minion      :1432][INFO    ][8766] Starting a new job with PID 8766
2019-03-13 08:11:05,264 [salt.minion      :1711][INFO    ][8766] Returning information for job: 20190313081105206148
2019-03-13 08:11:14,641 [salt.loaded.ext.module.maas:1023][INFO    ][7025] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1154.11889482s left)
2019-03-13 08:11:35,350 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081135337248
2019-03-13 08:11:35,370 [salt.minion      :1432][INFO    ][8930] Starting a new job with PID 8930
2019-03-13 08:11:35,397 [salt.minion      :1711][INFO    ][8930] Returning information for job: 20190313081135337248
2019-03-13 08:11:47,610 [salt.state       :300 ][INFO    ][7025] {'ret': True}
2019-03-13 08:11:47,611 [salt.state       :1951][INFO    ][7025] Completed state [maas.wait_for_machine_status] at time 08:11:47.611397 duration_in_ms=378869.904
2019-03-13 08:11:47,616 [salt.minion      :1711][INFO    ][7025] Returning information for job: 20190313080518995767
2019-03-13 08:11:48,461 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command state.apply with jid 20190313081148448948
2019-03-13 08:11:48,480 [salt.minion      :1432][INFO    ][9000] Starting a new job with PID 9000
2019-03-13 08:11:57,177 [salt.state       :915 ][INFO    ][9000] Loading fresh modules for state activity
2019-03-13 08:11:57,246 [salt.fileclient  :1219][INFO    ][9000] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-03-13 08:11:57,357 [salt.state       :1780][INFO    ][9000] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:11:57.356933
2019-03-13 08:11:57,357 [salt.state       :1813][INFO    ][9000] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-13 08:11:57,359 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9000] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-13 08:11:59,486 [salt.state       :300 ][INFO    ][9000] {'pid': 9060, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-13 08:11:59,487 [salt.state       :1951][INFO    ][9000] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:11:59.487393 duration_in_ms=2130.46
2019-03-13 08:11:59,492 [salt.state       :1780][INFO    ][9000] Running state [maas_machines_storage_cmp002_lvm] at time 08:11:59.491886
2019-03-13 08:11:59,492 [salt.state       :1813][INFO    ][9000] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-13 08:12:00,671 [salt.loaded.ext.module.maasng:610 ][INFO    ][9000] 8t7m6b
2019-03-13 08:12:00,671 [salt.loaded.ext.module.maasng:626 ][INFO    ][9000] sda
2019-03-13 08:12:01,214 [salt.loaded.ext.module.maasng:361 ][INFO    ][9000] 8t7m6b
2019-03-13 08:12:01,308 [salt.loaded.ext.module.maasng:367 ][INFO    ][9000] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'6f70fb7b-0c86-443e-a49c-8e54d9becd44', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8t7m6b', u'device_id': 1, u'filesystem': {u'mount_options': None, u'uuid': u'bbbcc732-8cb1-4d3e-886e-03ead5baa228', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/8t7m6b/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}], u'filesystem': None, u'name': u'sda', u'system_id': u'8t7m6b', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/8t7m6b/blockdevices/1/'}, {u'size': 800097042432, u'model': None, u'uuid': u'42dbe76c-e805-4810-9b97-b7cc5dce3095', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'cf5dc7e7-21da-4b85-85f0-19029f611d6b', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'8t7m6b', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/8t7m6b/blockdevices/3/'}]
2019-03-13 08:12:01,309 [salt.loaded.ext.module.maasng:632 ][INFO    ][9000] vgroot
2019-03-13 08:12:01,309 [salt.loaded.ext.module.maasng:635 ][INFO    ][9000] lvroot
2019-03-13 08:12:01,309 [salt.loaded.ext.module.maasng:639 ][INFO    ][9000] 107374182400
2019-03-13 08:12:01,913 [salt.loaded.ext.module.maasng:645 ][INFO    ][9000] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'esqc4e', 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.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'esqc4e', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'esqc4e', 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.38'}], u'system_id': u'8t7m6b', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/8t7m6b/interfaces/4/'}, u'fqdn': u'cmp002.maas', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'18d4693b-4ce9-46e2-ad73-8f616b11fc20', u'resource_uri': u'/MAAS/api/2.0/nodes/8t7m6b/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8t7m6b', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'dcbbffa8-e86b-4d99-8aa2-e86b8249cb6f', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'8t7m6b', u'resource_uri': u'/MAAS/api/2.0/nodes/8t7m6b/blockdevices/1/', 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'size': 800109715456}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'8t7m6b', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'blockdevice_set': [{u'partition_table_type': u'MBR', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'18d4693b-4ce9-46e2-ad73-8f616b11fc20', u'resource_uri': u'/MAAS/api/2.0/nodes/8t7m6b/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8t7m6b', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'dcbbffa8-e86b-4d99-8aa2-e86b8249cb6f', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'8t7m6b', u'resource_uri': u'/MAAS/api/2.0/nodes/8t7m6b/blockdevices/1/', 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'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'partition_table_type': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'75f5ef12-6383-43fe-b7c8-20b1e1140756', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8t7m6b', u'resource_uri': u'/MAAS/api/2.0/nodes/8t7m6b/blockdevices/11/', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'a8a0c8ab-55ea-4105-989a-b79bc35fb734', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'18d4693b-4ce9-46e2-ad73-8f616b11fc20', u'resource_uri': u'/MAAS/api/2.0/nodes/8t7m6b/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8t7m6b', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'dcbbffa8-e86b-4d99-8aa2-e86b8249cb6f', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'8t7m6b', u'resource_uri': u'/MAAS/api/2.0/nodes/8t7m6b/blockdevices/1/', 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'size': 800109715456}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'8t7m6b', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'commissioning_status_name': u'Passed', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'model': None, u'partition_table_type': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'75f5ef12-6383-43fe-b7c8-20b1e1140756', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8t7m6b', u'resource_uri': u'/MAAS/api/2.0/nodes/8t7m6b/blockdevices/11/', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'a8a0c8ab-55ea-4105-989a-b79bc35fb734', 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'size': 107374182400}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'min_hwe_kernel': u'hwe-16.04', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'esqc4e', 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.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'esqc4e', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'esqc4e', 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.38'}], u'system_id': u'8t7m6b', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/8t7m6b/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'8t7m6b', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/8t7m6b/interfaces/13/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'8t7m6b', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/8t7m6b/interfaces/15/'}, {u'name': u'ens2f1', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 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'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'8t7m6b', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/8t7m6b/interfaces/14/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'esqc4e', 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': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'esqc4e', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'esqc4e', 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.40'}], u'system_id': u'8t7m6b', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/8t7m6b/interfaces/11/'}, {u'name': u'ens2f0', u'links': [{u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'8t7m6b', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/8t7m6b/interfaces/12/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/8t7m6b/'}
2019-03-13 08:12:01,915 [salt.state       :300 ][INFO    ][9000] {'new': {'storage_layout': 'lvm'}}
2019-03-13 08:12:01,916 [salt.state       :1951][INFO    ][9000] Completed state [maas_machines_storage_cmp002_lvm] at time 08:12:01.916106 duration_in_ms=2424.219
2019-03-13 08:12:01,916 [salt.state       :1780][INFO    ][9000] Running state [maas_machines_storage_cmp001_lvm] at time 08:12:01.916661
2019-03-13 08:12:01,917 [salt.state       :1813][INFO    ][9000] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-13 08:12:03,081 [salt.loaded.ext.module.maasng:610 ][INFO    ][9000] ah8fdk
2019-03-13 08:12:03,081 [salt.loaded.ext.module.maasng:626 ][INFO    ][9000] sda
2019-03-13 08:12:03,486 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081203476994
2019-03-13 08:12:03,502 [salt.minion      :1432][INFO    ][9081] Starting a new job with PID 9081
2019-03-13 08:12:03,528 [salt.minion      :1711][INFO    ][9081] Returning information for job: 20190313081203476994
2019-03-13 08:12:03,653 [salt.loaded.ext.module.maasng:361 ][INFO    ][9000] ah8fdk
2019-03-13 08:12:03,748 [salt.loaded.ext.module.maasng:367 ][INFO    ][9000] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'b5c22917-fc02-456a-8986-ea29a183247c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ah8fdk', u'device_id': 2, u'filesystem': {u'mount_options': None, u'uuid': u'2b901bb0-7d9e-44eb-b487-6d105813ffd3', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ah8fdk/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'size': 800101236736}], u'filesystem': None, u'name': u'sda', u'system_id': u'ah8fdk', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/ah8fdk/blockdevices/2/'}, {u'size': 800097042432, u'model': None, u'uuid': u'd62c4504-b144-42db-b90e-a4015c6bb771', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'b1f8d36e-5d2e-4c00-ba4d-226fd1788738', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'ah8fdk', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ah8fdk/blockdevices/4/'}]
2019-03-13 08:12:03,749 [salt.loaded.ext.module.maasng:632 ][INFO    ][9000] vgroot
2019-03-13 08:12:03,749 [salt.loaded.ext.module.maasng:635 ][INFO    ][9000] lvroot
2019-03-13 08:12:03,749 [salt.loaded.ext.module.maasng:639 ][INFO    ][9000] 107374182400
2019-03-13 08:12:04,341 [salt.loaded.ext.module.maasng:645 ][INFO    ][9000] {u'hwe_kernel': u'', u'swap_size': None, u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'esqc4e', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'esqc4e', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'esqc4e', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'ah8fdk', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 5, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ah8fdk/interfaces/5/'}, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'ah8fdk', 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'size': 107374182400, u'uuid': u'93ee5a0a-c618-4447-84ea-19d83328d335', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'b172dc92-304a-46e3-b1da-7053dd91099f', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'ah8fdk', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ah8fdk/blockdevices/12/'}], u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'76dd215d-c92f-4ec2-98b1-b56028cc7d28', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ah8fdk', u'device_id': 2, u'filesystem': {u'mount_options': None, u'uuid': u'd97005eb-fb02-4e05-b8ba-e79676fb2589', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ah8fdk/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'filesystem': None, u'name': u'sda', u'system_id': u'ah8fdk', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/ah8fdk/blockdevices/2/'}, {u'size': 107374182400, u'model': None, u'uuid': u'93ee5a0a-c618-4447-84ea-19d83328d335', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'b172dc92-304a-46e3-b1da-7053dd91099f', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'ah8fdk', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/ah8fdk/blockdevices/12/'}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ah8fdk', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'76dd215d-c92f-4ec2-98b1-b56028cc7d28', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ah8fdk', u'device_id': 2, u'filesystem': {u'mount_options': None, u'uuid': u'd97005eb-fb02-4e05-b8ba-e79676fb2589', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ah8fdk/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'filesystem': None, u'name': u'sda', u'system_id': u'ah8fdk', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/ah8fdk/blockdevices/2/'}, u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'esqc4e', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'esqc4e', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'esqc4e', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'ah8fdk', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 5, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ah8fdk/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'ah8fdk', u'mac_address': u'38:ea:a7:8f:1f:d4', u'id': 18, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ah8fdk/interfaces/18/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'ah8fdk', u'mac_address': u'38:ea:a7:8f:1f:d5', u'id': 20, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ah8fdk/interfaces/20/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'esqc4e', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'esqc4e', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'esqc4e', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.44'}], u'system_id': u'ah8fdk', u'mac_address': u'9c:b6:54:8a:95:a4', u'id': 16, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ah8fdk/interfaces/16/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'ah8fdk', u'mac_address': u'38:ea:a7:8f:52:cc', u'id': 17, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ah8fdk/interfaces/17/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'ah8fdk', u'mac_address': u'38:ea:a7:8f:52:cd', u'id': 19, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ah8fdk/interfaces/19/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'76dd215d-c92f-4ec2-98b1-b56028cc7d28', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ah8fdk', u'device_id': 2, u'filesystem': {u'mount_options': None, u'uuid': u'd97005eb-fb02-4e05-b8ba-e79676fb2589', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ah8fdk/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'filesystem': None, u'name': u'sda', u'system_id': u'ah8fdk', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/ah8fdk/blockdevices/2/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 4, u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/ah8fdk/'}
2019-03-13 08:12:04,344 [salt.state       :300 ][INFO    ][9000] {'new': {'storage_layout': 'lvm'}}
2019-03-13 08:12:04,344 [salt.state       :1951][INFO    ][9000] Completed state [maas_machines_storage_cmp001_lvm] at time 08:12:04.344876 duration_in_ms=2428.214
2019-03-13 08:12:04,350 [salt.minion      :1711][INFO    ][9000] Returning information for job: 20190313081148448948
2019-03-13 08:12:05,035 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command state.apply with jid 20190313081205024061
2019-03-13 08:12:05,058 [salt.minion      :1432][INFO    ][9090] Starting a new job with PID 9090
2019-03-13 08:12:05,921 [salt.state       :915 ][INFO    ][9090] Loading fresh modules for state activity
2019-03-13 08:12:05,977 [salt.fileclient  :1219][INFO    ][9090] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-03-13 08:12:06,028 [salt.state       :1780][INFO    ][9090] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:12:06.028187
2019-03-13 08:12:06,028 [salt.state       :1813][INFO    ][9090] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-13 08:12:06,030 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9090] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-13 08:12:07,963 [salt.state       :300 ][INFO    ][9090] {'pid': 9097, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-13 08:12:07,964 [salt.state       :1951][INFO    ][9090] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:12:07.964695 duration_in_ms=1936.506
2019-03-13 08:12:07,968 [salt.state       :1780][INFO    ][9090] Running state [maas.deploy_machines] at time 08:12:07.967939
2019-03-13 08:12:07,968 [salt.state       :1813][INFO    ][9090] Executing state module.run for [maas.deploy_machines]
2019-03-13 08:12:07,969 [salt.utils.decorators:613 ][WARNING ][9090] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-13 08:12:08,530 [salt.loaded.ext.module.maas:684 ][INFO    ][9090] deploymachines hwe_kernel=hwe-16.04 system_id=8t7m6b distro_series=xenial
2019-03-13 08:12:10,885 [salt.loaded.ext.module.maas:684 ][INFO    ][9090] deploymachines hwe_kernel=hwe-16.04 system_id=ah8fdk distro_series=xenial
2019-03-13 08:12:13,261 [salt.loaded.ext.module.maas:684 ][INFO    ][9090] deploymachines hwe_kernel=hwe-16.04 system_id=gdbb8t distro_series=xenial
2019-03-13 08:12:15,622 [salt.loaded.ext.module.maas:684 ][INFO    ][9090] deploymachines hwe_kernel=hwe-16.04 system_id=cahak3 distro_series=xenial
2019-03-13 08:12:17,978 [salt.loaded.ext.module.maas:684 ][INFO    ][9090] deploymachines hwe_kernel=hwe-16.04 system_id=yx83q4 distro_series=xenial
2019-03-13 08:12:20,084 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081220075655
2019-03-13 08:12:20,102 [salt.minion      :1432][INFO    ][9356] Starting a new job with PID 9356
2019-03-13 08:12:20,131 [salt.minion      :1711][INFO    ][9356] Returning information for job: 20190313081220075655
2019-03-13 08:12:20,441 [salt.state       :300 ][INFO    ][9090] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-13 08:12:20,441 [salt.state       :1951][INFO    ][9090] Completed state [maas.deploy_machines] at time 08:12:20.441809 duration_in_ms=12473.869
2019-03-13 08:12:20,444 [salt.minion      :1711][INFO    ][9090] Returning information for job: 20190313081205024061
2019-03-13 08:12:21,153 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command state.apply with jid 20190313081221143733
2019-03-13 08:12:21,170 [salt.minion      :1432][INFO    ][9373] Starting a new job with PID 9373
2019-03-13 08:12:29,239 [salt.state       :915 ][INFO    ][9373] Loading fresh modules for state activity
2019-03-13 08:12:29,291 [salt.fileclient  :1219][INFO    ][9373] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-03-13 08:12:29,334 [salt.state       :1780][INFO    ][9373] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:12:29.334499
2019-03-13 08:12:29,335 [salt.state       :1813][INFO    ][9373] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-13 08:12:29,336 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9373] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-13 08:12:31,305 [salt.state       :300 ][INFO    ][9373] {'pid': 9393, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-13 08:12:31,306 [salt.state       :1951][INFO    ][9373] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:12:31.305974 duration_in_ms=1971.474
2019-03-13 08:12:31,309 [salt.state       :1780][INFO    ][9373] Running state [maas.wait_for_machine_status] at time 08:12:31.309679
2019-03-13 08:12:31,310 [salt.state       :1813][INFO    ][9373] Executing state module.run for [maas.wait_for_machine_status]
2019-03-13 08:12:31,312 [salt.utils.decorators:613 ][WARNING ][9373] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-13 08:12:34,139 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.18505788s left)
2019-03-13 08:12:36,224 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081236208737
2019-03-13 08:12:36,248 [salt.minion      :1432][INFO    ][9444] Starting a new job with PID 9444
2019-03-13 08:12:36,274 [salt.minion      :1711][INFO    ][9444] Returning information for job: 20190313081236208737
2019-03-13 08:13:06,299 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081306288805
2019-03-13 08:13:06,320 [salt.minion      :1432][INFO    ][9514] Starting a new job with PID 9514
2019-03-13 08:13:06,348 [salt.minion      :1711][INFO    ][9514] Returning information for job: 20190313081306288805
2019-03-13 08:13:07,090 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.233248s left)
2019-03-13 08:13:36,354 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081336343697
2019-03-13 08:13:36,371 [salt.minion      :1432][INFO    ][9552] Starting a new job with PID 9552
2019-03-13 08:13:36,402 [salt.minion      :1711][INFO    ][9552] Returning information for job: 20190313081336343697
2019-03-13 08:13:39,845 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.47811794s left)
2019-03-13 08:14:06,419 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081406404867
2019-03-13 08:14:06,439 [salt.minion      :1432][INFO    ][9608] Starting a new job with PID 9608
2019-03-13 08:14:06,472 [salt.minion      :1711][INFO    ][9608] Returning information for job: 20190313081406404867
2019-03-13 08:14:12,280 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2149.04385996s left)
2019-03-13 08:14:36,454 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081436444085
2019-03-13 08:14:36,468 [salt.minion      :1432][INFO    ][9649] Starting a new job with PID 9649
2019-03-13 08:14:36,498 [salt.minion      :1711][INFO    ][9649] Returning information for job: 20190313081436444085
2019-03-13 08:14:45,460 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.86350203s left)
2019-03-13 08:15:06,500 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081506486779
2019-03-13 08:15:06,518 [salt.minion      :1432][INFO    ][9729] Starting a new job with PID 9729
2019-03-13 08:15:06,549 [salt.minion      :1711][INFO    ][9729] Returning information for job: 20190313081506486779
2019-03-13 08:15:18,198 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.12564492s left)
2019-03-13 08:15:36,585 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081536563962
2019-03-13 08:15:36,609 [salt.minion      :1432][INFO    ][9818] Starting a new job with PID 9818
2019-03-13 08:15:36,646 [salt.minion      :1711][INFO    ][9818] Returning information for job: 20190313081536563962
2019-03-13 08:15:51,333 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.99055481s left)
2019-03-13 08:16:06,661 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081606653860
2019-03-13 08:16:06,674 [salt.minion      :1432][INFO    ][10078] Starting a new job with PID 10078
2019-03-13 08:16:06,703 [salt.minion      :1711][INFO    ][10078] Returning information for job: 20190313081606653860
2019-03-13 08:16:23,748 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2017.57586193s left)
2019-03-13 08:16:36,871 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081636859035
2019-03-13 08:16:36,887 [salt.minion      :1432][INFO    ][10161] Starting a new job with PID 10161
2019-03-13 08:16:36,921 [salt.minion      :1711][INFO    ][10161] Returning information for job: 20190313081636859035
2019-03-13 08:16:56,317 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1985.00653696s left)
2019-03-13 08:17:07,070 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081707037583
2019-03-13 08:17:07,087 [salt.minion      :1432][INFO    ][10436] Starting a new job with PID 10436
2019-03-13 08:17:07,117 [salt.minion      :1711][INFO    ][10436] Returning information for job: 20190313081707037583
2019-03-13 08:17:29,098 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1952.22554493s left)
2019-03-13 08:17:37,291 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081737234227
2019-03-13 08:17:37,306 [salt.minion      :1432][INFO    ][10530] Starting a new job with PID 10530
2019-03-13 08:17:37,337 [salt.minion      :1711][INFO    ][10530] Returning information for job: 20190313081737234227
2019-03-13 08:18:01,805 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1919.51874781s left)
2019-03-13 08:18:07,470 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081807449843
2019-03-13 08:18:07,484 [salt.minion      :1432][INFO    ][10939] Starting a new job with PID 10939
2019-03-13 08:18:07,510 [salt.minion      :1711][INFO    ][10939] Returning information for job: 20190313081807449843
2019-03-13 08:18:34,605 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1886.71820998s left)
2019-03-13 08:18:37,536 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081837518454
2019-03-13 08:18:37,554 [salt.minion      :1432][INFO    ][10996] Starting a new job with PID 10996
2019-03-13 08:18:37,580 [salt.minion      :1711][INFO    ][10996] Returning information for job: 20190313081837518454
2019-03-13 08:19:07,069 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1854.25491095s left)
2019-03-13 08:19:07,740 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081907706734
2019-03-13 08:19:07,759 [salt.minion      :1432][INFO    ][11377] Starting a new job with PID 11377
2019-03-13 08:19:07,781 [salt.minion      :1711][INFO    ][11377] Returning information for job: 20190313081907706734
2019-03-13 08:19:37,951 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313081937909061
2019-03-13 08:19:37,966 [salt.minion      :1432][INFO    ][11423] Starting a new job with PID 11423
2019-03-13 08:19:37,991 [salt.minion      :1711][INFO    ][11423] Returning information for job: 20190313081937909061
2019-03-13 08:19:39,533 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1821.79109383s left)
2019-03-13 08:20:08,141 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313082008088194
2019-03-13 08:20:08,156 [salt.minion      :1432][INFO    ][11555] Starting a new job with PID 11555
2019-03-13 08:20:08,174 [salt.minion      :1711][INFO    ][11555] Returning information for job: 20190313082008088194
2019-03-13 08:20:12,302 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1789.02133203s left)
2019-03-13 08:20:38,296 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313082038253063
2019-03-13 08:20:38,311 [salt.minion      :1432][INFO    ][11630] Starting a new job with PID 11630
2019-03-13 08:20:38,330 [salt.minion      :1711][INFO    ][11630] Returning information for job: 20190313082038253063
2019-03-13 08:20:44,836 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1756.48748302s left)
2019-03-13 08:21:08,424 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313082108388934
2019-03-13 08:21:08,437 [salt.minion      :1432][INFO    ][11856] Starting a new job with PID 11856
2019-03-13 08:21:08,459 [salt.minion      :1711][INFO    ][11856] Returning information for job: 20190313082108388934
2019-03-13 08:21:17,362 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1723.96160889s left)
2019-03-13 08:21:38,640 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313082138631555
2019-03-13 08:21:38,657 [salt.minion      :1432][INFO    ][11938] Starting a new job with PID 11938
2019-03-13 08:21:38,676 [salt.minion      :1711][INFO    ][11938] Returning information for job: 20190313082138631555
2019-03-13 08:21:49,725 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1691.59826899s left)
2019-03-13 08:22:08,680 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313082208670187
2019-03-13 08:22:08,696 [salt.minion      :1432][INFO    ][12169] Starting a new job with PID 12169
2019-03-13 08:22:08,717 [salt.minion      :1711][INFO    ][12169] Returning information for job: 20190313082208670187
2019-03-13 08:22:22,245 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1659.07871199s left)
2019-03-13 08:22:38,708 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313082238701117
2019-03-13 08:22:38,722 [salt.minion      :1432][INFO    ][12344] Starting a new job with PID 12344
2019-03-13 08:22:38,744 [salt.minion      :1711][INFO    ][12344] Returning information for job: 20190313082238701117
2019-03-13 08:22:54,763 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1626.56055689s left)
2019-03-13 08:23:08,769 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313082308760508
2019-03-13 08:23:08,782 [salt.minion      :1432][INFO    ][12511] Starting a new job with PID 12511
2019-03-13 08:23:08,804 [salt.minion      :1711][INFO    ][12511] Returning information for job: 20190313082308760508
2019-03-13 08:23:27,153 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1594.17031288s left)
2019-03-13 08:23:38,795 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313082338786433
2019-03-13 08:23:38,808 [salt.minion      :1432][INFO    ][12553] Starting a new job with PID 12553
2019-03-13 08:23:38,834 [salt.minion      :1711][INFO    ][12553] Returning information for job: 20190313082338786433
2019-03-13 08:23:59,668 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1561.65572691s left)
2019-03-13 08:24:08,836 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313082408827229
2019-03-13 08:24:08,851 [salt.minion      :1432][INFO    ][12613] Starting a new job with PID 12613
2019-03-13 08:24:08,870 [salt.minion      :1711][INFO    ][12613] Returning information for job: 20190313082408827229
2019-03-13 08:24:32,044 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1529.27910995s left)
2019-03-13 08:24:38,877 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313082438869079
2019-03-13 08:24:38,894 [salt.minion      :1432][INFO    ][12700] Starting a new job with PID 12700
2019-03-13 08:24:38,917 [salt.minion      :1711][INFO    ][12700] Returning information for job: 20190313082438869079
2019-03-13 08:25:04,684 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1496.63947082s left)
2019-03-13 08:25:09,069 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313082509060047
2019-03-13 08:25:09,083 [salt.minion      :1432][INFO    ][12875] Starting a new job with PID 12875
2019-03-13 08:25:09,105 [salt.minion      :1711][INFO    ][12875] Returning information for job: 20190313082509060047
2019-03-13 08:25:37,209 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1464.11495399s left)
2019-03-13 08:25:39,138 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313082539131535
2019-03-13 08:25:39,152 [salt.minion      :1432][INFO    ][12928] Starting a new job with PID 12928
2019-03-13 08:25:39,174 [salt.minion      :1711][INFO    ][12928] Returning information for job: 20190313082539131535
2019-03-13 08:26:09,269 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313082609259284
2019-03-13 08:26:09,286 [salt.minion      :1432][INFO    ][13166] Starting a new job with PID 13166
2019-03-13 08:26:09,319 [salt.minion      :1711][INFO    ][13166] Returning information for job: 20190313082609259284
2019-03-13 08:26:09,661 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1431.66241693s left)
2019-03-13 08:26:39,341 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313082639332996
2019-03-13 08:26:39,357 [salt.minion      :1432][INFO    ][13203] Starting a new job with PID 13203
2019-03-13 08:26:39,378 [salt.minion      :1711][INFO    ][13203] Returning information for job: 20190313082639332996
2019-03-13 08:26:42,284 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1399.03957295s left)
2019-03-13 08:27:09,434 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313082709424827
2019-03-13 08:27:09,453 [salt.minion      :1432][INFO    ][13269] Starting a new job with PID 13269
2019-03-13 08:27:09,475 [salt.minion      :1711][INFO    ][13269] Returning information for job: 20190313082709424827
2019-03-13 08:27:14,922 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1366.40156603s left)
2019-03-13 08:27:39,528 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313082739521382
2019-03-13 08:27:39,546 [salt.minion      :1432][INFO    ][13311] Starting a new job with PID 13311
2019-03-13 08:27:39,565 [salt.minion      :1711][INFO    ][13311] Returning information for job: 20190313082739521382
2019-03-13 08:27:47,297 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1334.02649093s left)
2019-03-13 08:28:09,650 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313082809641748
2019-03-13 08:28:09,667 [salt.minion      :1432][INFO    ][13373] Starting a new job with PID 13373
2019-03-13 08:28:09,685 [salt.minion      :1711][INFO    ][13373] Returning information for job: 20190313082809641748
2019-03-13 08:28:19,630 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1301.69328189s left)
2019-03-13 08:28:39,773 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313082839763597
2019-03-13 08:28:39,788 [salt.minion      :1432][INFO    ][13417] Starting a new job with PID 13417
2019-03-13 08:28:39,809 [salt.minion      :1711][INFO    ][13417] Returning information for job: 20190313082839763597
2019-03-13 08:28:52,146 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1269.17745996s left)
2019-03-13 08:29:09,932 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313082909920907
2019-03-13 08:29:09,946 [salt.minion      :1432][INFO    ][13485] Starting a new job with PID 13485
2019-03-13 08:29:09,967 [salt.minion      :1711][INFO    ][13485] Returning information for job: 20190313082909920907
2019-03-13 08:29:24,846 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1236.47808695s left)
2019-03-13 08:29:40,058 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313082940049811
2019-03-13 08:29:40,077 [salt.minion      :1432][INFO    ][13527] Starting a new job with PID 13527
2019-03-13 08:29:40,100 [salt.minion      :1711][INFO    ][13527] Returning information for job: 20190313082940049811
2019-03-13 08:29:57,292 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1204.03198981s left)
2019-03-13 08:30:10,200 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313083010189985
2019-03-13 08:30:10,220 [salt.minion      :1432][INFO    ][13590] Starting a new job with PID 13590
2019-03-13 08:30:10,238 [salt.minion      :1711][INFO    ][13590] Returning information for job: 20190313083010189985
2019-03-13 08:30:29,589 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1171.73434591s left)
2019-03-13 08:30:40,356 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313083040343182
2019-03-13 08:30:40,370 [salt.minion      :1432][INFO    ][13632] Starting a new job with PID 13632
2019-03-13 08:30:40,394 [salt.minion      :1711][INFO    ][13632] Returning information for job: 20190313083040343182
2019-03-13 08:31:02,066 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1139.25795889s left)
2019-03-13 08:31:10,649 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313083110604114
2019-03-13 08:31:10,664 [salt.minion      :1432][INFO    ][13694] Starting a new job with PID 13694
2019-03-13 08:31:10,684 [salt.minion      :1711][INFO    ][13694] Returning information for job: 20190313083110604114
2019-03-13 08:31:34,581 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1106.74224281s left)
2019-03-13 08:31:40,843 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313083140804870
2019-03-13 08:31:40,858 [salt.minion      :1432][INFO    ][13736] Starting a new job with PID 13736
2019-03-13 08:31:40,877 [salt.minion      :1711][INFO    ][13736] Returning information for job: 20190313083140804870
2019-03-13 08:32:06,901 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1074.42280602s left)
2019-03-13 08:32:11,040 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313083211015562
2019-03-13 08:32:11,054 [salt.minion      :1432][INFO    ][13796] Starting a new job with PID 13796
2019-03-13 08:32:11,074 [salt.minion      :1711][INFO    ][13796] Returning information for job: 20190313083211015562
2019-03-13 08:32:39,358 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1041.96559691s left)
2019-03-13 08:32:41,228 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313083241214268
2019-03-13 08:32:41,246 [salt.minion      :1432][INFO    ][13852] Starting a new job with PID 13852
2019-03-13 08:32:41,281 [salt.minion      :1711][INFO    ][13852] Returning information for job: 20190313083241214268
2019-03-13 08:33:11,436 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313083311420274
2019-03-13 08:33:11,450 [salt.minion      :1432][INFO    ][13921] Starting a new job with PID 13921
2019-03-13 08:33:11,473 [salt.minion      :1711][INFO    ][13921] Returning information for job: 20190313083311420274
2019-03-13 08:33:11,816 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1009.50799584s left)
2019-03-13 08:33:41,515 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313083341469405
2019-03-13 08:33:41,532 [salt.minion      :1432][INFO    ][13978] Starting a new job with PID 13978
2019-03-13 08:33:41,555 [salt.minion      :1711][INFO    ][13978] Returning information for job: 20190313083341469405
2019-03-13 08:33:44,228 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (977.096079826s left)
2019-03-13 08:34:11,724 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313083411710461
2019-03-13 08:34:11,737 [salt.minion      :1432][INFO    ][14027] Starting a new job with PID 14027
2019-03-13 08:34:11,760 [salt.minion      :1711][INFO    ][14027] Returning information for job: 20190313083411710461
2019-03-13 08:34:16,638 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (944.685554028s left)
2019-03-13 08:34:41,969 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313083441925377
2019-03-13 08:34:41,983 [salt.minion      :1432][INFO    ][14090] Starting a new job with PID 14090
2019-03-13 08:34:42,008 [salt.minion      :1711][INFO    ][14090] Returning information for job: 20190313083441925377
2019-03-13 08:34:49,072 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (912.251354933s left)
2019-03-13 08:35:12,234 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313083512188867
2019-03-13 08:35:12,247 [salt.minion      :1432][INFO    ][14128] Starting a new job with PID 14128
2019-03-13 08:35:12,266 [salt.minion      :1711][INFO    ][14128] Returning information for job: 20190313083512188867
2019-03-13 08:35:21,421 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (879.902354002s left)
2019-03-13 08:35:42,525 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313083542466507
2019-03-13 08:35:42,540 [salt.minion      :1432][INFO    ][14198] Starting a new job with PID 14198
2019-03-13 08:35:42,564 [salt.minion      :1711][INFO    ][14198] Returning information for job: 20190313083542466507
2019-03-13 08:35:53,872 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (847.451493025s left)
2019-03-13 08:36:12,580 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313083612541039
2019-03-13 08:36:12,595 [salt.minion      :1432][INFO    ][14230] Starting a new job with PID 14230
2019-03-13 08:36:12,616 [salt.minion      :1711][INFO    ][14230] Returning information for job: 20190313083612541039
2019-03-13 08:36:26,140 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (815.18394804s left)
2019-03-13 08:36:42,667 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313083642655750
2019-03-13 08:36:42,682 [salt.minion      :1432][INFO    ][14301] Starting a new job with PID 14301
2019-03-13 08:36:42,704 [salt.minion      :1711][INFO    ][14301] Returning information for job: 20190313083642655750
2019-03-13 08:36:58,546 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (782.777678967s left)
2019-03-13 08:37:12,689 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313083712680442
2019-03-13 08:37:12,703 [salt.minion      :1432][INFO    ][14333] Starting a new job with PID 14333
2019-03-13 08:37:12,720 [salt.minion      :1711][INFO    ][14333] Returning information for job: 20190313083712680442
2019-03-13 08:37:31,006 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (750.317215919s left)
2019-03-13 08:37:42,713 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313083742705141
2019-03-13 08:37:42,731 [salt.minion      :1432][INFO    ][14405] Starting a new job with PID 14405
2019-03-13 08:37:42,751 [salt.minion      :1711][INFO    ][14405] Returning information for job: 20190313083742705141
2019-03-13 08:38:03,393 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (717.930171013s left)
2019-03-13 08:38:12,752 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313083812742539
2019-03-13 08:38:12,767 [salt.minion      :1432][INFO    ][14439] Starting a new job with PID 14439
2019-03-13 08:38:12,791 [salt.minion      :1711][INFO    ][14439] Returning information for job: 20190313083812742539
2019-03-13 08:38:35,882 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (685.441783905s left)
2019-03-13 08:38:42,805 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313083842796929
2019-03-13 08:38:42,818 [salt.minion      :1432][INFO    ][14513] Starting a new job with PID 14513
2019-03-13 08:38:42,842 [salt.minion      :1711][INFO    ][14513] Returning information for job: 20190313083842796929
2019-03-13 08:39:08,284 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (653.039696932s left)
2019-03-13 08:39:12,893 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313083912884438
2019-03-13 08:39:12,909 [salt.minion      :1432][INFO    ][14545] Starting a new job with PID 14545
2019-03-13 08:39:12,933 [salt.minion      :1711][INFO    ][14545] Returning information for job: 20190313083912884438
2019-03-13 08:39:40,654 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (620.669747829s left)
2019-03-13 08:39:43,072 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313083943063816
2019-03-13 08:39:43,086 [salt.minion      :1432][INFO    ][14617] Starting a new job with PID 14617
2019-03-13 08:39:43,109 [salt.minion      :1711][INFO    ][14617] Returning information for job: 20190313083943063816
2019-03-13 08:40:13,072 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (588.251778841s left)
2019-03-13 08:40:13,241 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313084013229708
2019-03-13 08:40:13,255 [salt.minion      :1432][INFO    ][14649] Starting a new job with PID 14649
2019-03-13 08:40:13,277 [salt.minion      :1711][INFO    ][14649] Returning information for job: 20190313084013229708
2019-03-13 08:40:43,344 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313084043331741
2019-03-13 08:40:43,358 [salt.minion      :1432][INFO    ][14715] Starting a new job with PID 14715
2019-03-13 08:40:43,385 [salt.minion      :1711][INFO    ][14715] Returning information for job: 20190313084043331741
2019-03-13 08:40:45,510 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (555.813436985s left)
2019-03-13 08:41:13,434 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313084113423704
2019-03-13 08:41:13,446 [salt.minion      :1432][INFO    ][14746] Starting a new job with PID 14746
2019-03-13 08:41:13,469 [salt.minion      :1711][INFO    ][14746] Returning information for job: 20190313084113423704
2019-03-13 08:41:17,986 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (523.337472916s left)
2019-03-13 08:41:43,541 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313084143531462
2019-03-13 08:41:43,555 [salt.minion      :1432][INFO    ][14819] Starting a new job with PID 14819
2019-03-13 08:41:43,579 [salt.minion      :1711][INFO    ][14819] Returning information for job: 20190313084143531462
2019-03-13 08:41:50,417 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (490.906854868s left)
2019-03-13 08:42:13,669 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313084213661672
2019-03-13 08:42:13,681 [salt.minion      :1432][INFO    ][14851] Starting a new job with PID 14851
2019-03-13 08:42:13,704 [salt.minion      :1711][INFO    ][14851] Returning information for job: 20190313084213661672
2019-03-13 08:42:22,712 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (458.611558914s left)
2019-03-13 08:42:43,845 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313084243836187
2019-03-13 08:42:43,859 [salt.minion      :1432][INFO    ][15060] Starting a new job with PID 15060
2019-03-13 08:42:43,883 [salt.minion      :1711][INFO    ][15060] Returning information for job: 20190313084243836187
2019-03-13 08:42:55,118 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (426.20604682s left)
2019-03-13 08:43:13,988 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313084313980983
2019-03-13 08:43:14,003 [salt.minion      :1432][INFO    ][15108] Starting a new job with PID 15108
2019-03-13 08:43:14,026 [salt.minion      :1711][INFO    ][15108] Returning information for job: 20190313084313980983
2019-03-13 08:43:27,529 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (393.794936895s left)
2019-03-13 08:43:44,002 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313084343993911
2019-03-13 08:43:44,016 [salt.minion      :1432][INFO    ][15178] Starting a new job with PID 15178
2019-03-13 08:43:44,039 [salt.minion      :1711][INFO    ][15178] Returning information for job: 20190313084343993911
2019-03-13 08:43:59,953 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (361.370818853s left)
2019-03-13 08:44:14,153 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313084414146028
2019-03-13 08:44:14,167 [salt.minion      :1432][INFO    ][15210] Starting a new job with PID 15210
2019-03-13 08:44:14,192 [salt.minion      :1711][INFO    ][15210] Returning information for job: 20190313084414146028
2019-03-13 08:44:32,363 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (328.960438013s left)
2019-03-13 08:44:44,373 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313084444365331
2019-03-13 08:44:44,387 [salt.minion      :1432][INFO    ][15281] Starting a new job with PID 15281
2019-03-13 08:44:44,410 [salt.minion      :1711][INFO    ][15281] Returning information for job: 20190313084444365331
2019-03-13 08:45:04,825 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (296.4989779s left)
2019-03-13 08:45:14,578 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313084514569761
2019-03-13 08:45:14,591 [salt.minion      :1432][INFO    ][15313] Starting a new job with PID 15313
2019-03-13 08:45:14,615 [salt.minion      :1711][INFO    ][15313] Returning information for job: 20190313084514569761
2019-03-13 08:45:37,246 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (264.077348948s left)
2019-03-13 08:45:44,793 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313084544784694
2019-03-13 08:45:44,806 [salt.minion      :1432][INFO    ][15386] Starting a new job with PID 15386
2019-03-13 08:45:44,827 [salt.minion      :1711][INFO    ][15386] Returning information for job: 20190313084544784694
2019-03-13 08:46:09,717 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (231.606509924s left)
2019-03-13 08:46:14,815 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313084614805355
2019-03-13 08:46:14,830 [salt.minion      :1432][INFO    ][15420] Starting a new job with PID 15420
2019-03-13 08:46:14,852 [salt.minion      :1711][INFO    ][15420] Returning information for job: 20190313084614805355
2019-03-13 08:46:42,235 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (199.088321924s left)
2019-03-13 08:46:44,888 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313084644870385
2019-03-13 08:46:44,903 [salt.minion      :1432][INFO    ][15491] Starting a new job with PID 15491
2019-03-13 08:46:44,928 [salt.minion      :1711][INFO    ][15491] Returning information for job: 20190313084644870385
2019-03-13 08:47:14,684 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (166.639621973s left)
2019-03-13 08:47:14,952 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313084714920654
2019-03-13 08:47:14,966 [salt.minion      :1432][INFO    ][15527] Starting a new job with PID 15527
2019-03-13 08:47:14,994 [salt.minion      :1711][INFO    ][15527] Returning information for job: 20190313084714920654
2019-03-13 08:47:45,032 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313084745017759
2019-03-13 08:47:45,045 [salt.minion      :1432][INFO    ][15594] Starting a new job with PID 15594
2019-03-13 08:47:45,068 [salt.minion      :1711][INFO    ][15594] Returning information for job: 20190313084745017759
2019-03-13 08:47:46,961 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (134.362218857s left)
2019-03-13 08:48:15,193 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313084815172757
2019-03-13 08:48:15,206 [salt.minion      :1432][INFO    ][15625] Starting a new job with PID 15625
2019-03-13 08:48:15,230 [salt.minion      :1711][INFO    ][15625] Returning information for job: 20190313084815172757
2019-03-13 08:48:19,509 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (101.814409971s left)
2019-03-13 08:48:45,223 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313084845200700
2019-03-13 08:48:45,236 [salt.minion      :1432][INFO    ][15698] Starting a new job with PID 15698
2019-03-13 08:48:45,259 [salt.minion      :1711][INFO    ][15698] Returning information for job: 20190313084845200700
2019-03-13 08:48:52,085 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (69.2382588387s left)
2019-03-13 08:49:15,322 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313084915312178
2019-03-13 08:49:15,336 [salt.minion      :1432][INFO    ][15730] Starting a new job with PID 15730
2019-03-13 08:49:15,356 [salt.minion      :1711][INFO    ][15730] Returning information for job: 20190313084915312178
2019-03-13 08:49:24,333 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (36.9901349545s left)
2019-03-13 08:49:45,465 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313084945451887
2019-03-13 08:49:45,477 [salt.minion      :1432][INFO    ][15799] Starting a new job with PID 15799
2019-03-13 08:49:45,497 [salt.minion      :1711][INFO    ][15799] Returning information for job: 20190313084945451887
2019-03-13 08:49:56,613 [salt.loaded.ext.module.maas:1023][INFO    ][9373] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (4.71087598801s left)
2019-03-13 08:50:15,601 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313085015576479
2019-03-13 08:50:15,613 [salt.minion      :1432][INFO    ][15834] Starting a new job with PID 15834
2019-03-13 08:50:15,635 [salt.minion      :1711][INFO    ][15834] Returning information for job: 20190313085015576479
2019-03-13 08:50:29,071 [salt.state       :302 ][ERROR   ][9373] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-03-13 08:50:29,073 [salt.state       :1951][INFO    ][9373] Completed state [maas.wait_for_machine_status] at time 08:50:29.072855 duration_in_ms=2277763.01
2019-03-13 08:50:29,170 [salt.minion      :1711][INFO    ][9373] Returning information for job: 20190313081221143733
2019-03-13 08:50:40,758 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command pillar.get with jid 20190313085040748935
2019-03-13 08:50:40,774 [salt.minion      :1432][INFO    ][15876] Starting a new job with PID 15876
2019-03-13 08:50:40,786 [salt.minion      :1711][INFO    ][15876] Returning information for job: 20190313085040748935
2019-03-13 08:50:41,383 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command service.status with jid 20190313085041362795
2019-03-13 08:50:41,398 [salt.minion      :1432][INFO    ][15894] Starting a new job with PID 15894
2019-03-13 08:50:42,392 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][15894] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-03-13 08:50:42,419 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][15894] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-03-13 08:50:42,445 [salt.minion      :1711][INFO    ][15894] Returning information for job: 20190313085041362795
2019-03-13 08:50:42,992 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command state.apply with jid 20190313085042984368
2019-03-13 08:50:43,006 [salt.minion      :1432][INFO    ][15921] Starting a new job with PID 15921
2019-03-13 08:50:51,932 [salt.state       :915 ][INFO    ][15921] Loading fresh modules for state activity
2019-03-13 08:50:52,667 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15921] Executing command 'salt-minion --version' in directory '/root'
2019-03-13 08:50:52,951 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15921] Executing command 'salt-minion --version' in directory '/root'
2019-03-13 08:50:53,882 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15921] Executing command 'salt-minion --version' in directory '/root'
2019-03-13 08:50:54,141 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15921] Executing command 'salt-minion --version' in directory '/root'
2019-03-13 08:50:56,095 [salt.state       :1780][INFO    ][15921] Running state [salt-minion] at time 08:50:56.095040
2019-03-13 08:50:56,095 [salt.state       :1813][INFO    ][15921] Executing state pkg.installed for [salt-minion]
2019-03-13 08:50:56,096 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15921] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-13 08:50:56,213 [salt.state       :300 ][INFO    ][15921] All specified packages are already installed
2019-03-13 08:50:56,214 [salt.state       :1951][INFO    ][15921] Completed state [salt-minion] at time 08:50:56.214075 duration_in_ms=119.035
2019-03-13 08:50:56,214 [salt.state       :1780][INFO    ][15921] Running state [salt_minion_dependency_packages] at time 08:50:56.214407
2019-03-13 08:50:56,214 [salt.state       :1813][INFO    ][15921] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-03-13 08:50:56,224 [salt.state       :300 ][INFO    ][15921] All specified packages are already installed
2019-03-13 08:50:56,224 [salt.state       :1951][INFO    ][15921] Completed state [salt_minion_dependency_packages] at time 08:50:56.224739 duration_in_ms=10.332
2019-03-13 08:50:56,228 [salt.state       :1780][INFO    ][15921] Running state [/etc/salt/minion.d/minion.conf] at time 08:50:56.228641
2019-03-13 08:50:56,228 [salt.state       :1813][INFO    ][15921] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-03-13 08:50:56,510 [salt.state       :300 ][INFO    ][15921] File /etc/salt/minion.d/minion.conf is in the correct state
2019-03-13 08:50:56,511 [salt.state       :1951][INFO    ][15921] Completed state [/etc/salt/minion.d/minion.conf] at time 08:50:56.511609 duration_in_ms=282.968
2019-03-13 08:50:56,512 [salt.state       :1780][INFO    ][15921] Running state [python-netaddr] at time 08:50:56.512111
2019-03-13 08:50:56,512 [salt.state       :1813][INFO    ][15921] Executing state pkg.installed for [python-netaddr]
2019-03-13 08:50:56,528 [salt.state       :300 ][INFO    ][15921] All specified packages are already installed
2019-03-13 08:50:56,528 [salt.state       :1951][INFO    ][15921] Completed state [python-netaddr] at time 08:50:56.528540 duration_in_ms=16.43
2019-03-13 08:50:56,532 [salt.state       :1780][INFO    ][15921] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 08:50:56.532115
2019-03-13 08:50:56,532 [salt.state       :1813][INFO    ][15921] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-03-13 08:50:56,551 [salt.state       :300 ][INFO    ][15921] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-03-13 08:50:56,552 [salt.state       :1951][INFO    ][15921] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 08:50:56.552571 duration_in_ms=20.455
2019-03-13 08:50:56,554 [salt.state       :1780][INFO    ][15921] Running state [salt-minion] at time 08:50:56.554382
2019-03-13 08:50:56,554 [salt.state       :1813][INFO    ][15921] Executing state service.running for [salt-minion]
2019-03-13 08:50:56,555 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15921] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-13 08:50:56,597 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15921] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-03-13 08:50:56,614 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15921] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-03-13 08:50:56,634 [salt.state       :300 ][INFO    ][15921] The service salt-minion is already running
2019-03-13 08:50:56,634 [salt.state       :1951][INFO    ][15921] Completed state [salt-minion] at time 08:50:56.634469 duration_in_ms=80.088
2019-03-13 08:50:56,637 [salt.state       :1780][INFO    ][15921] Running state [/etc/salt/grains.d] at time 08:50:56.637442
2019-03-13 08:50:56,638 [salt.state       :1813][INFO    ][15921] Executing state file.directory for [/etc/salt/grains.d]
2019-03-13 08:50:56,639 [salt.state       :300 ][INFO    ][15921] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-03-13 08:50:56,640 [salt.state       :1951][INFO    ][15921] Completed state [/etc/salt/grains.d] at time 08:50:56.639968 duration_in_ms=2.525
2019-03-13 08:50:56,641 [salt.state       :1780][INFO    ][15921] Running state [/etc/salt/grains] at time 08:50:56.641019
2019-03-13 08:50:56,641 [salt.state       :1813][INFO    ][15921] Executing state file.managed for [/etc/salt/grains]
2019-03-13 08:50:56,642 [salt.state       :300 ][INFO    ][15921] File /etc/salt/grains exists with proper permissions. No changes made.
2019-03-13 08:50:56,642 [salt.state       :1951][INFO    ][15921] Completed state [/etc/salt/grains] at time 08:50:56.642717 duration_in_ms=1.697
2019-03-13 08:50:56,644 [salt.state       :1780][INFO    ][15921] Running state [/etc/salt/grains.d/placeholder] at time 08:50:56.644919
2019-03-13 08:50:56,645 [salt.state       :1813][INFO    ][15921] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-03-13 08:50:56,645 [salt.state       :300 ][INFO    ][15921] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-03-13 08:50:56,645 [salt.state       :1951][INFO    ][15921] Completed state [/etc/salt/grains.d/placeholder] at time 08:50:56.645888 duration_in_ms=0.969
2019-03-13 08:50:56,646 [salt.state       :1780][INFO    ][15921] Running state [/etc/salt/grains.d/sphinx] at time 08:50:56.646366
2019-03-13 08:50:56,646 [salt.state       :1813][INFO    ][15921] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-03-13 08:50:56,647 [salt.state       :300 ][INFO    ][15921] File /etc/salt/grains.d/sphinx is in the correct state
2019-03-13 08:50:56,648 [salt.state       :1951][INFO    ][15921] Completed state [/etc/salt/grains.d/sphinx] at time 08:50:56.648117 duration_in_ms=1.751
2019-03-13 08:50:56,650 [salt.state       :1780][INFO    ][15921] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 08:50:56.650150
2019-03-13 08:50:56,650 [salt.state       :1813][INFO    ][15921] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-03-13 08:50:56,650 [salt.state       :300 ][INFO    ][15921] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-03-13 08:50:56,651 [salt.state       :1951][INFO    ][15921] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 08:50:56.650950 duration_in_ms=0.8
2019-03-13 08:50:56,651 [salt.state       :1780][INFO    ][15921] Running state [/etc/salt/grains.d/dns_records] at time 08:50:56.651442
2019-03-13 08:50:56,651 [salt.state       :1813][INFO    ][15921] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-03-13 08:50:56,652 [salt.state       :300 ][INFO    ][15921] File /etc/salt/grains.d/dns_records is in the correct state
2019-03-13 08:50:56,653 [salt.state       :1951][INFO    ][15921] Completed state [/etc/salt/grains.d/dns_records] at time 08:50:56.652989 duration_in_ms=1.547
2019-03-13 08:50:56,653 [salt.state       :1780][INFO    ][15921] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 08:50:56.653940
2019-03-13 08:50:56,654 [salt.state       :1813][INFO    ][15921] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-03-13 08:50:56,654 [salt.state       :300 ][INFO    ][15921] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-03-13 08:50:56,654 [salt.state       :1951][INFO    ][15921] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 08:50:56.654687 duration_in_ms=0.747
2019-03-13 08:50:56,656 [salt.state       :1780][INFO    ][15921] Running state [/etc/salt/grains.d/salt] at time 08:50:56.656850
2019-03-13 08:50:56,657 [salt.state       :1813][INFO    ][15921] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-03-13 08:50:56,658 [salt.state       :300 ][INFO    ][15921] File /etc/salt/grains.d/salt is in the correct state
2019-03-13 08:50:56,658 [salt.state       :1951][INFO    ][15921] Completed state [/etc/salt/grains.d/salt] at time 08:50:56.658333 duration_in_ms=1.483
2019-03-13 08:50:56,659 [salt.state       :1780][INFO    ][15921] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 08:50:56.659209
2019-03-13 08:50:56,659 [salt.state       :1813][INFO    ][15921] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-03-13 08:50:56,659 [salt.state       :300 ][INFO    ][15921] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-03-13 08:50:56,659 [salt.state       :1951][INFO    ][15921] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 08:50:56.659935 duration_in_ms=0.726
2019-03-13 08:50:56,661 [salt.state       :1780][INFO    ][15921] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 08:50:56.661836
2019-03-13 08:50:56,662 [salt.state       :1813][INFO    ][15921] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-03-13 08:50:56,662 [salt.state       :300 ][INFO    ][15921] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-03-13 08:50:56,662 [salt.state       :1951][INFO    ][15921] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 08:50:56.662597 duration_in_ms=0.761
2019-03-13 08:50:56,663 [salt.state       :1780][INFO    ][15921] Running state [mine.update] at time 08:50:56.663257
2019-03-13 08:50:56,663 [salt.state       :1813][INFO    ][15921] Executing state module.wait for [mine.update]
2019-03-13 08:50:56,663 [salt.state       :300 ][INFO    ][15921] No changes made for mine.update
2019-03-13 08:50:56,663 [salt.state       :1951][INFO    ][15921] Completed state [mine.update] at time 08:50:56.663946 duration_in_ms=0.689
2019-03-13 08:50:56,664 [salt.state       :1780][INFO    ][15921] Running state [ca-certificates] at time 08:50:56.664180
2019-03-13 08:50:56,664 [salt.state       :1813][INFO    ][15921] Executing state pkg.installed for [ca-certificates]
2019-03-13 08:50:56,674 [salt.state       :300 ][INFO    ][15921] All specified packages are already installed
2019-03-13 08:50:56,674 [salt.state       :1951][INFO    ][15921] Completed state [ca-certificates] at time 08:50:56.674449 duration_in_ms=10.269
2019-03-13 08:50:56,675 [salt.state       :1780][INFO    ][15921] Running state [update-ca-certificates] at time 08:50:56.675142
2019-03-13 08:50:56,675 [salt.state       :1813][INFO    ][15921] Executing state cmd.wait for [update-ca-certificates]
2019-03-13 08:50:56,675 [salt.state       :300 ][INFO    ][15921] No changes made for update-ca-certificates
2019-03-13 08:50:56,675 [salt.state       :1951][INFO    ][15921] Completed state [update-ca-certificates] at time 08:50:56.675841 duration_in_ms=0.7
2019-03-13 08:50:56,676 [salt.state       :1780][INFO    ][15921] Running state [iptables] at time 08:50:56.676077
2019-03-13 08:50:56,676 [salt.state       :1813][INFO    ][15921] Executing state pkg.installed for [iptables]
2019-03-13 08:50:56,685 [salt.state       :300 ][INFO    ][15921] All specified packages are already installed
2019-03-13 08:50:56,686 [salt.state       :1951][INFO    ][15921] Completed state [iptables] at time 08:50:56.686111 duration_in_ms=10.034
2019-03-13 08:50:56,686 [salt.state       :1780][INFO    ][15921] Running state [iptables-persistent] at time 08:50:56.686344
2019-03-13 08:50:56,686 [salt.state       :1813][INFO    ][15921] Executing state pkg.installed for [iptables-persistent]
2019-03-13 08:50:56,696 [salt.state       :300 ][INFO    ][15921] All specified packages are already installed
2019-03-13 08:50:56,696 [salt.state       :1951][INFO    ][15921] Completed state [iptables-persistent] at time 08:50:56.696175 duration_in_ms=9.831
2019-03-13 08:50:56,697 [salt.state       :1780][INFO    ][15921] Running state [iptables_modules_v4_load] at time 08:50:56.697404
2019-03-13 08:50:56,697 [salt.state       :1813][INFO    ][15921] Executing state kmod.present for [iptables_modules_v4_load]
2019-03-13 08:50:56,698 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15921] Executing command 'lsmod' in directory '/root'
2019-03-13 08:50:56,721 [salt.state       :300 ][INFO    ][15921] Kernel modules iptable_filter, ip_tables are already present
2019-03-13 08:50:56,722 [salt.state       :1951][INFO    ][15921] Completed state [iptables_modules_v4_load] at time 08:50:56.722289 duration_in_ms=24.884
2019-03-13 08:50:56,723 [salt.state       :1780][INFO    ][15921] Running state [/etc/iptables/rules.v4] at time 08:50:56.723443
2019-03-13 08:50:56,724 [salt.state       :1813][INFO    ][15921] Executing state file.managed for [/etc/iptables/rules.v4]
2019-03-13 08:50:56,839 [salt.state       :300 ][INFO    ][15921] File /etc/iptables/rules.v4 is in the correct state
2019-03-13 08:50:56,839 [salt.state       :1951][INFO    ][15921] Completed state [/etc/iptables/rules.v4] at time 08:50:56.839642 duration_in_ms=116.199
2019-03-13 08:50:56,840 [salt.state       :1780][INFO    ][15921] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 08:50:56.840714
2019-03-13 08:50:56,841 [salt.state       :1813][INFO    ][15921] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-03-13 08:50:56,841 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15921] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-03-13 08:50:56,864 [salt.state       :300 ][INFO    ][15921] onlyif execution failed
2019-03-13 08:50:56,864 [salt.state       :1951][INFO    ][15921] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 08:50:56.864776 duration_in_ms=24.061
2019-03-13 08:50:56,866 [salt.state       :1780][INFO    ][15921] Running state [netfilter-persistent] at time 08:50:56.866798
2019-03-13 08:50:56,867 [salt.state       :1813][INFO    ][15921] Executing state service.running for [netfilter-persistent]
2019-03-13 08:50:56,868 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15921] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-03-13 08:50:56,891 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15921] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-03-13 08:50:56,911 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15921] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-03-13 08:50:56,928 [salt.state       :300 ][INFO    ][15921] The service netfilter-persistent is already running
2019-03-13 08:50:56,929 [salt.state       :1951][INFO    ][15921] Completed state [netfilter-persistent] at time 08:50:56.929165 duration_in_ms=62.367
2019-03-13 08:50:56,930 [salt.state       :1780][INFO    ][15921] Running state [iptables_extra.remove_stale_tables] at time 08:50:56.930368
2019-03-13 08:50:56,930 [salt.state       :1813][INFO    ][15921] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-03-13 08:50:56,933 [salt.state       :300 ][INFO    ][15921] No changes made for iptables_extra.remove_stale_tables
2019-03-13 08:50:56,933 [salt.state       :1951][INFO    ][15921] Completed state [iptables_extra.remove_stale_tables] at time 08:50:56.933782 duration_in_ms=3.414
2019-03-13 08:50:56,934 [salt.state       :1780][INFO    ][15921] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 08:50:56.934143
2019-03-13 08:50:56,934 [salt.state       :1813][INFO    ][15921] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-03-13 08:50:56,935 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15921] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-03-13 08:50:56,950 [salt.state       :300 ][INFO    ][15921] onlyif execution failed
2019-03-13 08:50:56,951 [salt.state       :1951][INFO    ][15921] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 08:50:56.951388 duration_in_ms=17.244
2019-03-13 08:50:56,952 [salt.state       :1780][INFO    ][15921] Running state [/etc/iptables/rules.v6] at time 08:50:56.952581
2019-03-13 08:50:56,952 [salt.state       :1813][INFO    ][15921] Executing state file.absent for [/etc/iptables/rules.v6]
2019-03-13 08:50:56,953 [salt.state       :300 ][INFO    ][15921] File /etc/iptables/rules.v6 is not present
2019-03-13 08:50:56,953 [salt.state       :1951][INFO    ][15921] Completed state [/etc/iptables/rules.v6] at time 08:50:56.953750 duration_in_ms=1.17
2019-03-13 08:50:56,954 [salt.state       :1780][INFO    ][15921] Running state [iptables_extra.flush_all] at time 08:50:56.954651
2019-03-13 08:50:56,957 [salt.state       :1813][INFO    ][15921] Executing state module.wait for [iptables_extra.flush_all]
2019-03-13 08:50:56,958 [salt.state       :300 ][INFO    ][15921] No changes made for iptables_extra.flush_all
2019-03-13 08:50:56,958 [salt.state       :1951][INFO    ][15921] Completed state [iptables_extra.flush_all] at time 08:50:56.958394 duration_in_ms=3.743
2019-03-13 08:50:56,962 [salt.minion      :1711][INFO    ][15921] Returning information for job: 20190313085042984368
2019-03-13 08:50:57,524 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command state.apply with jid 20190313085057510315
2019-03-13 08:50:57,539 [salt.minion      :1432][INFO    ][16012] Starting a new job with PID 16012
2019-03-13 08:50:58,637 [salt.state       :915 ][INFO    ][16012] Loading fresh modules for state activity
2019-03-13 08:50:59,502 [salt.state       :1780][INFO    ][16012] Running state [maas-rack-controller] at time 08:50:59.502568
2019-03-13 08:50:59,503 [salt.state       :1813][INFO    ][16012] Executing state pkg.installed for [maas-rack-controller]
2019-03-13 08:50:59,503 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16012] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-13 08:50:59,605 [salt.state       :300 ][INFO    ][16012] All specified packages are already installed
2019-03-13 08:50:59,606 [salt.state       :1951][INFO    ][16012] Completed state [maas-rack-controller] at time 08:50:59.605943 duration_in_ms=103.375
2019-03-13 08:50:59,606 [salt.state       :1780][INFO    ][16012] Running state [ipmitool] at time 08:50:59.606391
2019-03-13 08:50:59,606 [salt.state       :1813][INFO    ][16012] Executing state pkg.installed for [ipmitool]
2019-03-13 08:50:59,616 [salt.state       :300 ][INFO    ][16012] All specified packages are already installed
2019-03-13 08:50:59,616 [salt.state       :1951][INFO    ][16012] Completed state [ipmitool] at time 08:50:59.616473 duration_in_ms=10.082
2019-03-13 08:50:59,622 [salt.state       :1780][INFO    ][16012] Running state [/etc/maas/rackd.conf] at time 08:50:59.622494
2019-03-13 08:50:59,622 [salt.state       :1813][INFO    ][16012] Executing state file.line for [/etc/maas/rackd.conf]
2019-03-13 08:50:59,624 [salt.state       :300 ][INFO    ][16012] No changes needed to be made
2019-03-13 08:50:59,624 [salt.state       :1951][INFO    ][16012] Completed state [/etc/maas/rackd.conf] at time 08:50:59.624267 duration_in_ms=1.773
2019-03-13 08:50:59,624 [salt.state       :1780][INFO    ][16012] Running state [/etc/maas/rackd.conf] at time 08:50:59.624512
2019-03-13 08:50:59,624 [salt.state       :1813][INFO    ][16012] Executing state file.managed for [/etc/maas/rackd.conf]
2019-03-13 08:50:59,625 [salt.loaded.int.states.file:2298][WARNING ][16012] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-03-13 08:50:59,625 [salt.state       :300 ][INFO    ][16012] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-03-13 08:50:59,625 [salt.state       :1951][INFO    ][16012] Completed state [/etc/maas/rackd.conf] at time 08:50:59.625841 duration_in_ms=1.328
2019-03-13 08:50:59,626 [salt.state       :1780][INFO    ][16012] Running state [maas-rackd] at time 08:50:59.626916
2019-03-13 08:50:59,627 [salt.state       :1813][INFO    ][16012] Executing state service.running for [maas-rackd]
2019-03-13 08:50:59,627 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16012] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-03-13 08:50:59,662 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16012] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-03-13 08:50:59,676 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16012] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-03-13 08:50:59,694 [salt.state       :300 ][INFO    ][16012] The service maas-rackd is already running
2019-03-13 08:50:59,695 [salt.state       :1951][INFO    ][16012] Completed state [maas-rackd] at time 08:50:59.695225 duration_in_ms=68.308
2019-03-13 08:50:59,697 [salt.minion      :1711][INFO    ][16012] Returning information for job: 20190313085057510315
2019-03-13 08:51:00,248 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command state.apply with jid 20190313085100233382
2019-03-13 08:51:00,263 [salt.minion      :1432][INFO    ][16035] Starting a new job with PID 16035
2019-03-13 08:51:01,699 [salt.state       :915 ][INFO    ][16035] Loading fresh modules for state activity
2019-03-13 08:51:02,717 [salt.state       :1780][INFO    ][16035] Running state [maas-region-controller] at time 08:51:02.717858
2019-03-13 08:51:02,718 [salt.state       :1813][INFO    ][16035] Executing state pkg.installed for [maas-region-controller]
2019-03-13 08:51:02,719 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16035] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-13 08:51:02,821 [salt.state       :300 ][INFO    ][16035] All specified packages are already installed
2019-03-13 08:51:02,822 [salt.state       :1951][INFO    ][16035] Completed state [maas-region-controller] at time 08:51:02.822211 duration_in_ms=104.354
2019-03-13 08:51:02,822 [salt.state       :1780][INFO    ][16035] Running state [python-oauth] at time 08:51:02.822531
2019-03-13 08:51:02,822 [salt.state       :1813][INFO    ][16035] Executing state pkg.installed for [python-oauth]
2019-03-13 08:51:02,832 [salt.state       :300 ][INFO    ][16035] All specified packages are already installed
2019-03-13 08:51:02,832 [salt.state       :1951][INFO    ][16035] Completed state [python-oauth] at time 08:51:02.832377 duration_in_ms=9.846
2019-03-13 08:51:02,836 [salt.state       :1780][INFO    ][16035] Running state [/etc/maas/regiond.conf] at time 08:51:02.836744
2019-03-13 08:51:02,837 [salt.state       :1813][INFO    ][16035] Executing state file.replace for [/etc/maas/regiond.conf]
2019-03-13 08:51:02,841 [salt.state       :300 ][INFO    ][16035] No changes needed to be made
2019-03-13 08:51:02,841 [salt.state       :1951][INFO    ][16035] Completed state [/etc/maas/regiond.conf] at time 08:51:02.841826 duration_in_ms=5.082
2019-03-13 08:51:02,842 [salt.state       :1780][INFO    ][16035] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 08:51:02.842313
2019-03-13 08:51:02,842 [salt.state       :1813][INFO    ][16035] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-03-13 08:51:02,909 [salt.state       :300 ][INFO    ][16035] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-03-13 08:51:02,910 [salt.state       :1951][INFO    ][16035] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 08:51:02.910140 duration_in_ms=67.826
2019-03-13 08:51:02,910 [salt.state       :1780][INFO    ][16035] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:51:02.910643
2019-03-13 08:51:02,910 [salt.state       :1813][INFO    ][16035] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-13 08:51:02,915 [salt.state       :300 ][INFO    ][16035] No changes needed to be made
2019-03-13 08:51:02,915 [salt.state       :1951][INFO    ][16035] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:51:02.915530 duration_in_ms=4.887
2019-03-13 08:51:02,916 [salt.state       :1780][INFO    ][16035] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:51:02.916010
2019-03-13 08:51:02,916 [salt.state       :1813][INFO    ][16035] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-13 08:51:02,920 [salt.state       :300 ][INFO    ][16035] No changes needed to be made
2019-03-13 08:51:02,920 [salt.state       :1951][INFO    ][16035] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:51:02.920627 duration_in_ms=4.617
2019-03-13 08:51:02,921 [salt.state       :1780][INFO    ][16035] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 08:51:02.921107
2019-03-13 08:51:02,921 [salt.state       :1813][INFO    ][16035] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-03-13 08:51:02,946 [salt.state       :300 ][INFO    ][16035] No changes needed to be made
2019-03-13 08:51:02,946 [salt.state       :1951][INFO    ][16035] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 08:51:02.946369 duration_in_ms=25.262
2019-03-13 08:51:02,946 [salt.state       :1780][INFO    ][16035] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 08:51:02.946862
2019-03-13 08:51:02,947 [salt.state       :1813][INFO    ][16035] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-03-13 08:51:02,961 [salt.state       :300 ][INFO    ][16035] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-03-13 08:51:02,961 [salt.state       :1951][INFO    ][16035] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 08:51:02.961525 duration_in_ms=14.663
2019-03-13 08:51:02,962 [salt.state       :1780][INFO    ][16035] Running state [a2enmod headers] at time 08:51:02.962894
2019-03-13 08:51:02,963 [salt.state       :1813][INFO    ][16035] Executing state cmd.run for [a2enmod headers]
2019-03-13 08:51:02,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16035] Executing command 'a2enmod headers' in directory '/root'
2019-03-13 08:51:03,036 [salt.state       :300 ][INFO    ][16035] {'pid': 16061, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-03-13 08:51:03,037 [salt.state       :1951][INFO    ][16035] Completed state [a2enmod headers] at time 08:51:03.037245 duration_in_ms=74.349
2019-03-13 08:51:03,038 [salt.state       :1780][INFO    ][16035] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 08:51:03.037926
2019-03-13 08:51:03,038 [salt.state       :1813][INFO    ][16035] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-03-13 08:51:03,059 [salt.state       :300 ][INFO    ][16035] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-03-13 08:51:03,059 [salt.state       :1951][INFO    ][16035] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 08:51:03.059694 duration_in_ms=21.769
2019-03-13 08:51:03,060 [salt.state       :1780][INFO    ][16035] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 08:51:03.060325
2019-03-13 08:51:03,060 [salt.state       :1813][INFO    ][16035] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-03-13 08:51:03,113 [salt.state       :300 ][INFO    ][16035] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-03-13 08:51:03,113 [salt.state       :1951][INFO    ][16035] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 08:51:03.113397 duration_in_ms=53.071
2019-03-13 08:51:03,113 [salt.state       :1780][INFO    ][16035] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 08:51:03.113947
2019-03-13 08:51:03,114 [salt.state       :1813][INFO    ][16035] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-03-13 08:51:03,168 [salt.state       :300 ][INFO    ][16035] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-03-13 08:51:03,168 [salt.state       :1951][INFO    ][16035] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 08:51:03.168688 duration_in_ms=54.741
2019-03-13 08:51:03,169 [salt.state       :1780][INFO    ][16035] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 08:51:03.169205
2019-03-13 08:51:03,169 [salt.state       :1813][INFO    ][16035] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-03-13 08:51:03,252 [salt.state       :300 ][INFO    ][16035] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-03-13 08:51:03,253 [salt.state       :1951][INFO    ][16035] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 08:51:03.252979 duration_in_ms=83.774
2019-03-13 08:51:03,253 [salt.state       :1780][INFO    ][16035] Running state [/root/.pgpass] at time 08:51:03.253261
2019-03-13 08:51:03,253 [salt.state       :1813][INFO    ][16035] Executing state file.managed for [/root/.pgpass]
2019-03-13 08:51:03,307 [salt.state       :300 ][INFO    ][16035] File /root/.pgpass is in the correct state
2019-03-13 08:51:03,307 [salt.state       :1951][INFO    ][16035] Completed state [/root/.pgpass] at time 08:51:03.307754 duration_in_ms=54.493
2019-03-13 08:51:03,313 [salt.state       :1780][INFO    ][16035] Running state [maas-region syncdb --noinput] at time 08:51:03.313618
2019-03-13 08:51:03,313 [salt.state       :1813][INFO    ][16035] Executing state cmd.run for [maas-region syncdb --noinput]
2019-03-13 08:51:03,314 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16035] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-03-13 08:51:06,015 [salt.state       :300 ][INFO    ][16035] {'pid': 16074, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sites, sessions, maasserver, contenttypes, piston3, metadataserver, auth\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-03-13 08:51:06,016 [salt.state       :1951][INFO    ][16035] Completed state [maas-region syncdb --noinput] at time 08:51:06.016235 duration_in_ms=2702.616
2019-03-13 08:51:06,016 [salt.state       :2022][WARNING ][16035] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-13 08:51:06,018 [salt.state       :1780][INFO    ][16035] Running state [maas-regiond] at time 08:51:06.018281
2019-03-13 08:51:06,018 [salt.state       :1813][INFO    ][16035] Executing state service.running for [maas-regiond]
2019-03-13 08:51:06,020 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16035] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-03-13 08:51:06,047 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16035] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-03-13 08:51:06,059 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16035] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-03-13 08:51:06,072 [salt.state       :300 ][INFO    ][16035] The service maas-regiond is already running
2019-03-13 08:51:06,072 [salt.state       :1951][INFO    ][16035] Completed state [maas-regiond] at time 08:51:06.072832 duration_in_ms=54.55
2019-03-13 08:51:06,074 [salt.state       :1780][INFO    ][16035] Running state [bind9] at time 08:51:06.074612
2019-03-13 08:51:06,077 [salt.state       :1813][INFO    ][16035] Executing state service.running for [bind9]
2019-03-13 08:51:06,078 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16035] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-03-13 08:51:06,091 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16035] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-03-13 08:51:06,103 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16035] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-03-13 08:51:06,115 [salt.state       :300 ][INFO    ][16035] The service bind9 is already running
2019-03-13 08:51:06,116 [salt.state       :1951][INFO    ][16035] Completed state [bind9] at time 08:51:06.116011 duration_in_ms=41.399
2019-03-13 08:51:06,117 [salt.state       :1780][INFO    ][16035] Running state [apache2] at time 08:51:06.117749
2019-03-13 08:51:06,118 [salt.state       :1813][INFO    ][16035] Executing state service.running for [apache2]
2019-03-13 08:51:06,118 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16035] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-03-13 08:51:06,131 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16035] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-03-13 08:51:06,142 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16035] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-03-13 08:51:06,163 [salt.state       :300 ][INFO    ][16035] The service apache2 is already running
2019-03-13 08:51:06,163 [salt.state       :1951][INFO    ][16035] Completed state [apache2] at time 08:51:06.163731 duration_in_ms=45.982
2019-03-13 08:51:06,164 [salt.state       :1780][INFO    ][16035] Running state [maasng.wait_for_http_code] at time 08:51:06.164877
2019-03-13 08:51:06,165 [salt.state       :1813][INFO    ][16035] Executing state module.run for [maasng.wait_for_http_code]
2019-03-13 08:51:06,165 [salt.utils.decorators:613 ][WARNING ][16035] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-13 08:51:06,353 [salt.state       :300 ][INFO    ][16035] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-03-13 08:51:06,353 [salt.state       :1951][INFO    ][16035] Completed state [maasng.wait_for_http_code] at time 08:51:06.353804 duration_in_ms=188.926
2019-03-13 08:51:06,354 [salt.state       :1780][INFO    ][16035] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 08:51:06.354603
2019-03-13 08:51:06,355 [salt.state       :1813][INFO    ][16035] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-03-13 08:51:06,356 [salt.state       :300 ][INFO    ][16035] /var/lib/maas/.setup_admin exists
2019-03-13 08:51:06,356 [salt.state       :1951][INFO    ][16035] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 08:51:06.356634 duration_in_ms=2.031
2019-03-13 08:51:06,357 [salt.state       :1780][INFO    ][16035] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:51:06.357305
2019-03-13 08:51:06,357 [salt.state       :1813][INFO    ][16035] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-13 08:51:06,358 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16035] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-13 08:51:08,064 [salt.state       :300 ][INFO    ][16035] {'pid': 16095, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-13 08:51:08,065 [salt.state       :1951][INFO    ][16035] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:51:08.065114 duration_in_ms=1707.809
2019-03-13 08:51:08,071 [salt.state       :1780][INFO    ][16035] Running state [maas_region_boot_source_resources_mirror] at time 08:51:08.071294
2019-03-13 08:51:08,071 [salt.state       :1813][INFO    ][16035] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-03-13 08:51:08,143 [salt.state       :300 ][INFO    ][16035] {'changes': {}}
2019-03-13 08:51:08,143 [salt.state       :1951][INFO    ][16035] Completed state [maas_region_boot_source_resources_mirror] at time 08:51:08.143660 duration_in_ms=72.366
2019-03-13 08:51:08,144 [salt.state       :1780][INFO    ][16035] Running state [maasng.boot_resources_import] at time 08:51:08.144334
2019-03-13 08:51:08,144 [salt.state       :1813][INFO    ][16035] Executing state module.run for [maasng.boot_resources_import]
2019-03-13 08:51:08,144 [salt.utils.decorators:613 ][WARNING ][16035] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-13 08:51:08,215 [salt.loaded.ext.module.maasng:1600][INFO    ][16035] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-03-13 08:51:13,260 [salt.loaded.ext.module.maasng:1600][INFO    ][16035] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-03-13 08:51:15,296 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313085115287856
2019-03-13 08:51:15,311 [salt.minion      :1432][INFO    ][16250] Starting a new job with PID 16250
2019-03-13 08:51:15,341 [salt.minion      :1711][INFO    ][16250] Returning information for job: 20190313085115287856
2019-03-13 08:51:18,337 [salt.state       :300 ][INFO    ][16035] {'ret': True}
2019-03-13 08:51:18,338 [salt.state       :1951][INFO    ][16035] Completed state [maasng.boot_resources_import] at time 08:51:18.338195 duration_in_ms=10193.86
2019-03-13 08:51:18,341 [salt.state       :1780][INFO    ][16035] Running state [maas_region_boot_sources_selection_xenial] at time 08:51:18.341138
2019-03-13 08:51:18,341 [salt.state       :1813][INFO    ][16035] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-03-13 08:51:18,468 [salt.state       :300 ][INFO    ][16035] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-03-13 08:51:18,468 [salt.state       :1951][INFO    ][16035] Completed state [maas_region_boot_sources_selection_xenial] at time 08:51:18.468567 duration_in_ms=127.429
2019-03-13 08:51:18,469 [salt.state       :1780][INFO    ][16035] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 08:51:18.469357
2019-03-13 08:51:18,469 [salt.state       :1813][INFO    ][16035] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-03-13 08:51:18,469 [salt.utils.decorators:613 ][WARNING ][16035] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-13 08:51:18,470 [salt.loaded.ext.module.maasng:1771][INFO    ][16035] boot-sources sync initiated for ALL Rack's
2019-03-13 08:51:19,312 [salt.state       :300 ][INFO    ][16035] {'ret': True}
2019-03-13 08:51:19,312 [salt.state       :1951][INFO    ][16035] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 08:51:19.312452 duration_in_ms=843.095
2019-03-13 08:51:19,313 [salt.state       :1780][INFO    ][16035] Running state [maas.process_maas_config] at time 08:51:19.313729
2019-03-13 08:51:19,314 [salt.state       :1813][INFO    ][16035] Executing state module.run for [maas.process_maas_config]
2019-03-13 08:51:19,314 [salt.utils.decorators:613 ][WARNING ][16035] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-13 08:51:19,314 [salt.loaded.ext.module.maas:92  ][INFO    ][16035] maasconfig name=enable_http_proxy value=True
2019-03-13 08:51:19,356 [salt.loaded.ext.module.maas:92  ][INFO    ][16035] maasconfig name=upstream_dns value=8.8.8.8
2019-03-13 08:51:20,676 [salt.loaded.ext.module.maas:92  ][INFO    ][16035] maasconfig name=commissioning_distro_series value=xenial
2019-03-13 08:51:20,732 [salt.loaded.ext.module.maas:92  ][INFO    ][16035] maasconfig name=default_osystem value=ubuntu
2019-03-13 08:51:20,774 [salt.loaded.ext.module.maas:92  ][INFO    ][16035] maasconfig name=active_discovery_interval value=600
2019-03-13 08:51:20,828 [salt.loaded.ext.module.maas:92  ][INFO    ][16035] maasconfig name=dnssec_validation value=no
2019-03-13 08:51:20,893 [salt.loaded.ext.module.maas:92  ][INFO    ][16035] maasconfig name=maas_name value=mas01
2019-03-13 08:51:20,954 [salt.loaded.ext.module.maas:92  ][INFO    ][16035] maasconfig name=network_discovery value=enabled
2019-03-13 08:51:21,059 [salt.loaded.ext.module.maas:92  ][INFO    ][16035] maasconfig name=enable_third_party_drivers value=True
2019-03-13 08:51:21,106 [salt.loaded.ext.module.maas:92  ][INFO    ][16035] maasconfig name=default_storage_layout value=lvm
2019-03-13 08:51:21,155 [salt.loaded.ext.module.maas:92  ][INFO    ][16035] maasconfig name=ntp_external_only value=True
2019-03-13 08:51:21,197 [salt.loaded.ext.module.maas:92  ][INFO    ][16035] maasconfig name=disk_erase_with_secure_erase value=False
2019-03-13 08:51:21,238 [salt.loaded.ext.module.maas:92  ][INFO    ][16035] maasconfig name=default_distro_series value=xenial
2019-03-13 08:51:21,286 [salt.loaded.ext.module.maas:92  ][INFO    ][16035] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-03-13 08:51:21,377 [salt.state       :300 ][INFO    ][16035] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-03-13 08:51:21,377 [salt.state       :1951][INFO    ][16035] Completed state [maas.process_maas_config] at time 08:51:21.377896 duration_in_ms=2064.167
2019-03-13 08:51:21,378 [salt.state       :1780][INFO    ][16035] Running state [pxe_admin] at time 08:51:21.378461
2019-03-13 08:51:21,378 [salt.state       :1813][INFO    ][16035] Executing state maasng.fabric_present for [pxe_admin]
2019-03-13 08:51:21,421 [salt.loaded.ext.module.maasng:945 ][INFO    ][16035] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'esqc4e', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-13 08:51:21,474 [salt.loaded.ext.module.maasng:1008][WARNING ][16035] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-03-13 08:51:21,474 [salt.loaded.ext.module.maasng:1011][WARNING ][16035] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-03-13 08:51:21,558 [salt.state       :300 ][INFO    ][16035] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-03-13 08:51:21,558 [salt.state       :1951][INFO    ][16035] Completed state [pxe_admin] at time 08:51:21.558430 duration_in_ms=179.968
2019-03-13 08:51:21,558 [salt.state       :1780][INFO    ][16035] Running state [vlan 0] at time 08:51:21.558780
2019-03-13 08:51:21,559 [salt.state       :1813][INFO    ][16035] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-13 08:51:21,611 [salt.loaded.ext.module.maasng:945 ][INFO    ][16035] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-2', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'esqc4e', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-03-13 08:51:21,692 [salt.loaded.ext.module.maasng:945 ][INFO    ][16035] [{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'esqc4e', 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-03-13 08:51:21,890 [salt.loaded.ext.module.maasng:945 ][INFO    ][16035] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-2', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'esqc4e', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-03-13 08:51:21,955 [salt.state       :300 ][INFO    ][16035] {'new': 'Vlan untagged was updated'}
2019-03-13 08:51:21,955 [salt.state       :1951][INFO    ][16035] Completed state [vlan 0] at time 08:51:21.955233 duration_in_ms=396.453
2019-03-13 08:51:21,956 [salt.state       :1780][INFO    ][16035] Running state [192.168.11.0/24] at time 08:51:21.956112
2019-03-13 08:51:21,956 [salt.state       :1813][INFO    ][16035] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-03-13 08:51:22,104 [salt.loaded.ext.module.maasng:945 ][INFO    ][16035] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-2', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'esqc4e', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-03-13 08:51:22,104 [salt.loaded.ext.module.maasng:1235][WARNING ][16035] Ignoring parameter vlan:0
2019-03-13 08:51:22,188 [salt.state       :300 ][INFO    ][16035] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-03-13 08:51:22,189 [salt.state       :1951][INFO    ][16035] Completed state [192.168.11.0/24] at time 08:51:22.189001 duration_in_ms=232.888
2019-03-13 08:51:22,189 [salt.state       :1780][INFO    ][16035] Running state [maas_create_iprange_1] at time 08:51:22.189843
2019-03-13 08:51:22,190 [salt.state       :1813][INFO    ][16035] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-03-13 08:51:22,241 [salt.state       :300 ][INFO    ][16035] Iprange maas_create_iprange_1 already exist.
2019-03-13 08:51:22,241 [salt.state       :1951][INFO    ][16035] Completed state [maas_create_iprange_1] at time 08:51:22.241941 duration_in_ms=52.098
2019-03-13 08:51:22,242 [salt.state       :1780][INFO    ][16035] Running state [vlan 0] at time 08:51:22.242193
2019-03-13 08:51:22,242 [salt.state       :1813][INFO    ][16035] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-13 08:51:22,291 [salt.loaded.ext.module.maasng:945 ][INFO    ][16035] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-2', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'esqc4e', 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'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-03-13 08:51:22,379 [salt.loaded.ext.module.maasng:945 ][INFO    ][16035] [{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'esqc4e', 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-03-13 08:51:22,590 [salt.loaded.ext.module.maasng:945 ][INFO    ][16035] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'esqc4e', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-13 08:51:22,700 [salt.state       :300 ][INFO    ][16035] {'new': 'Vlan untagged was updated'}
2019-03-13 08:51:22,700 [salt.state       :1951][INFO    ][16035] Completed state [vlan 0] at time 08:51:22.700333 duration_in_ms=458.139
2019-03-13 08:51:22,700 [salt.state       :1780][INFO    ][16035] Running state [opnfv] at time 08:51:22.700936
2019-03-13 08:51:22,701 [salt.state       :1813][INFO    ][16035] Executing state maasng.sshkey_present for [opnfv]
2019-03-13 08:51:22,765 [salt.loaded.ext.module.maasng:1903][INFO    ][16035] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-03-13 08:51:22,765 [salt.state       :300 ][INFO    ][16035] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H already exist for user opnfv.
2019-03-13 08:51:22,766 [salt.state       :1951][INFO    ][16035] Completed state [opnfv] at time 08:51:22.765959 duration_in_ms=65.022
2019-03-13 08:51:22,766 [salt.state       :1780][INFO    ][16035] Running state [maas.process_tags] at time 08:51:22.766579
2019-03-13 08:51:22,773 [salt.state       :1813][INFO    ][16035] Executing state module.run for [maas.process_tags]
2019-03-13 08:51:22,773 [salt.utils.decorators:613 ][WARNING ][16035] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-13 08:51:22,820 [salt.loaded.ext.module.maas:92  ][INFO    ][16035] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-03-13 08:51:22,878 [salt.state       :300 ][INFO    ][16035] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-03-13 08:51:22,878 [salt.state       :1951][INFO    ][16035] Completed state [maas.process_tags] at time 08:51:22.878692 duration_in_ms=112.113
2019-03-13 08:51:22,880 [salt.minion      :1711][INFO    ][16035] Returning information for job: 20190313085100233382
2019-03-13 08:51:23,481 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command state.apply with jid 20190313085123471722
2019-03-13 08:51:23,498 [salt.minion      :1432][INFO    ][16481] Starting a new job with PID 16481
2019-03-13 08:51:31,768 [salt.state       :915 ][INFO    ][16481] Loading fresh modules for state activity
2019-03-13 08:51:31,859 [salt.state       :1780][INFO    ][16481] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:51:31.859840
2019-03-13 08:51:31,860 [salt.state       :1813][INFO    ][16481] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-13 08:51:31,861 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16481] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-13 08:51:33,843 [salt.state       :300 ][INFO    ][16481] {'pid': 16506, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-13 08:51:33,844 [salt.state       :1951][INFO    ][16481] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:51:33.844171 duration_in_ms=1984.33
2019-03-13 08:51:33,845 [salt.state       :1780][INFO    ][16481] Running state [maas.process_machines] at time 08:51:33.845716
2019-03-13 08:51:33,846 [salt.state       :1813][INFO    ][16481] Executing state module.run for [maas.process_machines]
2019-03-13 08:51:33,846 [salt.utils.decorators:613 ][WARNING ][16481] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-13 08:51:34,325 [salt.loaded.ext.module.maas:412 ][WARNING ][16481] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-13 08:51:34,326 [salt.loaded.ext.module.maas:92  ][INFO    ][16481] 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=8t7m6b architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-13 08:51:35,268 [salt.loaded.ext.module.maas:412 ][WARNING ][16481] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-13 08:51:35,268 [salt.loaded.ext.module.maas:92  ][INFO    ][16481] 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=ah8fdk architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-13 08:51:36,215 [salt.loaded.ext.module.maas:412 ][WARNING ][16481] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-13 08:51:36,216 [salt.loaded.ext.module.maas:92  ][INFO    ][16481] 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=gdbb8t architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-13 08:51:37,141 [salt.loaded.ext.module.maas:412 ][WARNING ][16481] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-13 08:51:37,142 [salt.loaded.ext.module.maas:92  ][INFO    ][16481] 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=cahak3 architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-13 08:51:38,071 [salt.loaded.ext.module.maas:412 ][WARNING ][16481] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-13 08:51:38,072 [salt.loaded.ext.module.maas:92  ][INFO    ][16481] 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=yx83q4 architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-13 08:51:38,522 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313085138514598
2019-03-13 08:51:38,545 [salt.minion      :1432][INFO    ][16746] Starting a new job with PID 16746
2019-03-13 08:51:38,582 [salt.minion      :1711][INFO    ][16746] Returning information for job: 20190313085138514598
2019-03-13 08:51:38,994 [salt.state       :300 ][INFO    ][16481] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-13 08:51:38,995 [salt.state       :1951][INFO    ][16481] Completed state [maas.process_machines] at time 08:51:38.995279 duration_in_ms=5149.562
2019-03-13 08:51:38,997 [salt.minion      :1711][INFO    ][16481] Returning information for job: 20190313085123471722
2019-03-13 08:52:12,011 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command state.apply with jid 20190313085211994337
2019-03-13 08:52:12,034 [salt.minion      :1432][INFO    ][16810] Starting a new job with PID 16810
2019-03-13 08:52:20,248 [salt.state       :915 ][INFO    ][16810] Loading fresh modules for state activity
2019-03-13 08:52:20,336 [salt.state       :1780][INFO    ][16810] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:52:20.336506
2019-03-13 08:52:20,336 [salt.state       :1813][INFO    ][16810] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-13 08:52:20,338 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16810] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-13 08:52:22,164 [salt.state       :300 ][INFO    ][16810] {'pid': 16819, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-13 08:52:22,165 [salt.state       :1951][INFO    ][16810] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:52:22.165035 duration_in_ms=1828.53
2019-03-13 08:52:22,167 [salt.state       :1780][INFO    ][16810] Running state [maas.wait_for_machine_status] at time 08:52:22.167706
2019-03-13 08:52:22,168 [salt.state       :1813][INFO    ][16810] Executing state module.run for [maas.wait_for_machine_status]
2019-03-13 08:52:22,168 [salt.utils.decorators:613 ][WARNING ][16810] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-13 08:52:24,432 [salt.state       :300 ][INFO    ][16810] {'ret': True}
2019-03-13 08:52:24,432 [salt.state       :1951][INFO    ][16810] Completed state [maas.wait_for_machine_status] at time 08:52:24.432764 duration_in_ms=2265.058
2019-03-13 08:52:24,434 [salt.minion      :1711][INFO    ][16810] Returning information for job: 20190313085211994337
2019-03-13 08:52:24,980 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command state.apply with jid 20190313085224969733
2019-03-13 08:52:24,997 [salt.minion      :1432][INFO    ][16832] Starting a new job with PID 16832
2019-03-13 08:52:26,569 [salt.state       :915 ][INFO    ][16832] Loading fresh modules for state activity
2019-03-13 08:52:26,703 [salt.state       :1780][INFO    ][16832] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:52:26.703572
2019-03-13 08:52:26,703 [salt.state       :1813][INFO    ][16832] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-13 08:52:26,705 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16832] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-13 08:52:28,475 [salt.state       :300 ][INFO    ][16832] {'pid': 16839, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-13 08:52:28,476 [salt.state       :1951][INFO    ][16832] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:52:28.476450 duration_in_ms=1772.878
2019-03-13 08:52:28,478 [salt.state       :1780][INFO    ][16832] Running state [maas_machines_storage_cmp002_lvm] at time 08:52:28.478369
2019-03-13 08:52:28,478 [salt.state       :1813][INFO    ][16832] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-13 08:52:28,941 [salt.state       :300 ][INFO    ][16832] Machine cmp002 is not in Ready state.
2019-03-13 08:52:28,941 [salt.state       :1951][INFO    ][16832] Completed state [maas_machines_storage_cmp002_lvm] at time 08:52:28.941886 duration_in_ms=463.516
2019-03-13 08:52:28,942 [salt.state       :1780][INFO    ][16832] Running state [maas_machines_storage_cmp001_lvm] at time 08:52:28.942262
2019-03-13 08:52:28,942 [salt.state       :1813][INFO    ][16832] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-13 08:52:29,400 [salt.state       :300 ][INFO    ][16832] Machine cmp001 is not in Ready state.
2019-03-13 08:52:29,401 [salt.state       :1951][INFO    ][16832] Completed state [maas_machines_storage_cmp001_lvm] at time 08:52:29.401225 duration_in_ms=458.961
2019-03-13 08:52:29,406 [salt.minion      :1711][INFO    ][16832] Returning information for job: 20190313085224969733
2019-03-13 08:52:29,925 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command state.apply with jid 20190313085229915052
2019-03-13 08:52:29,938 [salt.minion      :1432][INFO    ][16849] Starting a new job with PID 16849
2019-03-13 08:52:30,936 [salt.state       :915 ][INFO    ][16849] Loading fresh modules for state activity
2019-03-13 08:52:31,018 [salt.state       :1780][INFO    ][16849] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:52:31.018371
2019-03-13 08:52:31,018 [salt.state       :1813][INFO    ][16849] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-13 08:52:31,020 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16849] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-13 08:52:32,914 [salt.state       :300 ][INFO    ][16849] {'pid': 16856, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-13 08:52:32,914 [salt.state       :1951][INFO    ][16849] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:52:32.914591 duration_in_ms=1896.219
2019-03-13 08:52:32,917 [salt.state       :1780][INFO    ][16849] Running state [maas.deploy_machines] at time 08:52:32.917062
2019-03-13 08:52:32,917 [salt.state       :1813][INFO    ][16849] Executing state module.run for [maas.deploy_machines]
2019-03-13 08:52:32,917 [salt.utils.decorators:613 ][WARNING ][16849] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-13 08:52:33,403 [salt.loaded.ext.module.maas:684 ][INFO    ][16849] deploymachines hwe_kernel=hwe-16.04 system_id=yx83q4 distro_series=xenial
2019-03-13 08:52:35,408 [salt.state       :300 ][INFO    ][16849] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-03-13 08:52:35,408 [salt.state       :1951][INFO    ][16849] Completed state [maas.deploy_machines] at time 08:52:35.408702 duration_in_ms=2491.639
2019-03-13 08:52:35,411 [salt.minion      :1711][INFO    ][16849] Returning information for job: 20190313085229915052
2019-03-13 08:52:35,988 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command state.apply with jid 20190313085235976490
2019-03-13 08:52:36,002 [salt.minion      :1432][INFO    ][16950] Starting a new job with PID 16950
2019-03-13 08:52:44,610 [salt.state       :915 ][INFO    ][16950] Loading fresh modules for state activity
2019-03-13 08:52:44,696 [salt.state       :1780][INFO    ][16950] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:52:44.696048
2019-03-13 08:52:44,696 [salt.state       :1813][INFO    ][16950] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-13 08:52:44,698 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16950] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-13 08:52:46,367 [salt.state       :300 ][INFO    ][16950] {'pid': 16993, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-13 08:52:46,368 [salt.state       :1951][INFO    ][16950] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:52:46.368321 duration_in_ms=1672.274
2019-03-13 08:52:46,369 [salt.state       :1780][INFO    ][16950] Running state [maas.wait_for_machine_status] at time 08:52:46.369857
2019-03-13 08:52:46,370 [salt.state       :1813][INFO    ][16950] Executing state module.run for [maas.wait_for_machine_status]
2019-03-13 08:52:46,370 [salt.utils.decorators:613 ][WARNING ][16950] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-13 08:52:48,778 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.60118008s left)
2019-03-13 08:52:51,022 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313085251012610
2019-03-13 08:52:51,036 [salt.minion      :1432][INFO    ][17020] Starting a new job with PID 17020
2019-03-13 08:52:51,060 [salt.minion      :1711][INFO    ][17020] Returning information for job: 20190313085251012610
2019-03-13 08:53:21,107 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2215.27135205s left)
2019-03-13 08:53:21,226 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313085321173317
2019-03-13 08:53:21,242 [salt.minion      :1432][INFO    ][17050] Starting a new job with PID 17050
2019-03-13 08:53:21,271 [salt.minion      :1711][INFO    ][17050] Returning information for job: 20190313085321173317
2019-03-13 08:53:51,230 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313085351221794
2019-03-13 08:53:51,246 [salt.minion      :1432][INFO    ][17128] Starting a new job with PID 17128
2019-03-13 08:53:51,269 [salt.minion      :1711][INFO    ][17128] Returning information for job: 20190313085351221794
2019-03-13 08:53:53,652 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2182.72680593s left)
2019-03-13 08:54:21,429 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313085421419482
2019-03-13 08:54:21,445 [salt.minion      :1432][INFO    ][17148] Starting a new job with PID 17148
2019-03-13 08:54:21,472 [salt.minion      :1711][INFO    ][17148] Returning information for job: 20190313085421419482
2019-03-13 08:54:26,257 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2150.12174797s left)
2019-03-13 08:54:51,642 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313085451631484
2019-03-13 08:54:51,655 [salt.minion      :1432][INFO    ][17232] Starting a new job with PID 17232
2019-03-13 08:54:51,678 [salt.minion      :1711][INFO    ][17232] Returning information for job: 20190313085451631484
2019-03-13 08:54:58,594 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2117.78470111s left)
2019-03-13 08:55:21,868 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313085521842230
2019-03-13 08:55:21,882 [salt.minion      :1432][INFO    ][17255] Starting a new job with PID 17255
2019-03-13 08:55:21,905 [salt.minion      :1711][INFO    ][17255] Returning information for job: 20190313085521842230
2019-03-13 08:55:30,936 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2085.44251609s left)
2019-03-13 08:55:51,885 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313085551873245
2019-03-13 08:55:51,896 [salt.minion      :1432][INFO    ][17337] Starting a new job with PID 17337
2019-03-13 08:55:51,920 [salt.minion      :1711][INFO    ][17337] Returning information for job: 20190313085551873245
2019-03-13 08:56:03,290 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2053.08838201s left)
2019-03-13 08:56:22,082 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313085622075010
2019-03-13 08:56:22,098 [salt.minion      :1432][INFO    ][17358] Starting a new job with PID 17358
2019-03-13 08:56:22,118 [salt.minion      :1711][INFO    ][17358] Returning information for job: 20190313085622075010
2019-03-13 08:56:35,834 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2020.54515195s left)
2019-03-13 08:56:52,302 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313085652292854
2019-03-13 08:56:52,316 [salt.minion      :1432][INFO    ][17465] Starting a new job with PID 17465
2019-03-13 08:56:52,339 [salt.minion      :1711][INFO    ][17465] Returning information for job: 20190313085652292854
2019-03-13 08:57:08,269 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1988.10966396s left)
2019-03-13 08:57:22,318 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313085722307784
2019-03-13 08:57:22,334 [salt.minion      :1432][INFO    ][17499] Starting a new job with PID 17499
2019-03-13 08:57:22,363 [salt.minion      :1711][INFO    ][17499] Returning information for job: 20190313085722307784
2019-03-13 08:57:40,882 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1955.49665189s left)
2019-03-13 08:57:52,376 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313085752368041
2019-03-13 08:57:52,391 [salt.minion      :1432][INFO    ][17702] Starting a new job with PID 17702
2019-03-13 08:57:52,413 [salt.minion      :1711][INFO    ][17702] Returning information for job: 20190313085752368041
2019-03-13 08:58:13,445 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1922.9341321s left)
2019-03-13 08:58:22,405 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313085822397091
2019-03-13 08:58:22,421 [salt.minion      :1432][INFO    ][17726] Starting a new job with PID 17726
2019-03-13 08:58:22,445 [salt.minion      :1711][INFO    ][17726] Returning information for job: 20190313085822397091
2019-03-13 08:58:45,934 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1890.44464207s left)
2019-03-13 08:58:52,454 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313085852437122
2019-03-13 08:58:52,472 [salt.minion      :1432][INFO    ][17871] Starting a new job with PID 17871
2019-03-13 08:58:52,496 [salt.minion      :1711][INFO    ][17871] Returning information for job: 20190313085852437122
2019-03-13 08:59:18,401 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1857.97775388s left)
2019-03-13 08:59:22,508 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313085922487330
2019-03-13 08:59:22,524 [salt.minion      :1432][INFO    ][17892] Starting a new job with PID 17892
2019-03-13 08:59:22,548 [salt.minion      :1711][INFO    ][17892] Returning information for job: 20190313085922487330
2019-03-13 08:59:50,980 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1825.39852095s left)
2019-03-13 08:59:52,621 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313085952586623
2019-03-13 08:59:52,639 [salt.minion      :1432][INFO    ][18018] Starting a new job with PID 18018
2019-03-13 08:59:52,662 [salt.minion      :1711][INFO    ][18018] Returning information for job: 20190313085952586623
2019-03-13 09:00:22,744 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313090022721140
2019-03-13 09:00:22,759 [salt.minion      :1432][INFO    ][18046] Starting a new job with PID 18046
2019-03-13 09:00:22,781 [salt.minion      :1711][INFO    ][18046] Returning information for job: 20190313090022721140
2019-03-13 09:00:23,346 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1793.03242302s left)
2019-03-13 09:00:52,828 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313090052811366
2019-03-13 09:00:52,841 [salt.minion      :1432][INFO    ][18197] Starting a new job with PID 18197
2019-03-13 09:00:52,861 [salt.minion      :1711][INFO    ][18197] Returning information for job: 20190313090052811366
2019-03-13 09:00:55,697 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1760.68189502s left)
2019-03-13 09:01:22,907 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313090122884853
2019-03-13 09:01:22,920 [salt.minion      :1432][INFO    ][18220] Starting a new job with PID 18220
2019-03-13 09:01:22,943 [salt.minion      :1711][INFO    ][18220] Returning information for job: 20190313090122884853
2019-03-13 09:01:28,296 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1728.08307099s left)
2019-03-13 09:01:52,969 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313090152937498
2019-03-13 09:01:52,982 [salt.minion      :1432][INFO    ][18300] Starting a new job with PID 18300
2019-03-13 09:01:53,006 [salt.minion      :1711][INFO    ][18300] Returning information for job: 20190313090152937498
2019-03-13 09:02:00,754 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1695.62483788s left)
2019-03-13 09:02:23,037 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313090223001969
2019-03-13 09:02:23,050 [salt.minion      :1432][INFO    ][18321] Starting a new job with PID 18321
2019-03-13 09:02:23,069 [salt.minion      :1711][INFO    ][18321] Returning information for job: 20190313090223001969
2019-03-13 09:02:33,305 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1663.07351494s left)
2019-03-13 09:02:53,087 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313090253072874
2019-03-13 09:02:53,100 [salt.minion      :1432][INFO    ][18417] Starting a new job with PID 18417
2019-03-13 09:02:53,123 [salt.minion      :1711][INFO    ][18417] Returning information for job: 20190313090253072874
2019-03-13 09:03:05,661 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1630.71757889s left)
2019-03-13 09:03:23,130 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313090323113866
2019-03-13 09:03:23,143 [salt.minion      :1432][INFO    ][18444] Starting a new job with PID 18444
2019-03-13 09:03:23,165 [salt.minion      :1711][INFO    ][18444] Returning information for job: 20190313090323113866
2019-03-13 09:03:37,988 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1598.39075398s left)
2019-03-13 09:03:53,194 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313090353178664
2019-03-13 09:03:53,208 [salt.minion      :1432][INFO    ][18673] Starting a new job with PID 18673
2019-03-13 09:03:53,230 [salt.minion      :1711][INFO    ][18673] Returning information for job: 20190313090353178664
2019-03-13 09:04:10,425 [salt.loaded.ext.module.maas:1023][INFO    ][16950] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1565.95324898s left)
2019-03-13 09:04:23,268 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command saltutil.find_job with jid 20190313090423231946
2019-03-13 09:04:23,281 [salt.minion      :1432][INFO    ][18716] Starting a new job with PID 18716
2019-03-13 09:04:23,306 [salt.minion      :1711][INFO    ][18716] Returning information for job: 20190313090423231946
2019-03-13 09:04:43,092 [salt.state       :300 ][INFO    ][16950] {'ret': True}
2019-03-13 09:04:43,092 [salt.state       :1951][INFO    ][16950] Completed state [maas.wait_for_machine_status] at time 09:04:43.092645 duration_in_ms=716722.786
2019-03-13 09:04:43,097 [salt.minion      :1711][INFO    ][16950] Returning information for job: 20190313085235976490
2019-03-13 09:04:56,852 [salt.utils.schedule:1377][INFO    ][6933] Running scheduled job: __mine_interval
2019-03-13 10:04:56,850 [salt.utils.schedule:1377][INFO    ][6933] Running scheduled job: __mine_interval
2019-03-13 10:42:29,152 [salt.minion      :1308][INFO    ][6933] User sudo_ubuntu Executing command cp.push_dir with jid 20190313104229131903
2019-03-13 10:42:29,221 [salt.minion      :1432][INFO    ][28762] Starting a new job with PID 28762
