2019-03-31 05:10:15,191 [salt.utils.decorators:613 ][WARNING ][2180] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-31 05:10:16,089 [salt.utils.decorators:613 ][WARNING ][2180] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-31 05:10:19,212 [salt.loaded.int.states.file:2298][WARNING ][2536] 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-31 05:10:45,144 [salt.state       :2022][WARNING ][2915] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-31 05:10:48,241 [salt.utils.decorators:613 ][WARNING ][2915] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-31 05:11:00,829 [salt.utils.decorators:613 ][WARNING ][2915] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-31 05:11:26,471 [salt.utils.decorators:613 ][WARNING ][2915] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-31 05:11:48,314 [salt.utils.decorators:613 ][WARNING ][2915] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-31 05:11:49,256 [salt.loaded.ext.module.maasng:1008][WARNING ][2915] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-03-31 05:11:49,256 [salt.loaded.ext.module.maasng:1011][WARNING ][2915] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-03-31 05:11:50,404 [salt.loaded.ext.module.maasng:1235][WARNING ][2915] Ignoring parameter vlan:0
2019-03-31 05:11:51,248 [salt.utils.decorators:613 ][WARNING ][2915] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-31 05:11:54,010 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5240] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-31 05:11:54,032 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5240] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-03-31 05:11:54,084 [salt.utils.parsers:1051][WARNING ][383] Minion received a SIGTERM. Exiting.
2019-03-31 05:11:55,200 [salt.cli.daemons :293 ][INFO    ][5312] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-03-31 05:11:55,318 [salt.cli.daemons :82  ][INFO    ][5312] Starting up the Salt Minion
2019-03-31 05:11:55,318 [salt.utils.event :1017][INFO    ][5312] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-03-31 05:11:56,389 [salt.minion      :976 ][INFO    ][5312] Creating minion process manager
2019-03-31 05:11:58,377 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5312] Executing command ['date', '+%z'] in directory '/root'
2019-03-31 05:11:58,399 [salt.utils.schedule:568 ][INFO    ][5312] Updating job settings for scheduled job: __mine_interval
2019-03-31 05:11:58,400 [salt.minion      :1108][INFO    ][5312] Added mine.update to scheduler
2019-03-31 05:11:58,405 [salt.minion      :1975][INFO    ][5312] Minion is starting as user 'root'
2019-03-31 05:11:58,417 [salt.minion      :2336][INFO    ][5312] Minion is ready to receive requests!
2019-03-31 05:12:02,280 [salt.utils.decorators:613 ][WARNING ][5247] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-31 05:12:02,344 [salt.loaded.ext.module.maas:412 ][WARNING ][5247] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-31 05:12:03,696 [salt.loaded.ext.module.maas:412 ][WARNING ][5247] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-31 05:12:05,025 [salt.loaded.ext.module.maas:412 ][WARNING ][5247] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-31 05:12:06,224 [salt.loaded.ext.module.maas:412 ][WARNING ][5247] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-31 05:12:07,026 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331051207014222
2019-03-31 05:12:07,043 [salt.minion      :1432][INFO    ][5619] Starting a new job with PID 5619
2019-03-31 05:12:07,065 [salt.minion      :1711][INFO    ][5619] Returning information for job: 20190331051207014222
2019-03-31 05:12:07,530 [salt.loaded.ext.module.maas:412 ][WARNING ][5247] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-31 05:12:39,801 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command state.apply with jid 20190331051239786396
2019-03-31 05:12:39,823 [salt.minion      :1432][INFO    ][5724] Starting a new job with PID 5724
2019-03-31 05:12:47,802 [salt.state       :915 ][INFO    ][5724] Loading fresh modules for state activity
2019-03-31 05:12:47,855 [salt.fileclient  :1219][INFO    ][5724] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-03-31 05:12:47,899 [salt.state       :1780][INFO    ][5724] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:12:47.899065
2019-03-31 05:12:47,899 [salt.state       :1813][INFO    ][5724] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-31 05:12:47,901 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5724] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-31 05:12:49,769 [salt.state       :300 ][INFO    ][5724] {'pid': 5731, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-31 05:12:49,770 [salt.state       :1951][INFO    ][5724] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:12:49.770520 duration_in_ms=1871.455
2019-03-31 05:12:49,772 [salt.state       :1780][INFO    ][5724] Running state [maas.wait_for_machine_status] at time 05:12:49.772798
2019-03-31 05:12:49,773 [salt.state       :1813][INFO    ][5724] Executing state module.run for [maas.wait_for_machine_status]
2019-03-31 05:12:49,773 [salt.utils.decorators:613 ][WARNING ][5724] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-31 05:12:50,426 [salt.loaded.ext.module.maas:1023][INFO    ][5724] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.35749197s left)
2019-03-31 05:12:54,885 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331051254872097
2019-03-31 05:12:54,908 [salt.minion      :1432][INFO    ][5783] Starting a new job with PID 5783
2019-03-31 05:12:54,929 [salt.minion      :1711][INFO    ][5783] Returning information for job: 20190331051254872097
2019-03-31 05:13:21,169 [salt.loaded.ext.module.maas:1023][INFO    ][5724] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.61463404s left)
2019-03-31 05:13:25,106 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331051325094537
2019-03-31 05:13:25,126 [salt.minion      :1432][INFO    ][5815] Starting a new job with PID 5815
2019-03-31 05:13:25,148 [salt.minion      :1711][INFO    ][5815] Returning information for job: 20190331051325094537
2019-03-31 05:13:51,978 [salt.loaded.ext.module.maas:1023][INFO    ][5724] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.80572009s left)
2019-03-31 05:13:55,189 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331051355181530
2019-03-31 05:13:55,202 [salt.minion      :1432][INFO    ][5882] Starting a new job with PID 5882
2019-03-31 05:13:55,222 [salt.minion      :1711][INFO    ][5882] Returning information for job: 20190331051355181530
2019-03-31 05:14:22,852 [salt.loaded.ext.module.maas:1023][INFO    ][5724] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.93160105s left)
2019-03-31 05:14:25,245 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331051425230869
2019-03-31 05:14:25,267 [salt.minion      :1432][INFO    ][5915] Starting a new job with PID 5915
2019-03-31 05:14:25,285 [salt.minion      :1711][INFO    ][5915] Returning information for job: 20190331051425230869
2019-03-31 05:14:53,860 [salt.loaded.ext.module.maas:1023][INFO    ][5724] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.92380691s left)
2019-03-31 05:14:55,298 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331051455281011
2019-03-31 05:14:55,319 [salt.minion      :1432][INFO    ][6009] Starting a new job with PID 6009
2019-03-31 05:14:55,340 [salt.minion      :1711][INFO    ][6009] Returning information for job: 20190331051455281011
2019-03-31 05:15:24,936 [salt.loaded.ext.module.maas:1023][INFO    ][5724] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.84733605s left)
2019-03-31 05:15:25,509 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331051525491963
2019-03-31 05:15:25,530 [salt.minion      :1432][INFO    ][6101] Starting a new job with PID 6101
2019-03-31 05:15:25,554 [salt.minion      :1711][INFO    ][6101] Returning information for job: 20190331051525491963
2019-03-31 05:15:55,618 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331051555603553
2019-03-31 05:15:55,641 [salt.minion      :1432][INFO    ][6400] Starting a new job with PID 6400
2019-03-31 05:15:55,662 [salt.minion      :1711][INFO    ][6400] Returning information for job: 20190331051555603553
2019-03-31 05:15:56,042 [salt.loaded.ext.module.maas:1023][INFO    ][5724] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.74151111s left)
2019-03-31 05:16:25,836 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331051625824054
2019-03-31 05:16:25,849 [salt.minion      :1432][INFO    ][6538] Starting a new job with PID 6538
2019-03-31 05:16:25,872 [salt.minion      :1711][INFO    ][6538] Returning information for job: 20190331051625824054
2019-03-31 05:16:27,204 [salt.loaded.ext.module.maas:1023][INFO    ][5724] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.579741s left)
2019-03-31 05:16:55,920 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331051655912616
2019-03-31 05:16:55,937 [salt.minion      :1432][INFO    ][6758] Starting a new job with PID 6758
2019-03-31 05:16:55,957 [salt.minion      :1711][INFO    ][6758] Returning information for job: 20190331051655912616
2019-03-31 05:16:58,989 [salt.loaded.ext.module.maas:1023][INFO    ][5724] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.79446602s left)
2019-03-31 05:17:26,013 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331051726000670
2019-03-31 05:17:26,036 [salt.minion      :1432][INFO    ][6860] Starting a new job with PID 6860
2019-03-31 05:17:26,059 [salt.minion      :1711][INFO    ][6860] Returning information for job: 20190331051726000670
2019-03-31 05:17:31,145 [salt.loaded.ext.module.maas:1023][INFO    ][5724] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.63888693s left)
2019-03-31 05:17:56,158 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331051756150717
2019-03-31 05:17:56,172 [salt.minion      :1432][INFO    ][7375] Starting a new job with PID 7375
2019-03-31 05:17:56,193 [salt.minion      :1711][INFO    ][7375] Returning information for job: 20190331051756150717
2019-03-31 05:18:03,319 [salt.loaded.ext.module.maas:1023][INFO    ][5724] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1186.46447706s left)
2019-03-31 05:18:26,229 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331051826216251
2019-03-31 05:18:26,252 [salt.minion      :1432][INFO    ][7402] Starting a new job with PID 7402
2019-03-31 05:18:26,275 [salt.minion      :1711][INFO    ][7402] Returning information for job: 20190331051826216251
2019-03-31 05:18:35,709 [salt.loaded.ext.module.maas:1023][INFO    ][5724] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1154.07410407s left)
2019-03-31 05:18:56,360 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331051856350749
2019-03-31 05:18:56,384 [salt.minion      :1432][INFO    ][7651] Starting a new job with PID 7651
2019-03-31 05:18:56,407 [salt.minion      :1711][INFO    ][7651] Returning information for job: 20190331051856350749
2019-03-31 05:19:08,366 [salt.state       :300 ][INFO    ][5724] {'ret': True}
2019-03-31 05:19:08,366 [salt.state       :1951][INFO    ][5724] Completed state [maas.wait_for_machine_status] at time 05:19:08.366351 duration_in_ms=378593.552
2019-03-31 05:19:08,372 [salt.minion      :1711][INFO    ][5724] Returning information for job: 20190331051239786396
2019-03-31 05:19:08,910 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command state.apply with jid 20190331051908898383
2019-03-31 05:19:08,925 [salt.minion      :1432][INFO    ][7692] Starting a new job with PID 7692
2019-03-31 05:19:16,832 [salt.state       :915 ][INFO    ][7692] Loading fresh modules for state activity
2019-03-31 05:19:16,890 [salt.fileclient  :1219][INFO    ][7692] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-03-31 05:19:16,983 [salt.state       :1780][INFO    ][7692] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:16.983606
2019-03-31 05:19:16,983 [salt.state       :1813][INFO    ][7692] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-31 05:19:16,985 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7692] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-31 05:19:18,753 [salt.state       :300 ][INFO    ][7692] {'pid': 7699, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-31 05:19:18,753 [salt.state       :1951][INFO    ][7692] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:18.753853 duration_in_ms=1770.247
2019-03-31 05:19:18,755 [salt.state       :1780][INFO    ][7692] Running state [maas_machines_storage_cmp002_lvm] at time 05:19:18.755840
2019-03-31 05:19:18,756 [salt.state       :1813][INFO    ][7692] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-31 05:19:19,905 [salt.loaded.ext.module.maasng:610 ][INFO    ][7692] nyncsb
2019-03-31 05:19:19,905 [salt.loaded.ext.module.maasng:626 ][INFO    ][7692] sda
2019-03-31 05:19:20,465 [salt.loaded.ext.module.maasng:361 ][INFO    ][7692] nyncsb
2019-03-31 05:19:20,554 [salt.loaded.ext.module.maasng:367 ][INFO    ][7692] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'nyncsb', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/nyncsb/blockdevices/1/', u'type': u'physical', u'id': 1, u'partitions': [{u'uuid': u'be569d1e-3eb1-40e7-93a4-d4f02fdb02e7', u'resource_uri': u'/MAAS/api/2.0/nodes/nyncsb/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nyncsb', u'filesystem': {u'uuid': u'21ca98d4-08ea-4b3f-8064-4f972219afe0', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 800101236736}]}, {u'size': 800097042432, u'model': None, u'block_size': 4096, u'uuid': u'2d3fb33d-16a9-4fdd-9c9f-e35c3ce3b817', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'nyncsb', u'partition_table_type': None, u'filesystem': {u'uuid': u'b5b7d23b-21b7-4da7-85bc-a58c881eb004', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/nyncsb/blockdevices/3/', u'type': u'virtual', u'id': 3, u'partitions': []}]
2019-03-31 05:19:20,555 [salt.loaded.ext.module.maasng:632 ][INFO    ][7692] vgroot
2019-03-31 05:19:20,555 [salt.loaded.ext.module.maasng:635 ][INFO    ][7692] lvroot
2019-03-31 05:19:20,555 [salt.loaded.ext.module.maasng:639 ][INFO    ][7692] 107374182400
2019-03-31 05:19:21,098 [salt.loaded.ext.module.maasng:645 ][INFO    ][7692] {u'hwe_kernel': u'', u'status_action': u'', u'swap_size': None, u'memory_test_status': -1, u'boot_interface': {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'4ycbte', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'4ycbte', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], 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'primary_rack': u'4ycbte', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'nyncsb', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/nyncsb/interfaces/4/'}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/nyncsb/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'ba04d440-5915-4e12-a26b-16cf22f21345', u'resource_uri': u'/MAAS/api/2.0/nodes/nyncsb/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nyncsb', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'795163b8-346b-4ea8-be6e-cff67350ed32', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'nyncsb', 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'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'current_commissioning_result_id': 2, u'hostname': u'cmp002', u'storage': 800109.715456, u'owner_data': {}, u'system_id': u'nyncsb', 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'resource_uri': u'/MAAS/api/2.0/nodes/nyncsb/blockdevices/11/', u'uuid': u'c1f21821-5170-4585-8a35-ee4bc6261ae4', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'ea91932c-3049-48de-87a3-9d4f5f773cb1', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'nyncsb', 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': 11, u'serial': None, u'name': u'vgroot-lvroot'}], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/nyncsb/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'ba04d440-5915-4e12-a26b-16cf22f21345', u'resource_uri': u'/MAAS/api/2.0/nodes/nyncsb/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nyncsb', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'795163b8-346b-4ea8-be6e-cff67350ed32', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'nyncsb', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/nyncsb/blockdevices/11/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'c1f21821-5170-4585-8a35-ee4bc6261ae4', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'nyncsb', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'ea91932c-3049-48de-87a3-9d4f5f773cb1', u'label': u'root'}, 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'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/nyncsb/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'ba04d440-5915-4e12-a26b-16cf22f21345', u'resource_uri': u'/MAAS/api/2.0/nodes/nyncsb/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nyncsb', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'795163b8-346b-4ea8-be6e-cff67350ed32', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'nyncsb', 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'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'nyncsb', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'4ycbte', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'4ycbte', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], 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'primary_rack': u'4ycbte', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'nyncsb', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/nyncsb/interfaces/4/'}, {u'discovered': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'nyncsb', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/nyncsb/interfaces/15/'}, {u'discovered': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'nyncsb', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/nyncsb/interfaces/13/'}, {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'primary_rack': u'4ycbte', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'4ycbte', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'4ycbte', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'nyncsb', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/nyncsb/interfaces/14/'}, {u'discovered': None, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 20}], 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'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'nyncsb', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/nyncsb/interfaces/11/'}, {u'discovered': None, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 21}], 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'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'nyncsb', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/nyncsb/interfaces/12/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/nyncsb/'}
2019-03-31 05:19:21,100 [salt.state       :300 ][INFO    ][7692] {'new': {'storage_layout': 'lvm'}}
2019-03-31 05:19:21,101 [salt.state       :1951][INFO    ][7692] Completed state [maas_machines_storage_cmp002_lvm] at time 05:19:21.101052 duration_in_ms=2345.212
2019-03-31 05:19:21,101 [salt.state       :1780][INFO    ][7692] Running state [maas_machines_storage_cmp001_lvm] at time 05:19:21.101392
2019-03-31 05:19:21,101 [salt.state       :1813][INFO    ][7692] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-31 05:19:22,067 [salt.loaded.ext.module.maasng:610 ][INFO    ][7692] aqpfqx
2019-03-31 05:19:22,067 [salt.loaded.ext.module.maasng:626 ][INFO    ][7692] sda
2019-03-31 05:19:22,572 [salt.loaded.ext.module.maasng:361 ][INFO    ][7692] aqpfqx
2019-03-31 05:19:22,657 [salt.loaded.ext.module.maasng:367 ][INFO    ][7692] [{u'resource_uri': u'/MAAS/api/2.0/nodes/aqpfqx/blockdevices/2/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'9ab0bf8d-e46b-4a46-a235-8060d4036a5d', u'resource_uri': u'/MAAS/api/2.0/nodes/aqpfqx/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'aqpfqx', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'e1d0af5a-19f8-4694-9026-6289b2f93cd5', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'aqpfqx', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/aqpfqx/blockdevices/4/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'69c2cf9c-ed3a-441b-b9ee-b12d3a4c630b', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'aqpfqx', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'2ead5aa8-4eb1-43b3-aa50-2a4e13be921b', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'serial': None, u'size': 800097042432}]
2019-03-31 05:19:22,658 [salt.loaded.ext.module.maasng:632 ][INFO    ][7692] vgroot
2019-03-31 05:19:22,658 [salt.loaded.ext.module.maasng:635 ][INFO    ][7692] lvroot
2019-03-31 05:19:22,658 [salt.loaded.ext.module.maasng:639 ][INFO    ][7692] 107374182400
2019-03-31 05:19:23,144 [salt.loaded.ext.module.maasng:645 ][INFO    ][7692] {u'hwe_kernel': u'', u'status_action': u'', u'swap_size': None, u'memory_test_status': -1, u'boot_interface': {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'4ycbte', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'4ycbte', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], 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'primary_rack': u'4ycbte', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'aqpfqx', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/aqpfqx/interfaces/5/'}, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], 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'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/aqpfqx/blockdevices/2/', u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'75af86a5-bd26-442e-a0f1-f515c72781dd', u'resource_uri': u'/MAAS/api/2.0/nodes/aqpfqx/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'aqpfqx', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'5d7af1db-2893-43ef-b5b7-b629f743aec7', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'aqpfqx', 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'name': u'sda'}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'current_commissioning_result_id': 4, u'hostname': u'cmp001', u'storage': 800109.715456, u'owner_data': {}, u'system_id': u'aqpfqx', 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'resource_uri': u'/MAAS/api/2.0/nodes/aqpfqx/blockdevices/12/', u'uuid': u'a806dd50-7ddd-42f4-a07b-a58328630dac', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'734bd7f1-7bc9-4d21-a64b-aa32c837fc4e', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'aqpfqx', 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'name': u'vgroot-lvroot'}], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/aqpfqx/blockdevices/2/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'75af86a5-bd26-442e-a0f1-f515c72781dd', u'resource_uri': u'/MAAS/api/2.0/nodes/aqpfqx/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'aqpfqx', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'5d7af1db-2893-43ef-b5b7-b629f743aec7', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'aqpfqx', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/aqpfqx/blockdevices/12/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'a806dd50-7ddd-42f4-a07b-a58328630dac', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'aqpfqx', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'734bd7f1-7bc9-4d21-a64b-aa32c837fc4e', u'label': u'root'}, 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': 12, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/aqpfqx/blockdevices/2/', u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'75af86a5-bd26-442e-a0f1-f515c72781dd', u'resource_uri': u'/MAAS/api/2.0/nodes/aqpfqx/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'aqpfqx', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'5d7af1db-2893-43ef-b5b7-b629f743aec7', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'aqpfqx', 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'name': u'sda'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'aqpfqx', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'4ycbte', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'4ycbte', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], 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'primary_rack': u'4ycbte', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'aqpfqx', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/aqpfqx/interfaces/5/'}, {u'discovered': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'aqpfqx', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/aqpfqx/interfaces/19/'}, {u'discovered': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'aqpfqx', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/aqpfqx/interfaces/20/'}, {u'discovered': None, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 25}], 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'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'aqpfqx', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/aqpfqx/interfaces/16/'}, {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'primary_rack': u'4ycbte', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'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'primary_rack': u'4ycbte', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 26}], 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'primary_rack': u'4ycbte', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'aqpfqx', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/aqpfqx/interfaces/17/'}, {u'discovered': None, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 27}], 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'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'aqpfqx', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/aqpfqx/interfaces/18/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/aqpfqx/'}
2019-03-31 05:19:23,146 [salt.state       :300 ][INFO    ][7692] {'new': {'storage_layout': 'lvm'}}
2019-03-31 05:19:23,146 [salt.state       :1951][INFO    ][7692] Completed state [maas_machines_storage_cmp001_lvm] at time 05:19:23.146191 duration_in_ms=2044.799
2019-03-31 05:19:23,149 [salt.minion      :1711][INFO    ][7692] Returning information for job: 20190331051908898383
2019-03-31 05:19:23,669 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command state.apply with jid 20190331051923660439
2019-03-31 05:19:23,685 [salt.minion      :1432][INFO    ][7723] Starting a new job with PID 7723
2019-03-31 05:19:24,769 [salt.state       :915 ][INFO    ][7723] Loading fresh modules for state activity
2019-03-31 05:19:24,815 [salt.fileclient  :1219][INFO    ][7723] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-03-31 05:19:24,856 [salt.state       :1780][INFO    ][7723] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:24.856851
2019-03-31 05:19:24,857 [salt.state       :1813][INFO    ][7723] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-31 05:19:24,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7723] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-31 05:19:26,696 [salt.state       :300 ][INFO    ][7723] {'pid': 7730, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-31 05:19:26,697 [salt.state       :1951][INFO    ][7723] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:26.697703 duration_in_ms=1840.851
2019-03-31 05:19:26,700 [salt.state       :1780][INFO    ][7723] Running state [maas.deploy_machines] at time 05:19:26.700859
2019-03-31 05:19:26,701 [salt.state       :1813][INFO    ][7723] Executing state module.run for [maas.deploy_machines]
2019-03-31 05:19:26,704 [salt.utils.decorators:613 ][WARNING ][7723] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-31 05:19:27,261 [salt.loaded.ext.module.maas:684 ][INFO    ][7723] deploymachines hwe_kernel=hwe-16.04 system_id=nyncsb distro_series=xenial
2019-03-31 05:19:29,664 [salt.loaded.ext.module.maas:684 ][INFO    ][7723] deploymachines hwe_kernel=hwe-16.04 system_id=aqpfqx distro_series=xenial
2019-03-31 05:19:31,973 [salt.loaded.ext.module.maas:684 ][INFO    ][7723] deploymachines hwe_kernel=hwe-16.04 system_id=f7f3w8 distro_series=xenial
2019-03-31 05:19:34,322 [salt.loaded.ext.module.maas:684 ][INFO    ][7723] deploymachines hwe_kernel=hwe-16.04 system_id=s64dds distro_series=xenial
2019-03-31 05:19:36,661 [salt.loaded.ext.module.maas:684 ][INFO    ][7723] deploymachines hwe_kernel=hwe-16.04 system_id=qwsngn distro_series=xenial
2019-03-31 05:19:38,687 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331051938680456
2019-03-31 05:19:38,706 [salt.minion      :1432][INFO    ][8001] Starting a new job with PID 8001
2019-03-31 05:19:38,726 [salt.minion      :1711][INFO    ][8001] Returning information for job: 20190331051938680456
2019-03-31 05:19:39,048 [salt.state       :300 ][INFO    ][7723] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-31 05:19:39,048 [salt.state       :1951][INFO    ][7723] Completed state [maas.deploy_machines] at time 05:19:39.048470 duration_in_ms=12347.611
2019-03-31 05:19:39,053 [salt.minion      :1711][INFO    ][7723] Returning information for job: 20190331051923660439
2019-03-31 05:19:39,585 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command state.apply with jid 20190331051939575819
2019-03-31 05:19:39,608 [salt.minion      :1432][INFO    ][8018] Starting a new job with PID 8018
2019-03-31 05:19:47,656 [salt.state       :915 ][INFO    ][8018] Loading fresh modules for state activity
2019-03-31 05:19:47,706 [salt.fileclient  :1219][INFO    ][8018] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-03-31 05:19:47,752 [salt.state       :1780][INFO    ][8018] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:47.752481
2019-03-31 05:19:47,752 [salt.state       :1813][INFO    ][8018] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-31 05:19:47,754 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8018] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-31 05:19:49,675 [salt.state       :300 ][INFO    ][8018] {'pid': 8029, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-31 05:19:49,676 [salt.state       :1951][INFO    ][8018] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:49.675983 duration_in_ms=1923.502
2019-03-31 05:19:49,677 [salt.state       :1780][INFO    ][8018] Running state [maas.wait_for_machine_status] at time 05:19:49.677893
2019-03-31 05:19:49,678 [salt.state       :1813][INFO    ][8018] Executing state module.run for [maas.wait_for_machine_status]
2019-03-31 05:19:49,678 [salt.utils.decorators:613 ][WARNING ][8018] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-31 05:19:52,482 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.20450211s left)
2019-03-31 05:19:54,645 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331051954628178
2019-03-31 05:19:54,666 [salt.minion      :1432][INFO    ][8081] Starting a new job with PID 8081
2019-03-31 05:19:54,686 [salt.minion      :1711][INFO    ][8081] Returning information for job: 20190331051954628178
2019-03-31 05:20:24,697 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331052024684917
2019-03-31 05:20:24,716 [salt.minion      :1432][INFO    ][8114] Starting a new job with PID 8114
2019-03-31 05:20:24,739 [salt.minion      :1711][INFO    ][8114] Returning information for job: 20190331052024684917
2019-03-31 05:20:25,320 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.36674094s left)
2019-03-31 05:20:54,773 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331052054757718
2019-03-31 05:20:54,798 [salt.minion      :1432][INFO    ][8195] Starting a new job with PID 8195
2019-03-31 05:20:54,818 [salt.minion      :1711][INFO    ][8195] Returning information for job: 20190331052054757718
2019-03-31 05:20:58,241 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.44601202s left)
2019-03-31 05:21:24,840 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331052124826908
2019-03-31 05:21:24,861 [salt.minion      :1432][INFO    ][8239] Starting a new job with PID 8239
2019-03-31 05:21:24,884 [salt.minion      :1711][INFO    ][8239] Returning information for job: 20190331052124826908
2019-03-31 05:21:31,065 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.62188601s left)
2019-03-31 05:21:54,888 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331052154873942
2019-03-31 05:21:54,911 [salt.minion      :1432][INFO    ][8307] Starting a new job with PID 8307
2019-03-31 05:21:54,934 [salt.minion      :1711][INFO    ][8307] Returning information for job: 20190331052154873942
2019-03-31 05:22:03,808 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.87858796s left)
2019-03-31 05:22:25,090 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331052225074879
2019-03-31 05:22:25,111 [salt.minion      :1432][INFO    ][8361] Starting a new job with PID 8361
2019-03-31 05:22:25,130 [salt.minion      :1711][INFO    ][8361] Returning information for job: 20190331052225074879
2019-03-31 05:22:36,512 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.17465115s left)
2019-03-31 05:22:55,137 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331052255128073
2019-03-31 05:22:55,156 [salt.minion      :1432][INFO    ][8554] Starting a new job with PID 8554
2019-03-31 05:22:55,178 [salt.minion      :1711][INFO    ][8554] Returning information for job: 20190331052255128073
2019-03-31 05:23:09,306 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2050.38098311s left)
2019-03-31 05:23:25,232 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331052325218092
2019-03-31 05:23:25,253 [salt.minion      :1432][INFO    ][8619] Starting a new job with PID 8619
2019-03-31 05:23:25,277 [salt.minion      :1711][INFO    ][8619] Returning information for job: 20190331052325218092
2019-03-31 05:23:42,210 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2017.476897s left)
2019-03-31 05:23:55,321 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331052355313053
2019-03-31 05:23:55,338 [salt.minion      :1432][INFO    ][8958] Starting a new job with PID 8958
2019-03-31 05:23:55,357 [salt.minion      :1711][INFO    ][8958] Returning information for job: 20190331052355313053
2019-03-31 05:24:15,168 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1984.51820707s left)
2019-03-31 05:24:25,400 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331052425385660
2019-03-31 05:24:25,423 [salt.minion      :1432][INFO    ][9047] Starting a new job with PID 9047
2019-03-31 05:24:25,445 [salt.minion      :1711][INFO    ][9047] Returning information for job: 20190331052425385660
2019-03-31 05:24:48,297 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1951.38991499s left)
2019-03-31 05:24:55,556 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331052455544654
2019-03-31 05:24:55,572 [salt.minion      :1432][INFO    ][9424] Starting a new job with PID 9424
2019-03-31 05:24:55,592 [salt.minion      :1711][INFO    ][9424] Returning information for job: 20190331052455544654
2019-03-31 05:25:21,209 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1918.47715902s left)
2019-03-31 05:25:25,764 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331052525754489
2019-03-31 05:25:25,781 [salt.minion      :1432][INFO    ][9479] Starting a new job with PID 9479
2019-03-31 05:25:25,799 [salt.minion      :1711][INFO    ][9479] Returning information for job: 20190331052525754489
2019-03-31 05:25:54,269 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1885.41738605s left)
2019-03-31 05:25:55,917 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331052555907929
2019-03-31 05:25:55,933 [salt.minion      :1432][INFO    ][9885] Starting a new job with PID 9885
2019-03-31 05:25:55,955 [salt.minion      :1711][INFO    ][9885] Returning information for job: 20190331052555907929
2019-03-31 05:26:26,009 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331052625999265
2019-03-31 05:26:26,024 [salt.minion      :1432][INFO    ][9924] Starting a new job with PID 9924
2019-03-31 05:26:26,047 [salt.minion      :1711][INFO    ][9924] Returning information for job: 20190331052625999265
2019-03-31 05:26:27,224 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1852.46273303s left)
2019-03-31 05:26:56,166 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331052656155288
2019-03-31 05:26:56,180 [salt.minion      :1432][INFO    ][10273] Starting a new job with PID 10273
2019-03-31 05:26:56,201 [salt.minion      :1711][INFO    ][10273] Returning information for job: 20190331052656155288
2019-03-31 05:27:00,197 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1819.48926401s left)
2019-03-31 05:27:26,252 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331052726241577
2019-03-31 05:27:26,277 [salt.minion      :1432][INFO    ][10302] Starting a new job with PID 10302
2019-03-31 05:27:26,300 [salt.minion      :1711][INFO    ][10302] Returning information for job: 20190331052726241577
2019-03-31 05:27:33,136 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1786.55069804s left)
2019-03-31 05:27:56,442 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331052756429242
2019-03-31 05:27:56,460 [salt.minion      :1432][INFO    ][10696] Starting a new job with PID 10696
2019-03-31 05:27:56,483 [salt.minion      :1711][INFO    ][10696] Returning information for job: 20190331052756429242
2019-03-31 05:28:05,994 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1753.69311094s left)
2019-03-31 05:28:26,569 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331052826554776
2019-03-31 05:28:26,589 [salt.minion      :1432][INFO    ][10754] Starting a new job with PID 10754
2019-03-31 05:28:26,616 [salt.minion      :1711][INFO    ][10754] Returning information for job: 20190331052826554776
2019-03-31 05:28:38,514 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1721.17290807s left)
2019-03-31 05:28:56,791 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331052856777618
2019-03-31 05:28:56,814 [salt.minion      :1432][INFO    ][10982] Starting a new job with PID 10982
2019-03-31 05:28:56,832 [salt.minion      :1711][INFO    ][10982] Returning information for job: 20190331052856777618
2019-03-31 05:29:11,323 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1688.36383915s left)
2019-03-31 05:29:26,850 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331052926837929
2019-03-31 05:29:26,868 [salt.minion      :1432][INFO    ][11018] Starting a new job with PID 11018
2019-03-31 05:29:26,890 [salt.minion      :1711][INFO    ][11018] Returning information for job: 20190331052926837929
2019-03-31 05:29:43,834 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1655.85272908s left)
2019-03-31 05:29:56,945 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331052956928905
2019-03-31 05:29:56,964 [salt.minion      :1432][INFO    ][11203] Starting a new job with PID 11203
2019-03-31 05:29:56,987 [salt.minion      :1711][INFO    ][11203] Returning information for job: 20190331052956928905
2019-03-31 05:30:16,765 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1622.9216361s left)
2019-03-31 05:30:26,976 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331053026959961
2019-03-31 05:30:26,998 [salt.minion      :1432][INFO    ][11224] Starting a new job with PID 11224
2019-03-31 05:30:27,020 [salt.minion      :1711][INFO    ][11224] Returning information for job: 20190331053026959961
2019-03-31 05:30:49,546 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1590.14082694s left)
2019-03-31 05:30:57,024 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331053057013890
2019-03-31 05:30:57,045 [salt.minion      :1432][INFO    ][11448] Starting a new job with PID 11448
2019-03-31 05:30:57,067 [salt.minion      :1711][INFO    ][11448] Returning information for job: 20190331053057013890
2019-03-31 05:31:22,132 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1557.55457711s left)
2019-03-31 05:31:27,057 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331053127048697
2019-03-31 05:31:27,071 [salt.minion      :1432][INFO    ][11471] Starting a new job with PID 11471
2019-03-31 05:31:27,102 [salt.minion      :1711][INFO    ][11471] Returning information for job: 20190331053127048697
2019-03-31 05:31:55,038 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1524.64851904s left)
2019-03-31 05:31:57,236 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331053157223515
2019-03-31 05:31:57,254 [salt.minion      :1432][INFO    ][11596] Starting a new job with PID 11596
2019-03-31 05:31:57,277 [salt.minion      :1711][INFO    ][11596] Returning information for job: 20190331053157223515
2019-03-31 05:32:27,265 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331053227255852
2019-03-31 05:32:27,285 [salt.minion      :1432][INFO    ][11654] Starting a new job with PID 11654
2019-03-31 05:32:27,311 [salt.minion      :1711][INFO    ][11654] Returning information for job: 20190331053227255852
2019-03-31 05:32:28,041 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1491.64607215s left)
2019-03-31 05:32:57,493 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331053257476269
2019-03-31 05:32:57,514 [salt.minion      :1432][INFO    ][11962] Starting a new job with PID 11962
2019-03-31 05:32:57,538 [salt.minion      :1711][INFO    ][11962] Returning information for job: 20190331053257476269
2019-03-31 05:33:00,953 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1458.73401403s left)
2019-03-31 05:33:27,707 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331053327693294
2019-03-31 05:33:27,728 [salt.minion      :1432][INFO    ][11984] Starting a new job with PID 11984
2019-03-31 05:33:27,756 [salt.minion      :1711][INFO    ][11984] Returning information for job: 20190331053327693294
2019-03-31 05:33:33,974 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1425.71311307s left)
2019-03-31 05:33:57,901 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331053357890553
2019-03-31 05:33:57,923 [salt.minion      :1432][INFO    ][12063] Starting a new job with PID 12063
2019-03-31 05:33:57,947 [salt.minion      :1711][INFO    ][12063] Returning information for job: 20190331053357890553
2019-03-31 05:34:06,761 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1392.92579699s left)
2019-03-31 05:34:28,073 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331053428057150
2019-03-31 05:34:28,096 [salt.minion      :1432][INFO    ][12083] Starting a new job with PID 12083
2019-03-31 05:34:28,117 [salt.minion      :1711][INFO    ][12083] Returning information for job: 20190331053428057150
2019-03-31 05:34:39,506 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1360.18078017s left)
2019-03-31 05:34:58,294 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331053458279900
2019-03-31 05:34:58,345 [salt.minion      :1432][INFO    ][12164] Starting a new job with PID 12164
2019-03-31 05:34:58,375 [salt.minion      :1711][INFO    ][12164] Returning information for job: 20190331053458279900
2019-03-31 05:35:12,503 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1327.18386507s left)
2019-03-31 05:35:28,513 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331053528506174
2019-03-31 05:35:28,537 [salt.minion      :1432][INFO    ][12184] Starting a new job with PID 12184
2019-03-31 05:35:28,565 [salt.minion      :1711][INFO    ][12184] Returning information for job: 20190331053528506174
2019-03-31 05:35:45,217 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1294.46938205s left)
2019-03-31 05:35:58,617 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331053558606131
2019-03-31 05:35:58,638 [salt.minion      :1432][INFO    ][12272] Starting a new job with PID 12272
2019-03-31 05:35:58,661 [salt.minion      :1711][INFO    ][12272] Returning information for job: 20190331053558606131
2019-03-31 05:36:17,864 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1261.82264209s left)
2019-03-31 05:36:28,674 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331053628661841
2019-03-31 05:36:28,699 [salt.minion      :1432][INFO    ][12292] Starting a new job with PID 12292
2019-03-31 05:36:28,724 [salt.minion      :1711][INFO    ][12292] Returning information for job: 20190331053628661841
2019-03-31 05:36:50,711 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1228.97565699s left)
2019-03-31 05:36:58,741 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331053658728827
2019-03-31 05:36:58,763 [salt.minion      :1432][INFO    ][12382] Starting a new job with PID 12382
2019-03-31 05:36:58,792 [salt.minion      :1711][INFO    ][12382] Returning information for job: 20190331053658728827
2019-03-31 05:37:23,488 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1196.19821501s left)
2019-03-31 05:37:28,826 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331053728817004
2019-03-31 05:37:28,848 [salt.minion      :1432][INFO    ][12402] Starting a new job with PID 12402
2019-03-31 05:37:28,872 [salt.minion      :1711][INFO    ][12402] Returning information for job: 20190331053728817004
2019-03-31 05:37:56,101 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1163.58525014s left)
2019-03-31 05:37:58,918 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331053758910715
2019-03-31 05:37:58,932 [salt.minion      :1432][INFO    ][12488] Starting a new job with PID 12488
2019-03-31 05:37:58,954 [salt.minion      :1711][INFO    ][12488] Returning information for job: 20190331053758910715
2019-03-31 05:38:29,003 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331053828994045
2019-03-31 05:38:29,018 [salt.minion      :1432][INFO    ][12510] Starting a new job with PID 12510
2019-03-31 05:38:29,041 [salt.minion      :1711][INFO    ][12510] Returning information for job: 20190331053828994045
2019-03-31 05:38:29,113 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1130.57397509s left)
2019-03-31 05:38:59,116 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331053859101185
2019-03-31 05:38:59,136 [salt.minion      :1432][INFO    ][12586] Starting a new job with PID 12586
2019-03-31 05:38:59,164 [salt.minion      :1711][INFO    ][12586] Returning information for job: 20190331053859101185
2019-03-31 05:39:01,862 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1097.82458901s left)
2019-03-31 05:39:29,226 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331053929210679
2019-03-31 05:39:29,243 [salt.minion      :1432][INFO    ][12606] Starting a new job with PID 12606
2019-03-31 05:39:29,269 [salt.minion      :1711][INFO    ][12606] Returning information for job: 20190331053929210679
2019-03-31 05:39:34,585 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1065.10176396s left)
2019-03-31 05:39:59,375 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331053959365357
2019-03-31 05:39:59,398 [salt.minion      :1432][INFO    ][12687] Starting a new job with PID 12687
2019-03-31 05:39:59,422 [salt.minion      :1711][INFO    ][12687] Returning information for job: 20190331053959365357
2019-03-31 05:40:07,244 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1032.4428761s left)
2019-03-31 05:40:29,461 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331054029446298
2019-03-31 05:40:29,484 [salt.minion      :1432][INFO    ][12707] Starting a new job with PID 12707
2019-03-31 05:40:29,508 [salt.minion      :1711][INFO    ][12707] Returning information for job: 20190331054029446298
2019-03-31 05:40:39,883 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (999.803483963s left)
2019-03-31 05:40:59,653 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331054059645394
2019-03-31 05:40:59,673 [salt.minion      :1432][INFO    ][12812] Starting a new job with PID 12812
2019-03-31 05:40:59,698 [salt.minion      :1711][INFO    ][12812] Returning information for job: 20190331054059645394
2019-03-31 05:41:12,494 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (967.193134069s left)
2019-03-31 05:41:29,794 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331054129779908
2019-03-31 05:41:29,815 [salt.minion      :1432][INFO    ][12836] Starting a new job with PID 12836
2019-03-31 05:41:29,840 [salt.minion      :1711][INFO    ][12836] Returning information for job: 20190331054129779908
2019-03-31 05:41:45,253 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (934.433861017s left)
2019-03-31 05:42:00,020 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331054200008831
2019-03-31 05:42:00,043 [salt.minion      :1432][INFO    ][12919] Starting a new job with PID 12919
2019-03-31 05:42:00,068 [salt.minion      :1711][INFO    ][12919] Returning information for job: 20190331054200008831
2019-03-31 05:42:18,233 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (901.453362942s left)
2019-03-31 05:42:30,210 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331054230198294
2019-03-31 05:42:30,230 [salt.minion      :1432][INFO    ][12941] Starting a new job with PID 12941
2019-03-31 05:42:30,256 [salt.minion      :1711][INFO    ][12941] Returning information for job: 20190331054230198294
2019-03-31 05:42:51,081 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (868.605453968s left)
2019-03-31 05:43:00,260 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331054300244653
2019-03-31 05:43:00,284 [salt.minion      :1432][INFO    ][13022] Starting a new job with PID 13022
2019-03-31 05:43:00,309 [salt.minion      :1711][INFO    ][13022] Returning information for job: 20190331054300244653
2019-03-31 05:43:23,767 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (835.91960597s left)
2019-03-31 05:43:30,468 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331054330460418
2019-03-31 05:43:30,491 [salt.minion      :1432][INFO    ][13042] Starting a new job with PID 13042
2019-03-31 05:43:30,514 [salt.minion      :1711][INFO    ][13042] Returning information for job: 20190331054330460418
2019-03-31 05:43:56,532 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (803.15476799s left)
2019-03-31 05:44:00,526 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331054400509952
2019-03-31 05:44:00,547 [salt.minion      :1432][INFO    ][13125] Starting a new job with PID 13125
2019-03-31 05:44:00,572 [salt.minion      :1711][INFO    ][13125] Returning information for job: 20190331054400509952
2019-03-31 05:44:29,174 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (770.512459993s left)
2019-03-31 05:44:30,609 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331054430602182
2019-03-31 05:44:30,624 [salt.minion      :1432][INFO    ][13145] Starting a new job with PID 13145
2019-03-31 05:44:30,646 [salt.minion      :1711][INFO    ][13145] Returning information for job: 20190331054430602182
2019-03-31 05:45:00,653 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331054500644551
2019-03-31 05:45:00,677 [salt.minion      :1432][INFO    ][13227] Starting a new job with PID 13227
2019-03-31 05:45:00,702 [salt.minion      :1711][INFO    ][13227] Returning information for job: 20190331054500644551
2019-03-31 05:45:02,014 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (737.673089981s left)
2019-03-31 05:45:30,782 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331054530769616
2019-03-31 05:45:30,798 [salt.minion      :1432][INFO    ][13244] Starting a new job with PID 13244
2019-03-31 05:45:30,823 [salt.minion      :1711][INFO    ][13244] Returning information for job: 20190331054530769616
2019-03-31 05:45:34,802 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (704.884576082s left)
2019-03-31 05:46:00,848 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331054600836103
2019-03-31 05:46:00,868 [salt.minion      :1432][INFO    ][13326] Starting a new job with PID 13326
2019-03-31 05:46:00,893 [salt.minion      :1711][INFO    ][13326] Returning information for job: 20190331054600836103
2019-03-31 05:46:07,438 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (672.24830699s left)
2019-03-31 05:46:31,006 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331054630991900
2019-03-31 05:46:31,028 [salt.minion      :1432][INFO    ][13350] Starting a new job with PID 13350
2019-03-31 05:46:31,052 [salt.minion      :1711][INFO    ][13350] Returning information for job: 20190331054630991900
2019-03-31 05:46:40,187 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (639.49999094s left)
2019-03-31 05:47:01,118 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331054701110015
2019-03-31 05:47:01,139 [salt.minion      :1432][INFO    ][13433] Starting a new job with PID 13433
2019-03-31 05:47:01,163 [salt.minion      :1711][INFO    ][13433] Returning information for job: 20190331054701110015
2019-03-31 05:47:12,948 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (606.738471031s left)
2019-03-31 05:47:31,297 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331054731285550
2019-03-31 05:47:31,318 [salt.minion      :1432][INFO    ][13455] Starting a new job with PID 13455
2019-03-31 05:47:31,344 [salt.minion      :1711][INFO    ][13455] Returning information for job: 20190331054731285550
2019-03-31 05:47:45,744 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (573.942152977s left)
2019-03-31 05:48:01,450 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331054801441426
2019-03-31 05:48:01,472 [salt.minion      :1432][INFO    ][13536] Starting a new job with PID 13536
2019-03-31 05:48:01,497 [salt.minion      :1711][INFO    ][13536] Returning information for job: 20190331054801441426
2019-03-31 05:48:18,535 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (541.151710033s left)
2019-03-31 05:48:31,510 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331054831496947
2019-03-31 05:48:31,532 [salt.minion      :1432][INFO    ][13556] Starting a new job with PID 13556
2019-03-31 05:48:31,555 [salt.minion      :1711][INFO    ][13556] Returning information for job: 20190331054831496947
2019-03-31 05:48:51,265 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (508.422090054s left)
2019-03-31 05:49:01,724 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331054901707958
2019-03-31 05:49:01,746 [salt.minion      :1432][INFO    ][13639] Starting a new job with PID 13639
2019-03-31 05:49:01,770 [salt.minion      :1711][INFO    ][13639] Returning information for job: 20190331054901707958
2019-03-31 05:49:23,865 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (475.821958065s left)
2019-03-31 05:49:31,794 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331054931780170
2019-03-31 05:49:31,813 [salt.minion      :1432][INFO    ][13659] Starting a new job with PID 13659
2019-03-31 05:49:31,842 [salt.minion      :1711][INFO    ][13659] Returning information for job: 20190331054931780170
2019-03-31 05:49:56,609 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (443.077571154s left)
2019-03-31 05:50:01,829 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331055001820394
2019-03-31 05:50:01,845 [salt.minion      :1432][INFO    ][13742] Starting a new job with PID 13742
2019-03-31 05:50:01,871 [salt.minion      :1711][INFO    ][13742] Returning information for job: 20190331055001820394
2019-03-31 05:50:29,366 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (410.320574999s left)
2019-03-31 05:50:31,928 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331055031914527
2019-03-31 05:50:31,949 [salt.minion      :1432][INFO    ][13764] Starting a new job with PID 13764
2019-03-31 05:50:31,975 [salt.minion      :1711][INFO    ][13764] Returning information for job: 20190331055031914527
2019-03-31 05:51:02,045 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (377.641324043s left)
2019-03-31 05:51:02,058 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331055102044953
2019-03-31 05:51:02,077 [salt.minion      :1432][INFO    ][13994] Starting a new job with PID 13994
2019-03-31 05:51:02,104 [salt.minion      :1711][INFO    ][13994] Returning information for job: 20190331055102044953
2019-03-31 05:51:32,142 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331055132133030
2019-03-31 05:51:32,160 [salt.minion      :1432][INFO    ][14011] Starting a new job with PID 14011
2019-03-31 05:51:32,185 [salt.minion      :1711][INFO    ][14011] Returning information for job: 20190331055132133030
2019-03-31 05:51:34,796 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (344.890865088s left)
2019-03-31 05:52:02,301 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331055202287524
2019-03-31 05:52:02,323 [salt.minion      :1432][INFO    ][14094] Starting a new job with PID 14094
2019-03-31 05:52:02,348 [salt.minion      :1711][INFO    ][14094] Returning information for job: 20190331055202287524
2019-03-31 05:52:07,602 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (312.084912062s left)
2019-03-31 05:52:32,383 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331055232371233
2019-03-31 05:52:32,405 [salt.minion      :1432][INFO    ][14114] Starting a new job with PID 14114
2019-03-31 05:52:32,430 [salt.minion      :1711][INFO    ][14114] Returning information for job: 20190331055232371233
2019-03-31 05:52:40,325 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (279.361953974s left)
2019-03-31 05:53:02,588 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331055302576952
2019-03-31 05:53:02,605 [salt.minion      :1432][INFO    ][14204] Starting a new job with PID 14204
2019-03-31 05:53:02,633 [salt.minion      :1711][INFO    ][14204] Returning information for job: 20190331055302576952
2019-03-31 05:53:13,102 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (246.58495903s left)
2019-03-31 05:53:32,738 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331055332726724
2019-03-31 05:53:32,761 [salt.minion      :1432][INFO    ][14232] Starting a new job with PID 14232
2019-03-31 05:53:32,793 [salt.minion      :1711][INFO    ][14232] Returning information for job: 20190331055332726724
2019-03-31 05:53:45,767 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (213.919826031s left)
2019-03-31 05:54:02,785 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331055402772190
2019-03-31 05:54:02,805 [salt.minion      :1432][INFO    ][14305] Starting a new job with PID 14305
2019-03-31 05:54:02,829 [salt.minion      :1711][INFO    ][14305] Returning information for job: 20190331055402772190
2019-03-31 05:54:18,452 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (181.234737158s left)
2019-03-31 05:54:33,004 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331055432991908
2019-03-31 05:54:33,024 [salt.minion      :1432][INFO    ][14334] Starting a new job with PID 14334
2019-03-31 05:54:33,052 [salt.minion      :1711][INFO    ][14334] Returning information for job: 20190331055432991908
2019-03-31 05:54:51,214 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (148.472553968s left)
2019-03-31 05:55:03,029 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331055503020937
2019-03-31 05:55:03,045 [salt.minion      :1432][INFO    ][14410] Starting a new job with PID 14410
2019-03-31 05:55:03,068 [salt.minion      :1711][INFO    ][14410] Returning information for job: 20190331055503020937
2019-03-31 05:55:24,133 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (115.553884029s left)
2019-03-31 05:55:33,137 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331055533121814
2019-03-31 05:55:33,163 [salt.minion      :1432][INFO    ][14439] Starting a new job with PID 14439
2019-03-31 05:55:33,185 [salt.minion      :1711][INFO    ][14439] Returning information for job: 20190331055533121814
2019-03-31 05:55:56,810 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (82.8764081001s left)
2019-03-31 05:56:03,333 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331055603324110
2019-03-31 05:56:03,354 [salt.minion      :1432][INFO    ][14514] Starting a new job with PID 14514
2019-03-31 05:56:03,377 [salt.minion      :1711][INFO    ][14514] Returning information for job: 20190331055603324110
2019-03-31 05:56:29,582 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (50.1050710678s left)
2019-03-31 05:56:33,416 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331055633408315
2019-03-31 05:56:33,430 [salt.minion      :1432][INFO    ][14543] Starting a new job with PID 14543
2019-03-31 05:56:33,453 [salt.minion      :1711][INFO    ][14543] Returning information for job: 20190331055633408315
2019-03-31 05:57:02,204 [salt.loaded.ext.module.maas:1023][INFO    ][8018] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (17.4825489521s left)
2019-03-31 05:57:03,511 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331055703502289
2019-03-31 05:57:03,528 [salt.minion      :1432][INFO    ][14618] Starting a new job with PID 14618
2019-03-31 05:57:03,560 [salt.minion      :1711][INFO    ][14618] Returning information for job: 20190331055703502289
2019-03-31 05:57:33,720 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331055733711758
2019-03-31 05:57:33,738 [salt.minion      :1432][INFO    ][14645] Starting a new job with PID 14645
2019-03-31 05:57:33,762 [salt.minion      :1711][INFO    ][14645] Returning information for job: 20190331055733711758
2019-03-31 05:57:34,891 [salt.state       :302 ][ERROR   ][8018] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-03-31 05:57:34,892 [salt.state       :1951][INFO    ][8018] Completed state [maas.wait_for_machine_status] at time 05:57:34.892235 duration_in_ms=2265214.338
2019-03-31 05:57:34,897 [salt.minion      :1711][INFO    ][8018] Returning information for job: 20190331051939575819
2019-03-31 05:57:45,692 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command pillar.get with jid 20190331055745680338
2019-03-31 05:57:45,717 [salt.minion      :1432][INFO    ][14655] Starting a new job with PID 14655
2019-03-31 05:57:45,728 [salt.minion      :1711][INFO    ][14655] Returning information for job: 20190331055745680338
2019-03-31 05:57:46,257 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command service.status with jid 20190331055746248479
2019-03-31 05:57:46,279 [salt.minion      :1432][INFO    ][14660] Starting a new job with PID 14660
2019-03-31 05:57:46,802 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14660] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-03-31 05:57:46,838 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14660] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-03-31 05:57:46,855 [salt.minion      :1711][INFO    ][14660] Returning information for job: 20190331055746248479
2019-03-31 05:57:47,402 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command state.apply with jid 20190331055747389912
2019-03-31 05:57:47,423 [salt.minion      :1432][INFO    ][14671] Starting a new job with PID 14671
2019-03-31 05:57:55,550 [salt.state       :915 ][INFO    ][14671] Loading fresh modules for state activity
2019-03-31 05:57:56,114 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14671] Executing command 'salt-minion --version' in directory '/root'
2019-03-31 05:57:56,414 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14671] Executing command 'salt-minion --version' in directory '/root'
2019-03-31 05:57:57,400 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14671] Executing command 'salt-minion --version' in directory '/root'
2019-03-31 05:57:57,680 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14671] Executing command 'salt-minion --version' in directory '/root'
2019-03-31 05:57:59,500 [salt.state       :1780][INFO    ][14671] Running state [salt-minion] at time 05:57:59.500326
2019-03-31 05:57:59,500 [salt.state       :1813][INFO    ][14671] Executing state pkg.installed for [salt-minion]
2019-03-31 05:57:59,501 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14671] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-31 05:57:59,606 [salt.state       :300 ][INFO    ][14671] All specified packages are already installed
2019-03-31 05:57:59,606 [salt.state       :1951][INFO    ][14671] Completed state [salt-minion] at time 05:57:59.606416 duration_in_ms=106.091
2019-03-31 05:57:59,606 [salt.state       :1780][INFO    ][14671] Running state [salt_minion_dependency_packages] at time 05:57:59.606759
2019-03-31 05:57:59,607 [salt.state       :1813][INFO    ][14671] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-03-31 05:57:59,616 [salt.state       :300 ][INFO    ][14671] All specified packages are already installed
2019-03-31 05:57:59,616 [salt.state       :1951][INFO    ][14671] Completed state [salt_minion_dependency_packages] at time 05:57:59.616883 duration_in_ms=10.124
2019-03-31 05:57:59,623 [salt.state       :1780][INFO    ][14671] Running state [/etc/salt/minion.d/minion.conf] at time 05:57:59.623285
2019-03-31 05:57:59,623 [salt.state       :1813][INFO    ][14671] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-03-31 05:57:59,890 [salt.state       :300 ][INFO    ][14671] File /etc/salt/minion.d/minion.conf is in the correct state
2019-03-31 05:57:59,890 [salt.state       :1951][INFO    ][14671] Completed state [/etc/salt/minion.d/minion.conf] at time 05:57:59.890728 duration_in_ms=267.443
2019-03-31 05:57:59,891 [salt.state       :1780][INFO    ][14671] Running state [python-netaddr] at time 05:57:59.890999
2019-03-31 05:57:59,891 [salt.state       :1813][INFO    ][14671] Executing state pkg.installed for [python-netaddr]
2019-03-31 05:57:59,900 [salt.state       :300 ][INFO    ][14671] All specified packages are already installed
2019-03-31 05:57:59,901 [salt.state       :1951][INFO    ][14671] Completed state [python-netaddr] at time 05:57:59.901033 duration_in_ms=10.034
2019-03-31 05:57:59,905 [salt.state       :1780][INFO    ][14671] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:57:59.905683
2019-03-31 05:57:59,905 [salt.state       :1813][INFO    ][14671] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-03-31 05:57:59,918 [salt.state       :300 ][INFO    ][14671] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-03-31 05:57:59,918 [salt.state       :1951][INFO    ][14671] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:57:59.918558 duration_in_ms=12.875
2019-03-31 05:57:59,922 [salt.state       :1780][INFO    ][14671] Running state [salt-minion] at time 05:57:59.922005
2019-03-31 05:57:59,922 [salt.state       :1813][INFO    ][14671] Executing state service.running for [salt-minion]
2019-03-31 05:57:59,923 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14671] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-31 05:57:59,965 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14671] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-03-31 05:57:59,981 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14671] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-03-31 05:58:00,002 [salt.state       :300 ][INFO    ][14671] The service salt-minion is already running
2019-03-31 05:58:00,002 [salt.state       :1951][INFO    ][14671] Completed state [salt-minion] at time 05:58:00.002573 duration_in_ms=80.568
2019-03-31 05:58:00,007 [salt.state       :1780][INFO    ][14671] Running state [/etc/salt/grains.d] at time 05:58:00.007029
2019-03-31 05:58:00,007 [salt.state       :1813][INFO    ][14671] Executing state file.directory for [/etc/salt/grains.d]
2019-03-31 05:58:00,008 [salt.state       :300 ][INFO    ][14671] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-03-31 05:58:00,008 [salt.state       :1951][INFO    ][14671] Completed state [/etc/salt/grains.d] at time 05:58:00.008502 duration_in_ms=1.473
2019-03-31 05:58:00,010 [salt.state       :1780][INFO    ][14671] Running state [/etc/salt/grains] at time 05:58:00.009968
2019-03-31 05:58:00,010 [salt.state       :1813][INFO    ][14671] Executing state file.managed for [/etc/salt/grains]
2019-03-31 05:58:00,011 [salt.state       :300 ][INFO    ][14671] File /etc/salt/grains exists with proper permissions. No changes made.
2019-03-31 05:58:00,012 [salt.state       :1951][INFO    ][14671] Completed state [/etc/salt/grains] at time 05:58:00.012160 duration_in_ms=2.193
2019-03-31 05:58:00,013 [salt.state       :1780][INFO    ][14671] Running state [/etc/salt/grains.d/placeholder] at time 05:58:00.013089
2019-03-31 05:58:00,013 [salt.state       :1813][INFO    ][14671] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-03-31 05:58:00,014 [salt.state       :300 ][INFO    ][14671] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-03-31 05:58:00,017 [salt.state       :1951][INFO    ][14671] Completed state [/etc/salt/grains.d/placeholder] at time 05:58:00.017792 duration_in_ms=4.704
2019-03-31 05:58:00,018 [salt.state       :1780][INFO    ][14671] Running state [/etc/salt/grains.d/sphinx] at time 05:58:00.018258
2019-03-31 05:58:00,018 [salt.state       :1813][INFO    ][14671] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-03-31 05:58:00,019 [salt.state       :300 ][INFO    ][14671] File /etc/salt/grains.d/sphinx is in the correct state
2019-03-31 05:58:00,019 [salt.state       :1951][INFO    ][14671] Completed state [/etc/salt/grains.d/sphinx] at time 05:58:00.019789 duration_in_ms=1.532
2019-03-31 05:58:00,021 [salt.state       :1780][INFO    ][14671] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:58:00.021789
2019-03-31 05:58:00,022 [salt.state       :1813][INFO    ][14671] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-03-31 05:58:00,022 [salt.state       :300 ][INFO    ][14671] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-03-31 05:58:00,022 [salt.state       :1951][INFO    ][14671] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:58:00.022551 duration_in_ms=0.762
2019-03-31 05:58:00,023 [salt.state       :1780][INFO    ][14671] Running state [/etc/salt/grains.d/dns_records] at time 05:58:00.023039
2019-03-31 05:58:00,023 [salt.state       :1813][INFO    ][14671] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-03-31 05:58:00,024 [salt.state       :300 ][INFO    ][14671] File /etc/salt/grains.d/dns_records is in the correct state
2019-03-31 05:58:00,024 [salt.state       :1951][INFO    ][14671] Completed state [/etc/salt/grains.d/dns_records] at time 05:58:00.024365 duration_in_ms=1.325
2019-03-31 05:58:00,025 [salt.state       :1780][INFO    ][14671] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:58:00.025286
2019-03-31 05:58:00,025 [salt.state       :1813][INFO    ][14671] 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-31 05:58:00,025 [salt.state       :300 ][INFO    ][14671] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-03-31 05:58:00,026 [salt.state       :1951][INFO    ][14671] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:58:00.026052 duration_in_ms=0.767
2019-03-31 05:58:00,026 [salt.state       :1780][INFO    ][14671] Running state [/etc/salt/grains.d/salt] at time 05:58:00.026529
2019-03-31 05:58:00,026 [salt.state       :1813][INFO    ][14671] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-03-31 05:58:00,029 [salt.state       :300 ][INFO    ][14671] File /etc/salt/grains.d/salt is in the correct state
2019-03-31 05:58:00,029 [salt.state       :1951][INFO    ][14671] Completed state [/etc/salt/grains.d/salt] at time 05:58:00.029532 duration_in_ms=3.004
2019-03-31 05:58:00,030 [salt.state       :1780][INFO    ][14671] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:58:00.030421
2019-03-31 05:58:00,030 [salt.state       :1813][INFO    ][14671] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-03-31 05:58:00,031 [salt.state       :300 ][INFO    ][14671] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-03-31 05:58:00,031 [salt.state       :1951][INFO    ][14671] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:58:00.031204 duration_in_ms=0.783
2019-03-31 05:58:00,033 [salt.state       :1780][INFO    ][14671] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:58:00.033115
2019-03-31 05:58:00,033 [salt.state       :1813][INFO    ][14671] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-03-31 05:58:00,033 [salt.state       :300 ][INFO    ][14671] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-03-31 05:58:00,033 [salt.state       :1951][INFO    ][14671] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:58:00.033886 duration_in_ms=0.771
2019-03-31 05:58:00,034 [salt.state       :1780][INFO    ][14671] Running state [mine.update] at time 05:58:00.034547
2019-03-31 05:58:00,034 [salt.state       :1813][INFO    ][14671] Executing state module.wait for [mine.update]
2019-03-31 05:58:00,035 [salt.state       :300 ][INFO    ][14671] No changes made for mine.update
2019-03-31 05:58:00,035 [salt.state       :1951][INFO    ][14671] Completed state [mine.update] at time 05:58:00.035264 duration_in_ms=0.717
2019-03-31 05:58:00,035 [salt.state       :1780][INFO    ][14671] Running state [ca-certificates] at time 05:58:00.035500
2019-03-31 05:58:00,035 [salt.state       :1813][INFO    ][14671] Executing state pkg.installed for [ca-certificates]
2019-03-31 05:58:00,045 [salt.state       :300 ][INFO    ][14671] All specified packages are already installed
2019-03-31 05:58:00,046 [salt.state       :1951][INFO    ][14671] Completed state [ca-certificates] at time 05:58:00.046056 duration_in_ms=10.555
2019-03-31 05:58:00,046 [salt.state       :1780][INFO    ][14671] Running state [update-ca-certificates] at time 05:58:00.046751
2019-03-31 05:58:00,047 [salt.state       :1813][INFO    ][14671] Executing state cmd.wait for [update-ca-certificates]
2019-03-31 05:58:00,047 [salt.state       :300 ][INFO    ][14671] No changes made for update-ca-certificates
2019-03-31 05:58:00,047 [salt.state       :1951][INFO    ][14671] Completed state [update-ca-certificates] at time 05:58:00.047502 duration_in_ms=0.751
2019-03-31 05:58:00,047 [salt.state       :1780][INFO    ][14671] Running state [iptables] at time 05:58:00.047731
2019-03-31 05:58:00,047 [salt.state       :1813][INFO    ][14671] Executing state pkg.installed for [iptables]
2019-03-31 05:58:00,058 [salt.state       :300 ][INFO    ][14671] All specified packages are already installed
2019-03-31 05:58:00,058 [salt.state       :1951][INFO    ][14671] Completed state [iptables] at time 05:58:00.058311 duration_in_ms=10.58
2019-03-31 05:58:00,058 [salt.state       :1780][INFO    ][14671] Running state [iptables-persistent] at time 05:58:00.058556
2019-03-31 05:58:00,058 [salt.state       :1813][INFO    ][14671] Executing state pkg.installed for [iptables-persistent]
2019-03-31 05:58:00,069 [salt.state       :300 ][INFO    ][14671] All specified packages are already installed
2019-03-31 05:58:00,069 [salt.state       :1951][INFO    ][14671] Completed state [iptables-persistent] at time 05:58:00.069279 duration_in_ms=10.723
2019-03-31 05:58:00,071 [salt.state       :1780][INFO    ][14671] Running state [iptables_modules_v4_load] at time 05:58:00.071264
2019-03-31 05:58:00,071 [salt.state       :1813][INFO    ][14671] Executing state kmod.present for [iptables_modules_v4_load]
2019-03-31 05:58:00,072 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14671] Executing command 'lsmod' in directory '/root'
2019-03-31 05:58:00,093 [salt.state       :300 ][INFO    ][14671] Kernel modules iptable_filter, ip_tables are already present
2019-03-31 05:58:00,093 [salt.state       :1951][INFO    ][14671] Completed state [iptables_modules_v4_load] at time 05:58:00.093702 duration_in_ms=22.437
2019-03-31 05:58:00,094 [salt.state       :1780][INFO    ][14671] Running state [/etc/iptables/rules.v4] at time 05:58:00.094913
2019-03-31 05:58:00,095 [salt.state       :1813][INFO    ][14671] Executing state file.managed for [/etc/iptables/rules.v4]
2019-03-31 05:58:00,196 [salt.state       :300 ][INFO    ][14671] File /etc/iptables/rules.v4 is in the correct state
2019-03-31 05:58:00,196 [salt.state       :1951][INFO    ][14671] Completed state [/etc/iptables/rules.v4] at time 05:58:00.196907 duration_in_ms=101.993
2019-03-31 05:58:00,198 [salt.state       :1780][INFO    ][14671] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:58:00.198033
2019-03-31 05:58:00,198 [salt.state       :1813][INFO    ][14671] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-03-31 05:58:00,198 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14671] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-03-31 05:58:00,222 [salt.state       :300 ][INFO    ][14671] onlyif execution failed
2019-03-31 05:58:00,223 [salt.state       :1951][INFO    ][14671] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:58:00.223159 duration_in_ms=25.125
2019-03-31 05:58:00,225 [salt.state       :1780][INFO    ][14671] Running state [netfilter-persistent] at time 05:58:00.225018
2019-03-31 05:58:00,225 [salt.state       :1813][INFO    ][14671] Executing state service.running for [netfilter-persistent]
2019-03-31 05:58:00,226 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14671] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-03-31 05:58:00,248 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14671] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-03-31 05:58:00,269 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14671] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-03-31 05:58:00,287 [salt.state       :300 ][INFO    ][14671] The service netfilter-persistent is already running
2019-03-31 05:58:00,287 [salt.state       :1951][INFO    ][14671] Completed state [netfilter-persistent] at time 05:58:00.287870 duration_in_ms=62.852
2019-03-31 05:58:00,289 [salt.state       :1780][INFO    ][14671] Running state [iptables_extra.remove_stale_tables] at time 05:58:00.289274
2019-03-31 05:58:00,289 [salt.state       :1813][INFO    ][14671] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-03-31 05:58:00,290 [salt.state       :300 ][INFO    ][14671] No changes made for iptables_extra.remove_stale_tables
2019-03-31 05:58:00,290 [salt.state       :1951][INFO    ][14671] Completed state [iptables_extra.remove_stale_tables] at time 05:58:00.290593 duration_in_ms=1.318
2019-03-31 05:58:00,293 [salt.state       :1780][INFO    ][14671] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:58:00.293290
2019-03-31 05:58:00,293 [salt.state       :1813][INFO    ][14671] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-03-31 05:58:00,294 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14671] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-03-31 05:58:00,309 [salt.state       :300 ][INFO    ][14671] onlyif execution failed
2019-03-31 05:58:00,309 [salt.state       :1951][INFO    ][14671] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:58:00.309745 duration_in_ms=16.455
2019-03-31 05:58:00,311 [salt.state       :1780][INFO    ][14671] Running state [/etc/iptables/rules.v6] at time 05:58:00.311310
2019-03-31 05:58:00,311 [salt.state       :1813][INFO    ][14671] Executing state file.absent for [/etc/iptables/rules.v6]
2019-03-31 05:58:00,312 [salt.state       :300 ][INFO    ][14671] File /etc/iptables/rules.v6 is not present
2019-03-31 05:58:00,312 [salt.state       :1951][INFO    ][14671] Completed state [/etc/iptables/rules.v6] at time 05:58:00.312568 duration_in_ms=1.258
2019-03-31 05:58:00,313 [salt.state       :1780][INFO    ][14671] Running state [iptables_extra.flush_all] at time 05:58:00.313516
2019-03-31 05:58:00,313 [salt.state       :1813][INFO    ][14671] Executing state module.wait for [iptables_extra.flush_all]
2019-03-31 05:58:00,314 [salt.state       :300 ][INFO    ][14671] No changes made for iptables_extra.flush_all
2019-03-31 05:58:00,314 [salt.state       :1951][INFO    ][14671] Completed state [iptables_extra.flush_all] at time 05:58:00.314522 duration_in_ms=1.007
2019-03-31 05:58:00,320 [salt.minion      :1711][INFO    ][14671] Returning information for job: 20190331055747389912
2019-03-31 05:58:00,863 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command state.apply with jid 20190331055800853686
2019-03-31 05:58:00,887 [salt.minion      :1432][INFO    ][14805] Starting a new job with PID 14805
2019-03-31 05:58:01,977 [salt.state       :915 ][INFO    ][14805] Loading fresh modules for state activity
2019-03-31 05:58:02,869 [salt.state       :1780][INFO    ][14805] Running state [maas-rack-controller] at time 05:58:02.869852
2019-03-31 05:58:02,870 [salt.state       :1813][INFO    ][14805] Executing state pkg.installed for [maas-rack-controller]
2019-03-31 05:58:02,871 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14805] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-31 05:58:02,977 [salt.state       :300 ][INFO    ][14805] All specified packages are already installed
2019-03-31 05:58:02,978 [salt.state       :1951][INFO    ][14805] Completed state [maas-rack-controller] at time 05:58:02.978126 duration_in_ms=108.273
2019-03-31 05:58:02,978 [salt.state       :1780][INFO    ][14805] Running state [ipmitool] at time 05:58:02.978510
2019-03-31 05:58:02,979 [salt.state       :1813][INFO    ][14805] Executing state pkg.installed for [ipmitool]
2019-03-31 05:58:02,988 [salt.state       :300 ][INFO    ][14805] All specified packages are already installed
2019-03-31 05:58:02,988 [salt.state       :1951][INFO    ][14805] Completed state [ipmitool] at time 05:58:02.988740 duration_in_ms=10.231
2019-03-31 05:58:02,993 [salt.state       :1780][INFO    ][14805] Running state [/etc/maas/rackd.conf] at time 05:58:02.993639
2019-03-31 05:58:02,993 [salt.state       :1813][INFO    ][14805] Executing state file.line for [/etc/maas/rackd.conf]
2019-03-31 05:58:02,994 [salt.state       :300 ][INFO    ][14805] No changes needed to be made
2019-03-31 05:58:02,995 [salt.state       :1951][INFO    ][14805] Completed state [/etc/maas/rackd.conf] at time 05:58:02.995085 duration_in_ms=1.446
2019-03-31 05:58:02,995 [salt.state       :1780][INFO    ][14805] Running state [/etc/maas/rackd.conf] at time 05:58:02.995342
2019-03-31 05:58:02,995 [salt.state       :1813][INFO    ][14805] Executing state file.managed for [/etc/maas/rackd.conf]
2019-03-31 05:58:02,995 [salt.loaded.int.states.file:2298][WARNING ][14805] 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-31 05:58:02,996 [salt.state       :300 ][INFO    ][14805] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-03-31 05:58:02,996 [salt.state       :1951][INFO    ][14805] Completed state [/etc/maas/rackd.conf] at time 05:58:02.996879 duration_in_ms=1.537
2019-03-31 05:58:02,997 [salt.state       :1780][INFO    ][14805] Running state [maas-rackd] at time 05:58:02.997886
2019-03-31 05:58:02,998 [salt.state       :1813][INFO    ][14805] Executing state service.running for [maas-rackd]
2019-03-31 05:58:02,998 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14805] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-03-31 05:58:03,033 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14805] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-03-31 05:58:03,050 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14805] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-03-31 05:58:03,070 [salt.state       :300 ][INFO    ][14805] The service maas-rackd is already running
2019-03-31 05:58:03,070 [salt.state       :1951][INFO    ][14805] Completed state [maas-rackd] at time 05:58:03.070422 duration_in_ms=72.535
2019-03-31 05:58:03,072 [salt.minion      :1711][INFO    ][14805] Returning information for job: 20190331055800853686
2019-03-31 05:58:03,618 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command state.apply with jid 20190331055803609168
2019-03-31 05:58:03,637 [salt.minion      :1432][INFO    ][14832] Starting a new job with PID 14832
2019-03-31 05:58:04,737 [salt.state       :915 ][INFO    ][14832] Loading fresh modules for state activity
2019-03-31 05:58:05,740 [salt.state       :1780][INFO    ][14832] Running state [maas-region-controller] at time 05:58:05.740656
2019-03-31 05:58:05,741 [salt.state       :1813][INFO    ][14832] Executing state pkg.installed for [maas-region-controller]
2019-03-31 05:58:05,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14832] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-31 05:58:05,848 [salt.state       :300 ][INFO    ][14832] All specified packages are already installed
2019-03-31 05:58:05,849 [salt.state       :1951][INFO    ][14832] Completed state [maas-region-controller] at time 05:58:05.849111 duration_in_ms=108.455
2019-03-31 05:58:05,849 [salt.state       :1780][INFO    ][14832] Running state [python-oauth] at time 05:58:05.849433
2019-03-31 05:58:05,849 [salt.state       :1813][INFO    ][14832] Executing state pkg.installed for [python-oauth]
2019-03-31 05:58:05,858 [salt.state       :300 ][INFO    ][14832] All specified packages are already installed
2019-03-31 05:58:05,860 [salt.state       :1951][INFO    ][14832] Completed state [python-oauth] at time 05:58:05.860129 duration_in_ms=10.697
2019-03-31 05:58:05,863 [salt.state       :1780][INFO    ][14832] Running state [/etc/maas/regiond.conf] at time 05:58:05.863479
2019-03-31 05:58:05,863 [salt.state       :1813][INFO    ][14832] Executing state file.replace for [/etc/maas/regiond.conf]
2019-03-31 05:58:05,868 [salt.state       :300 ][INFO    ][14832] No changes needed to be made
2019-03-31 05:58:05,868 [salt.state       :1951][INFO    ][14832] Completed state [/etc/maas/regiond.conf] at time 05:58:05.868483 duration_in_ms=5.004
2019-03-31 05:58:05,869 [salt.state       :1780][INFO    ][14832] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:58:05.868957
2019-03-31 05:58:05,869 [salt.state       :1813][INFO    ][14832] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-03-31 05:58:05,929 [salt.state       :300 ][INFO    ][14832] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-03-31 05:58:05,929 [salt.state       :1951][INFO    ][14832] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:58:05.929712 duration_in_ms=60.753
2019-03-31 05:58:05,930 [salt.state       :1780][INFO    ][14832] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:58:05.930601
2019-03-31 05:58:05,931 [salt.state       :1813][INFO    ][14832] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-31 05:58:05,937 [salt.state       :300 ][INFO    ][14832] No changes needed to be made
2019-03-31 05:58:05,937 [salt.state       :1951][INFO    ][14832] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:58:05.937308 duration_in_ms=6.706
2019-03-31 05:58:05,938 [salt.state       :1780][INFO    ][14832] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:58:05.938046
2019-03-31 05:58:05,938 [salt.state       :1813][INFO    ][14832] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-31 05:58:05,944 [salt.state       :300 ][INFO    ][14832] No changes needed to be made
2019-03-31 05:58:05,944 [salt.state       :1951][INFO    ][14832] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:58:05.944804 duration_in_ms=6.759
2019-03-31 05:58:05,945 [salt.state       :1780][INFO    ][14832] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:58:05.945274
2019-03-31 05:58:05,945 [salt.state       :1813][INFO    ][14832] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-03-31 05:58:05,970 [salt.state       :300 ][INFO    ][14832] No changes needed to be made
2019-03-31 05:58:05,971 [salt.state       :1951][INFO    ][14832] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:58:05.971225 duration_in_ms=25.95
2019-03-31 05:58:05,971 [salt.state       :1780][INFO    ][14832] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:58:05.971769
2019-03-31 05:58:05,972 [salt.state       :1813][INFO    ][14832] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-03-31 05:58:05,985 [salt.state       :300 ][INFO    ][14832] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-03-31 05:58:05,985 [salt.state       :1951][INFO    ][14832] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:58:05.985779 duration_in_ms=14.01
2019-03-31 05:58:05,987 [salt.state       :1780][INFO    ][14832] Running state [a2enmod headers] at time 05:58:05.987229
2019-03-31 05:58:05,987 [salt.state       :1813][INFO    ][14832] Executing state cmd.run for [a2enmod headers]
2019-03-31 05:58:05,988 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14832] Executing command 'a2enmod headers' in directory '/root'
2019-03-31 05:58:06,053 [salt.state       :300 ][INFO    ][14832] {'pid': 14851, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-03-31 05:58:06,053 [salt.state       :1951][INFO    ][14832] Completed state [a2enmod headers] at time 05:58:06.053687 duration_in_ms=66.458
2019-03-31 05:58:06,054 [salt.state       :1780][INFO    ][14832] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:58:06.054030
2019-03-31 05:58:06,054 [salt.state       :1813][INFO    ][14832] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-03-31 05:58:06,072 [salt.state       :300 ][INFO    ][14832] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-03-31 05:58:06,073 [salt.state       :1951][INFO    ][14832] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:58:06.072987 duration_in_ms=18.957
2019-03-31 05:58:06,073 [salt.state       :1780][INFO    ][14832] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:58:06.073643
2019-03-31 05:58:06,073 [salt.state       :1813][INFO    ][14832] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-03-31 05:58:06,133 [salt.state       :300 ][INFO    ][14832] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-03-31 05:58:06,134 [salt.state       :1951][INFO    ][14832] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:58:06.134148 duration_in_ms=60.505
2019-03-31 05:58:06,134 [salt.state       :1780][INFO    ][14832] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:58:06.134654
2019-03-31 05:58:06,136 [salt.state       :1813][INFO    ][14832] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-03-31 05:58:06,195 [salt.state       :300 ][INFO    ][14832] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-03-31 05:58:06,196 [salt.state       :1951][INFO    ][14832] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:58:06.196118 duration_in_ms=61.463
2019-03-31 05:58:06,196 [salt.state       :1780][INFO    ][14832] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:58:06.196651
2019-03-31 05:58:06,196 [salt.state       :1813][INFO    ][14832] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-03-31 05:58:06,258 [salt.state       :300 ][INFO    ][14832] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-03-31 05:58:06,258 [salt.state       :1951][INFO    ][14832] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:58:06.258291 duration_in_ms=61.64
2019-03-31 05:58:06,258 [salt.state       :1780][INFO    ][14832] Running state [/root/.pgpass] at time 05:58:06.258540
2019-03-31 05:58:06,258 [salt.state       :1813][INFO    ][14832] Executing state file.managed for [/root/.pgpass]
2019-03-31 05:58:06,300 [salt.state       :300 ][INFO    ][14832] File /root/.pgpass is in the correct state
2019-03-31 05:58:06,301 [salt.state       :1951][INFO    ][14832] Completed state [/root/.pgpass] at time 05:58:06.300959 duration_in_ms=42.419
2019-03-31 05:58:06,307 [salt.state       :1780][INFO    ][14832] Running state [maas-region syncdb --noinput] at time 05:58:06.307462
2019-03-31 05:58:06,307 [salt.state       :1813][INFO    ][14832] Executing state cmd.run for [maas-region syncdb --noinput]
2019-03-31 05:58:06,308 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14832] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-03-31 05:58:08,923 [salt.state       :300 ][INFO    ][14832] {'pid': 14864, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: maasserver, auth, metadataserver, sessions, piston3, sites, contenttypes\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-03-31 05:58:08,924 [salt.state       :1951][INFO    ][14832] Completed state [maas-region syncdb --noinput] at time 05:58:08.924066 duration_in_ms=2616.603
2019-03-31 05:58:08,924 [salt.state       :2022][WARNING ][14832] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-31 05:58:08,928 [salt.state       :1780][INFO    ][14832] Running state [maas-regiond] at time 05:58:08.927936
2019-03-31 05:58:08,928 [salt.state       :1813][INFO    ][14832] Executing state service.running for [maas-regiond]
2019-03-31 05:58:08,930 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14832] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-03-31 05:58:08,969 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14832] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-03-31 05:58:08,987 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14832] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-03-31 05:58:09,008 [salt.state       :300 ][INFO    ][14832] The service maas-regiond is already running
2019-03-31 05:58:09,008 [salt.state       :1951][INFO    ][14832] Completed state [maas-regiond] at time 05:58:09.008813 duration_in_ms=80.877
2019-03-31 05:58:09,012 [salt.state       :1780][INFO    ][14832] Running state [bind9] at time 05:58:09.011947
2019-03-31 05:58:09,012 [salt.state       :1813][INFO    ][14832] Executing state service.running for [bind9]
2019-03-31 05:58:09,013 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14832] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-03-31 05:58:09,034 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14832] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-03-31 05:58:09,050 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14832] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-03-31 05:58:09,071 [salt.state       :300 ][INFO    ][14832] The service bind9 is already running
2019-03-31 05:58:09,071 [salt.state       :1951][INFO    ][14832] Completed state [bind9] at time 05:58:09.071904 duration_in_ms=59.957
2019-03-31 05:58:09,073 [salt.state       :1780][INFO    ][14832] Running state [apache2] at time 05:58:09.073648
2019-03-31 05:58:09,073 [salt.state       :1813][INFO    ][14832] Executing state service.running for [apache2]
2019-03-31 05:58:09,074 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14832] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-03-31 05:58:09,096 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14832] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-03-31 05:58:09,119 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14832] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-03-31 05:58:09,151 [salt.state       :300 ][INFO    ][14832] The service apache2 is already running
2019-03-31 05:58:09,152 [salt.state       :1951][INFO    ][14832] Completed state [apache2] at time 05:58:09.152344 duration_in_ms=78.695
2019-03-31 05:58:09,153 [salt.state       :1780][INFO    ][14832] Running state [maasng.wait_for_http_code] at time 05:58:09.153856
2019-03-31 05:58:09,154 [salt.state       :1813][INFO    ][14832] Executing state module.run for [maasng.wait_for_http_code]
2019-03-31 05:58:09,155 [salt.utils.decorators:613 ][WARNING ][14832] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-31 05:58:09,163 [salt.state       :300 ][INFO    ][14832] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-03-31 05:58:09,163 [salt.state       :1951][INFO    ][14832] Completed state [maasng.wait_for_http_code] at time 05:58:09.163887 duration_in_ms=10.032
2019-03-31 05:58:09,164 [salt.state       :1780][INFO    ][14832] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:58:09.164674
2019-03-31 05:58:09,164 [salt.state       :1813][INFO    ][14832] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-03-31 05:58:09,165 [salt.state       :300 ][INFO    ][14832] /var/lib/maas/.setup_admin exists
2019-03-31 05:58:09,165 [salt.state       :1951][INFO    ][14832] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:58:09.165552 duration_in_ms=0.878
2019-03-31 05:58:09,166 [salt.state       :1780][INFO    ][14832] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:09.166222
2019-03-31 05:58:09,166 [salt.state       :1813][INFO    ][14832] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-31 05:58:09,167 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14832] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-31 05:58:10,943 [salt.state       :300 ][INFO    ][14832] {'pid': 14883, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-31 05:58:10,944 [salt.state       :1951][INFO    ][14832] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:10.943922 duration_in_ms=1777.699
2019-03-31 05:58:10,954 [salt.state       :1780][INFO    ][14832] Running state [maas_region_boot_source_resources_mirror] at time 05:58:10.954530
2019-03-31 05:58:10,955 [salt.state       :1813][INFO    ][14832] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-03-31 05:58:11,048 [salt.state       :300 ][INFO    ][14832] {'changes': {}}
2019-03-31 05:58:11,048 [salt.state       :1951][INFO    ][14832] Completed state [maas_region_boot_source_resources_mirror] at time 05:58:11.048597 duration_in_ms=94.067
2019-03-31 05:58:11,049 [salt.state       :1780][INFO    ][14832] Running state [maasng.boot_resources_import] at time 05:58:11.049639
2019-03-31 05:58:11,050 [salt.state       :1813][INFO    ][14832] Executing state module.run for [maasng.boot_resources_import]
2019-03-31 05:58:11,050 [salt.utils.decorators:613 ][WARNING ][14832] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-31 05:58:11,149 [salt.loaded.ext.module.maasng:1600][INFO    ][14832] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-03-31 05:58:16,194 [salt.loaded.ext.module.maasng:1600][INFO    ][14832] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-03-31 05:58:18,658 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331055818650157
2019-03-31 05:58:18,680 [salt.minion      :1432][INFO    ][15043] Starting a new job with PID 15043
2019-03-31 05:58:18,709 [salt.minion      :1711][INFO    ][15043] Returning information for job: 20190331055818650157
2019-03-31 05:58:21,293 [salt.state       :300 ][INFO    ][14832] {'ret': True}
2019-03-31 05:58:21,293 [salt.state       :1951][INFO    ][14832] Completed state [maasng.boot_resources_import] at time 05:58:21.293493 duration_in_ms=10243.853
2019-03-31 05:58:21,295 [salt.state       :1780][INFO    ][14832] Running state [maas_region_boot_sources_selection_xenial] at time 05:58:21.294814
2019-03-31 05:58:21,295 [salt.state       :1813][INFO    ][14832] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-03-31 05:58:21,463 [salt.state       :300 ][INFO    ][14832] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-03-31 05:58:21,464 [salt.state       :1951][INFO    ][14832] Completed state [maas_region_boot_sources_selection_xenial] at time 05:58:21.464044 duration_in_ms=169.23
2019-03-31 05:58:21,465 [salt.state       :1780][INFO    ][14832] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:58:21.465482
2019-03-31 05:58:21,466 [salt.state       :1813][INFO    ][14832] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-03-31 05:58:21,466 [salt.utils.decorators:613 ][WARNING ][14832] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-31 05:58:21,467 [salt.loaded.ext.module.maasng:1771][INFO    ][14832] boot-sources sync initiated for ALL Rack's
2019-03-31 05:58:22,492 [salt.state       :300 ][INFO    ][14832] {'ret': True}
2019-03-31 05:58:22,493 [salt.state       :1951][INFO    ][14832] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:58:22.492989 duration_in_ms=1027.507
2019-03-31 05:58:22,494 [salt.state       :1780][INFO    ][14832] Running state [maas.process_maas_config] at time 05:58:22.494309
2019-03-31 05:58:22,494 [salt.state       :1813][INFO    ][14832] Executing state module.run for [maas.process_maas_config]
2019-03-31 05:58:22,495 [salt.utils.decorators:613 ][WARNING ][14832] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-31 05:58:22,496 [salt.loaded.ext.module.maas:92  ][INFO    ][14832] maasconfig name=enable_http_proxy value=True
2019-03-31 05:58:22,547 [salt.loaded.ext.module.maas:92  ][INFO    ][14832] maasconfig name=upstream_dns value=8.8.8.8
2019-03-31 05:58:22,598 [salt.loaded.ext.module.maas:92  ][INFO    ][14832] maasconfig name=commissioning_distro_series value=xenial
2019-03-31 05:58:22,654 [salt.loaded.ext.module.maas:92  ][INFO    ][14832] maasconfig name=default_osystem value=ubuntu
2019-03-31 05:58:22,709 [salt.loaded.ext.module.maas:92  ][INFO    ][14832] maasconfig name=active_discovery_interval value=600
2019-03-31 05:58:22,760 [salt.loaded.ext.module.maas:92  ][INFO    ][14832] maasconfig name=dnssec_validation value=no
2019-03-31 05:58:22,810 [salt.loaded.ext.module.maas:92  ][INFO    ][14832] maasconfig name=maas_name value=mas01
2019-03-31 05:58:22,859 [salt.loaded.ext.module.maas:92  ][INFO    ][14832] maasconfig name=network_discovery value=enabled
2019-03-31 05:58:22,951 [salt.loaded.ext.module.maas:92  ][INFO    ][14832] maasconfig name=enable_third_party_drivers value=True
2019-03-31 05:58:23,972 [salt.loaded.ext.module.maas:92  ][INFO    ][14832] maasconfig name=default_storage_layout value=lvm
2019-03-31 05:58:24,042 [salt.loaded.ext.module.maas:92  ][INFO    ][14832] maasconfig name=ntp_external_only value=True
2019-03-31 05:58:24,097 [salt.loaded.ext.module.maas:92  ][INFO    ][14832] maasconfig name=disk_erase_with_secure_erase value=False
2019-03-31 05:58:24,145 [salt.loaded.ext.module.maas:92  ][INFO    ][14832] maasconfig name=default_distro_series value=xenial
2019-03-31 05:58:24,195 [salt.loaded.ext.module.maas:92  ][INFO    ][14832] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-03-31 05:58:24,305 [salt.state       :300 ][INFO    ][14832] {'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-31 05:58:24,306 [salt.state       :1951][INFO    ][14832] Completed state [maas.process_maas_config] at time 05:58:24.306251 duration_in_ms=1811.942
2019-03-31 05:58:24,308 [salt.state       :1780][INFO    ][14832] Running state [pxe_admin] at time 05:58:24.307804
2019-03-31 05:58:24,308 [salt.state       :1813][INFO    ][14832] Executing state maasng.fabric_present for [pxe_admin]
2019-03-31 05:58:24,358 [salt.loaded.ext.module.maasng:945 ][INFO    ][14832] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4ycbte', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-03-31 05:58:24,429 [salt.loaded.ext.module.maasng:1008][WARNING ][14832] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-03-31 05:58:24,430 [salt.loaded.ext.module.maasng:1011][WARNING ][14832] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-03-31 05:58:24,510 [salt.state       :300 ][INFO    ][14832] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-03-31 05:58:24,510 [salt.state       :1951][INFO    ][14832] Completed state [pxe_admin] at time 05:58:24.510563 duration_in_ms=202.758
2019-03-31 05:58:24,511 [salt.state       :1780][INFO    ][14832] Running state [vlan 0] at time 05:58:24.511492
2019-03-31 05:58:24,511 [salt.state       :1813][INFO    ][14832] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-31 05:58:24,561 [salt.loaded.ext.module.maasng:945 ][INFO    ][14832] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4ycbte', 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'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-31 05:58:24,668 [salt.loaded.ext.module.maasng:945 ][INFO    ][14832] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'4ycbte', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-03-31 05:58:24,885 [salt.loaded.ext.module.maasng:945 ][INFO    ][14832] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4ycbte', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-03-31 05:58:24,959 [salt.state       :300 ][INFO    ][14832] {'new': 'Vlan untagged was updated'}
2019-03-31 05:58:24,959 [salt.state       :1951][INFO    ][14832] Completed state [vlan 0] at time 05:58:24.959644 duration_in_ms=448.151
2019-03-31 05:58:24,960 [salt.state       :1780][INFO    ][14832] Running state [192.168.11.0/24] at time 05:58:24.960839
2019-03-31 05:58:24,961 [salt.state       :1813][INFO    ][14832] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-03-31 05:58:25,146 [salt.loaded.ext.module.maasng:945 ][INFO    ][14832] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4ycbte', 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'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-31 05:58:25,146 [salt.loaded.ext.module.maasng:1235][WARNING ][14832] Ignoring parameter vlan:0
2019-03-31 05:58:25,204 [salt.state       :300 ][INFO    ][14832] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-03-31 05:58:25,204 [salt.state       :1951][INFO    ][14832] Completed state [192.168.11.0/24] at time 05:58:25.204784 duration_in_ms=243.944
2019-03-31 05:58:25,205 [salt.state       :1780][INFO    ][14832] Running state [maas_create_iprange_1] at time 05:58:25.205466
2019-03-31 05:58:25,205 [salt.state       :1813][INFO    ][14832] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-03-31 05:58:25,246 [salt.state       :300 ][INFO    ][14832] Iprange maas_create_iprange_1 already exist.
2019-03-31 05:58:25,246 [salt.state       :1951][INFO    ][14832] Completed state [maas_create_iprange_1] at time 05:58:25.246310 duration_in_ms=40.844
2019-03-31 05:58:25,246 [salt.state       :1780][INFO    ][14832] Running state [vlan 0] at time 05:58:25.246559
2019-03-31 05:58:25,246 [salt.state       :1813][INFO    ][14832] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-31 05:58:25,292 [salt.loaded.ext.module.maasng:945 ][INFO    ][14832] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4ycbte', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-03-31 05:58:25,381 [salt.loaded.ext.module.maasng:945 ][INFO    ][14832] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'4ycbte', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-03-31 05:58:25,584 [salt.loaded.ext.module.maasng:945 ][INFO    ][14832] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4ycbte', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-03-31 05:58:25,670 [salt.state       :300 ][INFO    ][14832] {'new': 'Vlan untagged was updated'}
2019-03-31 05:58:25,670 [salt.state       :1951][INFO    ][14832] Completed state [vlan 0] at time 05:58:25.670751 duration_in_ms=424.191
2019-03-31 05:58:25,675 [salt.state       :1780][INFO    ][14832] Running state [opnfv] at time 05:58:25.675037
2019-03-31 05:58:25,675 [salt.state       :1813][INFO    ][14832] Executing state maasng.sshkey_present for [opnfv]
2019-03-31 05:58:25,724 [salt.loaded.ext.module.maasng:1903][INFO    ][14832] [{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-31 05:58:25,724 [salt.state       :300 ][INFO    ][14832] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H already exist for user opnfv.
2019-03-31 05:58:25,724 [salt.state       :1951][INFO    ][14832] Completed state [opnfv] at time 05:58:25.724819 duration_in_ms=49.782
2019-03-31 05:58:25,725 [salt.state       :1780][INFO    ][14832] Running state [maas.process_tags] at time 05:58:25.725452
2019-03-31 05:58:25,725 [salt.state       :1813][INFO    ][14832] Executing state module.run for [maas.process_tags]
2019-03-31 05:58:25,726 [salt.utils.decorators:613 ][WARNING ][14832] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-31 05:58:25,773 [salt.loaded.ext.module.maas:92  ][INFO    ][14832] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-03-31 05:58:25,821 [salt.state       :300 ][INFO    ][14832] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-03-31 05:58:25,821 [salt.state       :1951][INFO    ][14832] Completed state [maas.process_tags] at time 05:58:25.821402 duration_in_ms=95.95
2019-03-31 05:58:25,824 [salt.minion      :1711][INFO    ][14832] Returning information for job: 20190331055803609168
2019-03-31 05:58:26,424 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command state.apply with jid 20190331055826414541
2019-03-31 05:58:26,446 [salt.minion      :1432][INFO    ][15268] Starting a new job with PID 15268
2019-03-31 05:58:34,535 [salt.state       :915 ][INFO    ][15268] Loading fresh modules for state activity
2019-03-31 05:58:34,628 [salt.state       :1780][INFO    ][15268] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:34.628216
2019-03-31 05:58:34,628 [salt.state       :1813][INFO    ][15268] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-31 05:58:34,630 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15268] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-31 05:58:36,341 [salt.state       :300 ][INFO    ][15268] {'pid': 15304, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-31 05:58:36,342 [salt.state       :1951][INFO    ][15268] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:58:36.341953 duration_in_ms=1713.738
2019-03-31 05:58:36,344 [salt.state       :1780][INFO    ][15268] Running state [maas.process_machines] at time 05:58:36.344149
2019-03-31 05:58:36,344 [salt.state       :1813][INFO    ][15268] Executing state module.run for [maas.process_machines]
2019-03-31 05:58:36,344 [salt.utils.decorators:613 ][WARNING ][15268] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-31 05:58:36,822 [salt.loaded.ext.module.maas:412 ][WARNING ][15268] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-31 05:58:36,824 [salt.loaded.ext.module.maas:92  ][INFO    ][15268] 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=nyncsb architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-31 05:58:37,823 [salt.loaded.ext.module.maas:412 ][WARNING ][15268] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-31 05:58:37,824 [salt.loaded.ext.module.maas:92  ][INFO    ][15268] 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=aqpfqx architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-31 05:58:38,854 [salt.loaded.ext.module.maas:412 ][WARNING ][15268] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-31 05:58:38,855 [salt.loaded.ext.module.maas:92  ][INFO    ][15268] 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=f7f3w8 architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-31 05:58:39,962 [salt.loaded.ext.module.maas:412 ][WARNING ][15268] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-31 05:58:39,964 [salt.loaded.ext.module.maas:92  ][INFO    ][15268] 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=s64dds architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-31 05:58:40,946 [salt.loaded.ext.module.maas:412 ][WARNING ][15268] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-31 05:58:40,948 [salt.loaded.ext.module.maas:92  ][INFO    ][15268] 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=qwsngn architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-31 05:58:41,520 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331055841510019
2019-03-31 05:58:41,534 [salt.minion      :1432][INFO    ][15519] Starting a new job with PID 15519
2019-03-31 05:58:41,561 [salt.minion      :1711][INFO    ][15519] Returning information for job: 20190331055841510019
2019-03-31 05:58:41,900 [salt.state       :300 ][INFO    ][15268] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-31 05:58:41,901 [salt.state       :1951][INFO    ][15268] Completed state [maas.process_machines] at time 05:58:41.901057 duration_in_ms=5556.907
2019-03-31 05:58:41,904 [salt.minion      :1711][INFO    ][15268] Returning information for job: 20190331055826414541
2019-03-31 05:59:14,794 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command state.apply with jid 20190331055914784131
2019-03-31 05:59:14,816 [salt.minion      :1432][INFO    ][15590] Starting a new job with PID 15590
2019-03-31 05:59:22,757 [salt.state       :915 ][INFO    ][15590] Loading fresh modules for state activity
2019-03-31 05:59:22,857 [salt.state       :1780][INFO    ][15590] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:22.857474
2019-03-31 05:59:22,857 [salt.state       :1813][INFO    ][15590] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-31 05:59:22,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15590] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-31 05:59:24,545 [salt.state       :300 ][INFO    ][15590] {'pid': 15603, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-31 05:59:24,546 [salt.state       :1951][INFO    ][15590] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:24.546827 duration_in_ms=1689.353
2019-03-31 05:59:24,550 [salt.state       :1780][INFO    ][15590] Running state [maas.wait_for_machine_status] at time 05:59:24.549909
2019-03-31 05:59:24,550 [salt.state       :1813][INFO    ][15590] Executing state module.run for [maas.wait_for_machine_status]
2019-03-31 05:59:24,551 [salt.utils.decorators:613 ][WARNING ][15590] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-31 05:59:27,037 [salt.state       :300 ][INFO    ][15590] {'ret': True}
2019-03-31 05:59:27,038 [salt.state       :1951][INFO    ][15590] Completed state [maas.wait_for_machine_status] at time 05:59:27.038387 duration_in_ms=2488.477
2019-03-31 05:59:27,043 [salt.minion      :1711][INFO    ][15590] Returning information for job: 20190331055914784131
2019-03-31 05:59:27,587 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command state.apply with jid 20190331055927575065
2019-03-31 05:59:27,610 [salt.minion      :1432][INFO    ][15616] Starting a new job with PID 15616
2019-03-31 05:59:28,684 [salt.state       :915 ][INFO    ][15616] Loading fresh modules for state activity
2019-03-31 05:59:28,819 [salt.state       :1780][INFO    ][15616] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:28.819267
2019-03-31 05:59:28,819 [salt.state       :1813][INFO    ][15616] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-31 05:59:28,821 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15616] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-31 05:59:30,496 [salt.state       :300 ][INFO    ][15616] {'pid': 15623, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-31 05:59:30,496 [salt.state       :1951][INFO    ][15616] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:30.496733 duration_in_ms=1677.465
2019-03-31 05:59:30,498 [salt.state       :1780][INFO    ][15616] Running state [maas_machines_storage_cmp002_lvm] at time 05:59:30.498591
2019-03-31 05:59:30,499 [salt.state       :1813][INFO    ][15616] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-31 05:59:31,038 [salt.state       :300 ][INFO    ][15616] Machine cmp002 is not in Ready state.
2019-03-31 05:59:31,039 [salt.state       :1951][INFO    ][15616] Completed state [maas_machines_storage_cmp002_lvm] at time 05:59:31.038943 duration_in_ms=540.351
2019-03-31 05:59:31,039 [salt.state       :1780][INFO    ][15616] Running state [maas_machines_storage_cmp001_lvm] at time 05:59:31.039323
2019-03-31 05:59:31,039 [salt.state       :1813][INFO    ][15616] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-31 05:59:31,565 [salt.state       :300 ][INFO    ][15616] Machine cmp001 is not in Ready state.
2019-03-31 05:59:31,565 [salt.state       :1951][INFO    ][15616] Completed state [maas_machines_storage_cmp001_lvm] at time 05:59:31.565508 duration_in_ms=526.185
2019-03-31 05:59:31,568 [salt.minion      :1711][INFO    ][15616] Returning information for job: 20190331055927575065
2019-03-31 05:59:32,117 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command state.apply with jid 20190331055932103455
2019-03-31 05:59:32,135 [salt.minion      :1432][INFO    ][15633] Starting a new job with PID 15633
2019-03-31 05:59:33,292 [salt.state       :915 ][INFO    ][15633] Loading fresh modules for state activity
2019-03-31 05:59:33,387 [salt.state       :1780][INFO    ][15633] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:33.387728
2019-03-31 05:59:33,388 [salt.state       :1813][INFO    ][15633] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-31 05:59:33,389 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15633] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-31 05:59:35,052 [salt.state       :300 ][INFO    ][15633] {'pid': 15653, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-31 05:59:35,054 [salt.state       :1951][INFO    ][15633] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:35.053846 duration_in_ms=1666.117
2019-03-31 05:59:35,057 [salt.state       :1780][INFO    ][15633] Running state [maas.deploy_machines] at time 05:59:35.057614
2019-03-31 05:59:35,058 [salt.state       :1813][INFO    ][15633] Executing state module.run for [maas.deploy_machines]
2019-03-31 05:59:35,059 [salt.utils.decorators:613 ][WARNING ][15633] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-31 05:59:35,692 [salt.loaded.ext.module.maas:684 ][INFO    ][15633] deploymachines hwe_kernel=hwe-16.04 system_id=qwsngn distro_series=xenial
2019-03-31 05:59:37,698 [salt.state       :300 ][INFO    ][15633] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-03-31 05:59:37,698 [salt.state       :1951][INFO    ][15633] Completed state [maas.deploy_machines] at time 05:59:37.698625 duration_in_ms=2641.011
2019-03-31 05:59:37,700 [salt.minion      :1711][INFO    ][15633] Returning information for job: 20190331055932103455
2019-03-31 05:59:38,239 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command state.apply with jid 20190331055938225645
2019-03-31 05:59:38,262 [salt.minion      :1432][INFO    ][15711] Starting a new job with PID 15711
2019-03-31 05:59:46,232 [salt.state       :915 ][INFO    ][15711] Loading fresh modules for state activity
2019-03-31 05:59:46,325 [salt.state       :1780][INFO    ][15711] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:46.325337
2019-03-31 05:59:46,325 [salt.state       :1813][INFO    ][15711] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-31 05:59:46,327 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15711] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-31 05:59:48,131 [salt.state       :300 ][INFO    ][15711] {'pid': 15723, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-31 05:59:48,131 [salt.state       :1951][INFO    ][15711] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:59:48.131667 duration_in_ms=1806.328
2019-03-31 05:59:48,138 [salt.state       :1780][INFO    ][15711] Running state [maas.wait_for_machine_status] at time 05:59:48.138384
2019-03-31 05:59:48,139 [salt.state       :1813][INFO    ][15711] Executing state module.run for [maas.wait_for_machine_status]
2019-03-31 05:59:48,139 [salt.utils.decorators:613 ][WARNING ][15711] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-31 05:59:50,881 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.27440906s left)
2019-03-31 05:59:53,353 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331055953300769
2019-03-31 05:59:53,374 [salt.minion      :1432][INFO    ][15779] Starting a new job with PID 15779
2019-03-31 05:59:53,398 [salt.minion      :1711][INFO    ][15779] Returning information for job: 20190331055953300769
2019-03-31 06:00:23,330 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331060023323254
2019-03-31 06:00:23,349 [salt.minion      :1432][INFO    ][15812] Starting a new job with PID 15812
2019-03-31 06:00:23,371 [salt.minion      :1711][INFO    ][15812] Returning information for job: 20190331060023323254
2019-03-31 06:00:23,504 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.65153408s left)
2019-03-31 06:00:53,533 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331060053526513
2019-03-31 06:00:53,551 [salt.minion      :1432][INFO    ][15894] Starting a new job with PID 15894
2019-03-31 06:00:53,573 [salt.minion      :1711][INFO    ][15894] Returning information for job: 20190331060053526513
2019-03-31 06:00:55,867 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2182.28871107s left)
2019-03-31 06:01:23,716 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331060123708293
2019-03-31 06:01:23,734 [salt.minion      :1432][INFO    ][15930] Starting a new job with PID 15930
2019-03-31 06:01:23,762 [salt.minion      :1711][INFO    ][15930] Returning information for job: 20190331060123708293
2019-03-31 06:01:28,616 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.53943205s left)
2019-03-31 06:01:53,913 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331060153905147
2019-03-31 06:01:53,937 [salt.minion      :1432][INFO    ][16001] Starting a new job with PID 16001
2019-03-31 06:01:53,960 [salt.minion      :1711][INFO    ][16001] Returning information for job: 20190331060153905147
2019-03-31 06:02:00,946 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2117.20945001s left)
2019-03-31 06:02:24,109 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331060224099980
2019-03-31 06:02:24,130 [salt.minion      :1432][INFO    ][16034] Starting a new job with PID 16034
2019-03-31 06:02:24,157 [salt.minion      :1711][INFO    ][16034] Returning information for job: 20190331060224099980
2019-03-31 06:02:33,756 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2084.39912796s left)
2019-03-31 06:02:54,302 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331060254294522
2019-03-31 06:02:54,321 [salt.minion      :1432][INFO    ][16102] Starting a new job with PID 16102
2019-03-31 06:02:54,349 [salt.minion      :1711][INFO    ][16102] Returning information for job: 20190331060254294522
2019-03-31 06:03:06,050 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2052.10570717s left)
2019-03-31 06:03:24,500 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331060324489890
2019-03-31 06:03:24,524 [salt.minion      :1432][INFO    ][16135] Starting a new job with PID 16135
2019-03-31 06:03:24,548 [salt.minion      :1711][INFO    ][16135] Returning information for job: 20190331060324489890
2019-03-31 06:03:38,858 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2019.29797816s left)
2019-03-31 06:03:54,582 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331060354573620
2019-03-31 06:03:54,596 [salt.minion      :1432][INFO    ][16226] Starting a new job with PID 16226
2019-03-31 06:03:54,619 [salt.minion      :1711][INFO    ][16226] Returning information for job: 20190331060354573620
2019-03-31 06:04:11,390 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1986.76586413s left)
2019-03-31 06:04:24,606 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331060424598547
2019-03-31 06:04:24,622 [salt.minion      :1432][INFO    ][16275] Starting a new job with PID 16275
2019-03-31 06:04:24,647 [salt.minion      :1711][INFO    ][16275] Returning information for job: 20190331060424598547
2019-03-31 06:04:44,267 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1953.88822818s left)
2019-03-31 06:04:54,725 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331060454713930
2019-03-31 06:04:54,743 [salt.minion      :1432][INFO    ][16451] Starting a new job with PID 16451
2019-03-31 06:04:54,767 [salt.minion      :1711][INFO    ][16451] Returning information for job: 20190331060454713930
2019-03-31 06:05:16,682 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1921.473665s left)
2019-03-31 06:05:24,816 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331060524800599
2019-03-31 06:05:24,838 [salt.minion      :1432][INFO    ][16489] Starting a new job with PID 16489
2019-03-31 06:05:24,862 [salt.minion      :1711][INFO    ][16489] Returning information for job: 20190331060524800599
2019-03-31 06:05:50,220 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1887.93556213s left)
2019-03-31 06:05:54,930 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331060554914534
2019-03-31 06:05:54,952 [salt.minion      :1432][INFO    ][16635] Starting a new job with PID 16635
2019-03-31 06:05:54,973 [salt.minion      :1711][INFO    ][16635] Returning information for job: 20190331060554914534
2019-03-31 06:06:22,632 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1855.52384019s left)
2019-03-31 06:06:25,132 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331060625124393
2019-03-31 06:06:25,149 [salt.minion      :1432][INFO    ][16670] Starting a new job with PID 16670
2019-03-31 06:06:25,173 [salt.minion      :1711][INFO    ][16670] Returning information for job: 20190331060625124393
2019-03-31 06:06:55,249 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331060655241163
2019-03-31 06:06:55,267 [salt.minion      :1432][INFO    ][16838] Starting a new job with PID 16838
2019-03-31 06:06:55,292 [salt.minion      :1711][INFO    ][16838] Returning information for job: 20190331060655241163
2019-03-31 06:06:55,422 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1822.73408604s left)
2019-03-31 06:07:25,386 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331060725372006
2019-03-31 06:07:25,408 [salt.minion      :1432][INFO    ][16872] Starting a new job with PID 16872
2019-03-31 06:07:25,429 [salt.minion      :1711][INFO    ][16872] Returning information for job: 20190331060725372006
2019-03-31 06:07:27,864 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1790.29151511s left)
2019-03-31 06:07:55,513 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331060755503651
2019-03-31 06:07:55,534 [salt.minion      :1432][INFO    ][17029] Starting a new job with PID 17029
2019-03-31 06:07:55,556 [salt.minion      :1711][INFO    ][17029] Returning information for job: 20190331060755503651
2019-03-31 06:08:00,779 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1757.37672997s left)
2019-03-31 06:08:25,642 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331060825627662
2019-03-31 06:08:25,663 [salt.minion      :1432][INFO    ][17062] Starting a new job with PID 17062
2019-03-31 06:08:25,686 [salt.minion      :1711][INFO    ][17062] Returning information for job: 20190331060825627662
2019-03-31 06:08:33,464 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1724.6919651s left)
2019-03-31 06:08:55,777 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331060855769646
2019-03-31 06:08:55,793 [salt.minion      :1432][INFO    ][17134] Starting a new job with PID 17134
2019-03-31 06:08:55,813 [salt.minion      :1711][INFO    ][17134] Returning information for job: 20190331060855769646
2019-03-31 06:09:06,182 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1691.97399998s left)
2019-03-31 06:09:25,920 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331060925908582
2019-03-31 06:09:25,940 [salt.minion      :1432][INFO    ][17166] Starting a new job with PID 17166
2019-03-31 06:09:25,969 [salt.minion      :1711][INFO    ][17166] Returning information for job: 20190331060925908582
2019-03-31 06:09:38,802 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1659.35350609s left)
2019-03-31 06:09:56,069 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331060956060246
2019-03-31 06:09:56,084 [salt.minion      :1432][INFO    ][17242] Starting a new job with PID 17242
2019-03-31 06:09:56,106 [salt.minion      :1711][INFO    ][17242] Returning information for job: 20190331060956060246
2019-03-31 06:10:11,692 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1626.46339607s left)
2019-03-31 06:10:26,225 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331061026210515
2019-03-31 06:10:26,250 [salt.minion      :1432][INFO    ][17269] Starting a new job with PID 17269
2019-03-31 06:10:26,272 [salt.minion      :1711][INFO    ][17269] Returning information for job: 20190331061026210515
2019-03-31 06:10:44,221 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1593.93441916s left)
2019-03-31 06:10:56,369 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331061056360984
2019-03-31 06:10:56,389 [salt.minion      :1432][INFO    ][17514] Starting a new job with PID 17514
2019-03-31 06:10:56,433 [salt.minion      :1711][INFO    ][17514] Returning information for job: 20190331061056360984
2019-03-31 06:11:17,081 [salt.loaded.ext.module.maas:1023][INFO    ][15711] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1561.07478619s left)
2019-03-31 06:11:26,563 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command saltutil.find_job with jid 20190331061126548417
2019-03-31 06:11:26,588 [salt.minion      :1432][INFO    ][17540] Starting a new job with PID 17540
2019-03-31 06:11:26,611 [salt.minion      :1711][INFO    ][17540] Returning information for job: 20190331061126548417
2019-03-31 06:11:50,206 [salt.state       :300 ][INFO    ][15711] {'ret': True}
2019-03-31 06:11:50,207 [salt.state       :1951][INFO    ][15711] Completed state [maas.wait_for_machine_status] at time 06:11:50.207768 duration_in_ms=722069.381
2019-03-31 06:11:50,213 [salt.minion      :1711][INFO    ][15711] Returning information for job: 20190331055938225645
2019-03-31 06:11:59,422 [salt.utils.schedule:1377][INFO    ][5312] Running scheduled job: __mine_interval
2019-03-31 07:11:59,422 [salt.utils.schedule:1377][INFO    ][5312] Running scheduled job: __mine_interval
2019-03-31 07:44:31,917 [salt.minion      :1308][INFO    ][5312] User sudo_ubuntu Executing command cp.push_dir with jid 20190331074431906150
2019-03-31 07:44:31,939 [salt.minion      :1432][INFO    ][27185] Starting a new job with PID 27185
