2019-06-22 05:25:22,570 [salt.utils.decorators:613 ][WARNING ][2131] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-22 05:25:23,303 [salt.utils.decorators:613 ][WARNING ][2131] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-22 05:25:26,324 [salt.loaded.int.states.file:2298][WARNING ][2571] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-22 05:25:53,250 [salt.state       :2022][WARNING ][3029] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-22 05:25:56,386 [salt.utils.decorators:613 ][WARNING ][3029] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-22 05:26:09,456 [salt.utils.decorators:613 ][WARNING ][3029] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-22 05:26:37,763 [salt.utils.decorators:613 ][WARNING ][3029] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-22 05:26:38,848 [salt.utils.decorators:613 ][WARNING ][3029] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-22 05:26:40,864 [salt.loaded.ext.module.maasng:1008][WARNING ][3029] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-06-22 05:26:40,865 [salt.loaded.ext.module.maasng:1011][WARNING ][3029] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-06-22 05:26:41,637 [salt.loaded.ext.module.maasng:1235][WARNING ][3029] Ignoring parameter vlan:0
2019-06-22 05:26:42,517 [salt.utils.decorators:613 ][WARNING ][3029] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-22 05:26:45,386 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5548] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-22 05:26:45,421 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5548] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-22 05:26:45,462 [salt.utils.parsers:1051][WARNING ][381] Minion received a SIGTERM. Exiting.
2019-06-22 05:26:46,458 [salt.cli.daemons :293 ][INFO    ][5628] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-06-22 05:26:46,576 [salt.cli.daemons :82  ][INFO    ][5628] Starting up the Salt Minion
2019-06-22 05:26:46,577 [salt.utils.event :1017][INFO    ][5628] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-06-22 05:26:47,771 [salt.minion      :976 ][INFO    ][5628] Creating minion process manager
2019-06-22 05:26:49,717 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5628] Executing command ['date', '+%z'] in directory '/root'
2019-06-22 05:26:49,743 [salt.utils.schedule:568 ][INFO    ][5628] Updating job settings for scheduled job: __mine_interval
2019-06-22 05:26:49,744 [salt.minion      :1108][INFO    ][5628] Added mine.update to scheduler
2019-06-22 05:26:49,749 [salt.minion      :1975][INFO    ][5628] Minion is starting as user 'root'
2019-06-22 05:26:49,761 [salt.minion      :2336][INFO    ][5628] Minion is ready to receive requests!
2019-06-22 05:26:51,637 [salt.utils.decorators:613 ][WARNING ][5558] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-22 05:26:51,699 [salt.loaded.ext.module.maas:412 ][WARNING ][5558] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-22 05:26:53,253 [salt.loaded.ext.module.maas:412 ][WARNING ][5558] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-22 05:26:54,600 [salt.loaded.ext.module.maas:412 ][WARNING ][5558] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-22 05:26:55,891 [salt.loaded.ext.module.maas:412 ][WARNING ][5558] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-22 05:26:57,295 [salt.loaded.ext.module.maas:412 ][WARNING ][5558] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-22 05:26:58,433 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622052658421637
2019-06-22 05:26:58,451 [salt.minion      :1432][INFO    ][5997] Starting a new job with PID 5997
2019-06-22 05:26:58,476 [salt.minion      :1711][INFO    ][5997] Returning information for job: 20190622052658421637
2019-06-22 05:27:29,761 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command state.apply with jid 20190622052729740762
2019-06-22 05:27:29,787 [salt.minion      :1432][INFO    ][6047] Starting a new job with PID 6047
2019-06-22 05:27:35,677 [salt.state       :915 ][INFO    ][6047] Loading fresh modules for state activity
2019-06-22 05:27:35,730 [salt.fileclient  :1219][INFO    ][6047] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-22 05:27:35,772 [salt.state       :1780][INFO    ][6047] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:27:35.772053
2019-06-22 05:27:35,772 [salt.state       :1813][INFO    ][6047] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-22 05:27:35,774 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6047] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-22 05:27:37,501 [salt.state       :300 ][INFO    ][6047] {'pid': 6054, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-22 05:27:37,502 [salt.state       :1951][INFO    ][6047] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:27:37.502780 duration_in_ms=1730.727
2019-06-22 05:27:37,506 [salt.state       :1780][INFO    ][6047] Running state [maas.wait_for_machine_status] at time 05:27:37.506140
2019-06-22 05:27:37,506 [salt.state       :1813][INFO    ][6047] Executing state module.run for [maas.wait_for_machine_status]
2019-06-22 05:27:37,507 [salt.utils.decorators:613 ][WARNING ][6047] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-22 05:27:38,787 [salt.loaded.ext.module.maas:1023][INFO    ][6047] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.73158288s left)
2019-06-22 05:27:44,863 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622052744852511
2019-06-22 05:27:44,892 [salt.minion      :1432][INFO    ][6107] Starting a new job with PID 6107
2019-06-22 05:27:44,912 [salt.minion      :1711][INFO    ][6107] Returning information for job: 20190622052744852511
2019-06-22 05:28:09,634 [salt.loaded.ext.module.maas:1023][INFO    ][6047] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.88546801s left)
2019-06-22 05:28:14,924 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622052814911656
2019-06-22 05:28:14,952 [salt.minion      :1432][INFO    ][6150] Starting a new job with PID 6150
2019-06-22 05:28:14,976 [salt.minion      :1711][INFO    ][6150] Returning information for job: 20190622052814911656
2019-06-22 05:28:40,455 [salt.loaded.ext.module.maas:1023][INFO    ][6047] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.06382203s left)
2019-06-22 05:28:45,021 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622052845004671
2019-06-22 05:28:45,053 [salt.minion      :1432][INFO    ][6181] Starting a new job with PID 6181
2019-06-22 05:28:45,076 [salt.minion      :1711][INFO    ][6181] Returning information for job: 20190622052845004671
2019-06-22 05:29:11,425 [salt.loaded.ext.module.maas:1023][INFO    ][6047] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.094069s left)
2019-06-22 05:29:15,093 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622052915078351
2019-06-22 05:29:15,119 [salt.minion      :1432][INFO    ][6226] Starting a new job with PID 6226
2019-06-22 05:29:15,139 [salt.minion      :1711][INFO    ][6226] Returning information for job: 20190622052915078351
2019-06-22 05:29:42,565 [salt.loaded.ext.module.maas:1023][INFO    ][6047] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.95394993s left)
2019-06-22 05:29:45,187 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622052945172007
2019-06-22 05:29:45,213 [salt.minion      :1432][INFO    ][6284] Starting a new job with PID 6284
2019-06-22 05:29:45,235 [salt.minion      :1711][INFO    ][6284] Returning information for job: 20190622052945172007
2019-06-22 05:30:13,619 [salt.loaded.ext.module.maas:1023][INFO    ][6047] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.89940095s left)
2019-06-22 05:30:15,286 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622053015275639
2019-06-22 05:30:15,307 [salt.minion      :1432][INFO    ][6422] Starting a new job with PID 6422
2019-06-22 05:30:15,329 [salt.minion      :1711][INFO    ][6422] Returning information for job: 20190622053015275639
2019-06-22 05:30:44,855 [salt.loaded.ext.module.maas:1023][INFO    ][6047] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.66385603s left)
2019-06-22 05:30:45,384 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622053045370963
2019-06-22 05:30:45,412 [salt.minion      :1432][INFO    ][6547] Starting a new job with PID 6547
2019-06-22 05:30:45,435 [salt.minion      :1711][INFO    ][6547] Returning information for job: 20190622053045370963
2019-06-22 05:31:15,522 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622053115512516
2019-06-22 05:31:15,546 [salt.minion      :1432][INFO    ][6842] Starting a new job with PID 6842
2019-06-22 05:31:15,569 [salt.minion      :1711][INFO    ][6842] Returning information for job: 20190622053115512516
2019-06-22 05:31:16,118 [salt.loaded.ext.module.maas:1023][INFO    ][6047] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.40144205s left)
2019-06-22 05:31:45,604 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622053145588863
2019-06-22 05:31:45,621 [salt.minion      :1432][INFO    ][6937] Starting a new job with PID 6937
2019-06-22 05:31:45,644 [salt.minion      :1711][INFO    ][6937] Returning information for job: 20190622053145588863
2019-06-22 05:31:48,061 [salt.loaded.ext.module.maas:1023][INFO    ][6047] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.45776296s left)
2019-06-22 05:32:15,743 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622053215726150
2019-06-22 05:32:15,773 [salt.minion      :1432][INFO    ][7238] Starting a new job with PID 7238
2019-06-22 05:32:15,795 [salt.minion      :1711][INFO    ][7238] Returning information for job: 20190622053215726150
2019-06-22 05:32:20,152 [salt.loaded.ext.module.maas:1023][INFO    ][6047] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.36689901s left)
2019-06-22 05:32:45,856 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622053245842642
2019-06-22 05:32:45,882 [salt.minion      :1432][INFO    ][7484] Starting a new job with PID 7484
2019-06-22 05:32:45,903 [salt.minion      :1711][INFO    ][7484] Returning information for job: 20190622053245842642
2019-06-22 05:32:52,331 [salt.loaded.ext.module.maas:1023][INFO    ][6047] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.18818092s left)
2019-06-22 05:33:15,992 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622053315977548
2019-06-22 05:33:16,019 [salt.minion      :1432][INFO    ][7672] Starting a new job with PID 7672
2019-06-22 05:33:16,043 [salt.minion      :1711][INFO    ][7672] Returning information for job: 20190622053315977548
2019-06-22 05:33:24,459 [salt.loaded.ext.module.maas:1023][INFO    ][6047] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1153.06011796s left)
2019-06-22 05:33:46,107 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622053346094467
2019-06-22 05:33:46,133 [salt.minion      :1432][INFO    ][7824] Starting a new job with PID 7824
2019-06-22 05:33:46,157 [salt.minion      :1711][INFO    ][7824] Returning information for job: 20190622053346094467
2019-06-22 05:33:57,870 [salt.state       :300 ][INFO    ][6047] {'ret': True}
2019-06-22 05:33:57,870 [salt.state       :1951][INFO    ][6047] Completed state [maas.wait_for_machine_status] at time 05:33:57.870564 duration_in_ms=380364.424
2019-06-22 05:33:57,873 [salt.minion      :1711][INFO    ][6047] Returning information for job: 20190622052729740762
2019-06-22 05:33:58,484 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command state.apply with jid 20190622053358467325
2019-06-22 05:33:58,508 [salt.minion      :1432][INFO    ][7909] Starting a new job with PID 7909
2019-06-22 05:34:04,470 [salt.state       :915 ][INFO    ][7909] Loading fresh modules for state activity
2019-06-22 05:34:04,529 [salt.fileclient  :1219][INFO    ][7909] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-22 05:34:04,632 [salt.state       :1780][INFO    ][7909] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:34:04.632146
2019-06-22 05:34:04,632 [salt.state       :1813][INFO    ][7909] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-22 05:34:04,634 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7909] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-22 05:34:06,415 [salt.state       :300 ][INFO    ][7909] {'pid': 7922, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-22 05:34:06,416 [salt.state       :1951][INFO    ][7909] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:34:06.416362 duration_in_ms=1784.216
2019-06-22 05:34:06,420 [salt.state       :1780][INFO    ][7909] Running state [maas_machines_storage_cmp002_lvm] at time 05:34:06.420212
2019-06-22 05:34:06,420 [salt.state       :1813][INFO    ][7909] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-22 05:34:07,588 [salt.loaded.ext.module.maasng:610 ][INFO    ][7909] xedpfd
2019-06-22 05:34:07,588 [salt.loaded.ext.module.maasng:626 ][INFO    ][7909] sda
2019-06-22 05:34:08,180 [salt.loaded.ext.module.maasng:361 ][INFO    ][7909] xedpfd
2019-06-22 05:34:08,283 [salt.loaded.ext.module.maasng:367 ][INFO    ][7909] [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'099b4304-c44b-4440-a0a7-2863f3a71f38', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xedpfd', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a87fa9eb-ae77-4aa9-9116-f394f34a750c', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/xedpfd/blockdevices/1/partition/1'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'xedpfd', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/xedpfd/blockdevices/1/'}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'ffa3de0f-26c9-4392-ad9e-c29769a6e1ae', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xedpfd', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'4b1426f0-c208-4645-8ac6-3384fb4f5b01', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 800097042432, u'type': u'virtual', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/xedpfd/blockdevices/3/'}]
2019-06-22 05:34:08,284 [salt.loaded.ext.module.maasng:632 ][INFO    ][7909] vgroot
2019-06-22 05:34:08,284 [salt.loaded.ext.module.maasng:635 ][INFO    ][7909] lvroot
2019-06-22 05:34:08,285 [salt.loaded.ext.module.maasng:639 ][INFO    ][7909] 107374182400
2019-06-22 05:34:08,878 [salt.loaded.ext.module.maasng:645 ][INFO    ][7909] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'owner_data': {}, u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/xedpfd/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'xedpfd', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'uuid': u'2d1fc834-353a-482d-9ddd-2c9b95c11f19', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'id': 11, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'xedpfd', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'4b8f7284-1c6b-4c52-8bac-15a3d001bf1a', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/xedpfd/blockdevices/11/', u'name': u'vgroot-lvroot'}], u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'0eced329-62c7-48b1-81e6-51a8677f4221', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xedpfd', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd04855e8-2bb6-4f11-b2db-c46848bf3447', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/xedpfd/blockdevices/1/partition/6'}], u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'xedpfd', 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'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/xedpfd/blockdevices/1/', u'name': u'sda'}, {u'size': 107374182400, u'model': None, u'uuid': u'2d1fc834-353a-482d-9ddd-2c9b95c11f19', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'id': 11, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'xedpfd', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'4b8f7284-1c6b-4c52-8bac-15a3d001bf1a', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/xedpfd/blockdevices/11/', u'name': u'vgroot-lvroot'}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'0eced329-62c7-48b1-81e6-51a8677f4221', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xedpfd', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd04855e8-2bb6-4f11-b2db-c46848bf3447', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/xedpfd/blockdevices/1/partition/6'}], u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'xedpfd', 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'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/xedpfd/blockdevices/1/', u'name': u'sda'}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'xedpfd', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'0eced329-62c7-48b1-81e6-51a8677f4221', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xedpfd', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd04855e8-2bb6-4f11-b2db-c46848bf3447', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/xedpfd/blockdevices/1/partition/6'}], u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'xedpfd', 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'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/xedpfd/blockdevices/1/', u'name': u'sda'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'yq8mrs', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'yq8mrs', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'yq8mrs', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'children': [], u'system_id': u'xedpfd', u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/xedpfd/interfaces/4/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'xedpfd', u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/xedpfd/interfaces/15/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'xedpfd', u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/xedpfd/interfaces/13/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'yq8mrs', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'yq8mrs', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'yq8mrs', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'children': [], u'system_id': u'xedpfd', u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/xedpfd/interfaces/14/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 22}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'xedpfd', u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/xedpfd/interfaces/11/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 23}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'xedpfd', u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/xedpfd/interfaces/12/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'yq8mrs', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'yq8mrs', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'yq8mrs', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'children': [], u'system_id': u'xedpfd', u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/xedpfd/interfaces/4/'}, u'current_commissioning_result_id': 2, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-06-22 05:34:08,881 [salt.state       :300 ][INFO    ][7909] {'new': {'storage_layout': 'lvm'}}
2019-06-22 05:34:08,881 [salt.state       :1951][INFO    ][7909] Completed state [maas_machines_storage_cmp002_lvm] at time 05:34:08.881486 duration_in_ms=2461.272
2019-06-22 05:34:08,883 [salt.state       :1780][INFO    ][7909] Running state [maas_machines_storage_cmp001_lvm] at time 05:34:08.883940
2019-06-22 05:34:08,884 [salt.state       :1813][INFO    ][7909] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-22 05:34:10,012 [salt.loaded.ext.module.maasng:610 ][INFO    ][7909] dhg6rx
2019-06-22 05:34:10,013 [salt.loaded.ext.module.maasng:626 ][INFO    ][7909] sda
2019-06-22 05:34:10,614 [salt.loaded.ext.module.maasng:361 ][INFO    ][7909] dhg6rx
2019-06-22 05:34:10,711 [salt.loaded.ext.module.maasng:367 ][INFO    ][7909] [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'52b73506-62c2-4793-b70e-f937437ccdd1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dhg6rx', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'bb10ef5e-634a-4eaf-afcc-64c88b8c813b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/dhg6rx/blockdevices/2/partition/2'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'dhg6rx', 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'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/dhg6rx/blockdevices/2/'}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'e6ecf7d6-5b63-4387-abfa-ff81a80e03d8', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dhg6rx', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'a2951575-d6bf-4e61-877a-dc4db3cfd35e', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 800097042432, u'type': u'virtual', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/dhg6rx/blockdevices/4/'}]
2019-06-22 05:34:10,712 [salt.loaded.ext.module.maasng:632 ][INFO    ][7909] vgroot
2019-06-22 05:34:10,712 [salt.loaded.ext.module.maasng:635 ][INFO    ][7909] lvroot
2019-06-22 05:34:10,712 [salt.loaded.ext.module.maasng:639 ][INFO    ][7909] 107374182400
2019-06-22 05:34:11,317 [salt.loaded.ext.module.maasng:645 ][INFO    ][7909] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', 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'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'yq8mrs', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'yq8mrs', 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'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'yq8mrs', 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'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'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'dhg6rx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dhg6rx/interfaces/5/'}, u'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/dhg6rx/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'dhg6rx', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'physicalblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'd647495d-cee2-4956-98dc-30004d5daf83', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dhg6rx', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'e03aeb84-7d0a-4b8e-b46f-503a8d7b0666', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/dhg6rx/blockdevices/2/partition/7', 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'dhg6rx', 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'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/dhg6rx/blockdevices/2/'}], u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'd647495d-cee2-4956-98dc-30004d5daf83', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dhg6rx', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'e03aeb84-7d0a-4b8e-b46f-503a8d7b0666', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/dhg6rx/blockdevices/2/partition/7', 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'dhg6rx', 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'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/dhg6rx/blockdevices/2/'}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'f52bdaef-9932-4dbf-9b58-3e5eda042e12', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dhg6rx', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'e869a10e-7542-4d88-b598-5fb0cfe3a366', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dhg6rx/blockdevices/12/'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'dhg6rx', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'memory_test_status_name': u'Unknown', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'virtualblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'f52bdaef-9932-4dbf-9b58-3e5eda042e12', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dhg6rx', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'e869a10e-7542-4d88-b598-5fb0cfe3a366', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dhg6rx/blockdevices/12/'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'boot_disk': {u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'd647495d-cee2-4956-98dc-30004d5daf83', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dhg6rx', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'e03aeb84-7d0a-4b8e-b46f-503a8d7b0666', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/dhg6rx/blockdevices/2/partition/7', 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'dhg6rx', 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'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/dhg6rx/blockdevices/2/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'yq8mrs', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'yq8mrs', 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'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'yq8mrs', 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'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'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'dhg6rx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dhg6rx/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 18, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'dhg6rx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dhg6rx/interfaces/18/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 20, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'dhg6rx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dhg6rx/interfaces/20/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'yq8mrs', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'yq8mrs', 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'enabled': True, u'id': 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'yq8mrs', 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'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'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'dhg6rx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dhg6rx/interfaces/16/'}, {u'name': u'ens2f1', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 17, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'dhg6rx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dhg6rx/interfaces/17/'}, {u'name': u'ens2f0', u'links': [{u'id': 29, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 19, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'dhg6rx', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dhg6rx/interfaces/19/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-06-22 05:34:11,320 [salt.state       :300 ][INFO    ][7909] {'new': {'storage_layout': 'lvm'}}
2019-06-22 05:34:11,320 [salt.state       :1951][INFO    ][7909] Completed state [maas_machines_storage_cmp001_lvm] at time 05:34:11.320793 duration_in_ms=2436.852
2019-06-22 05:34:11,324 [salt.minion      :1711][INFO    ][7909] Returning information for job: 20190622053358467325
2019-06-22 05:34:11,889 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command state.apply with jid 20190622053411873980
2019-06-22 05:34:11,913 [salt.minion      :1432][INFO    ][7950] Starting a new job with PID 7950
2019-06-22 05:34:12,885 [salt.state       :915 ][INFO    ][7950] Loading fresh modules for state activity
2019-06-22 05:34:12,943 [salt.fileclient  :1219][INFO    ][7950] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-22 05:34:12,998 [salt.state       :1780][INFO    ][7950] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:34:12.998842
2019-06-22 05:34:12,999 [salt.state       :1813][INFO    ][7950] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-22 05:34:13,000 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7950] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-22 05:34:14,715 [salt.state       :300 ][INFO    ][7950] {'pid': 7961, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-22 05:34:14,716 [salt.state       :1951][INFO    ][7950] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:34:14.716679 duration_in_ms=1717.836
2019-06-22 05:34:14,719 [salt.state       :1780][INFO    ][7950] Running state [maas.deploy_machines] at time 05:34:14.719641
2019-06-22 05:34:14,720 [salt.state       :1813][INFO    ][7950] Executing state module.run for [maas.deploy_machines]
2019-06-22 05:34:14,721 [salt.utils.decorators:613 ][WARNING ][7950] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-22 05:34:15,316 [salt.loaded.ext.module.maas:684 ][INFO    ][7950] deploymachines hwe_kernel=hwe-16.04 system_id=xedpfd distro_series=xenial
2019-06-22 05:34:17,879 [salt.loaded.ext.module.maas:684 ][INFO    ][7950] deploymachines hwe_kernel=hwe-16.04 system_id=dhg6rx distro_series=xenial
2019-06-22 05:34:20,425 [salt.loaded.ext.module.maas:684 ][INFO    ][7950] deploymachines hwe_kernel=hwe-16.04 system_id=h3wbe7 distro_series=xenial
2019-06-22 05:34:23,123 [salt.loaded.ext.module.maas:684 ][INFO    ][7950] deploymachines hwe_kernel=hwe-16.04 system_id=68d3sm distro_series=xenial
2019-06-22 05:34:25,736 [salt.loaded.ext.module.maas:684 ][INFO    ][7950] deploymachines hwe_kernel=hwe-16.04 system_id=dmwr6t distro_series=xenial
2019-06-22 05:34:26,976 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622053426963141
2019-06-22 05:34:26,999 [salt.minion      :1432][INFO    ][8219] Starting a new job with PID 8219
2019-06-22 05:34:27,028 [salt.minion      :1711][INFO    ][8219] Returning information for job: 20190622053426963141
2019-06-22 05:34:28,220 [salt.state       :300 ][INFO    ][7950] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-22 05:34:28,221 [salt.state       :1951][INFO    ][7950] Completed state [maas.deploy_machines] at time 05:34:28.221297 duration_in_ms=13501.656
2019-06-22 05:34:28,225 [salt.minion      :1711][INFO    ][7950] Returning information for job: 20190622053411873980
2019-06-22 05:34:28,800 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command state.apply with jid 20190622053428788512
2019-06-22 05:34:28,828 [salt.minion      :1432][INFO    ][8246] Starting a new job with PID 8246
2019-06-22 05:34:34,821 [salt.state       :915 ][INFO    ][8246] Loading fresh modules for state activity
2019-06-22 05:34:34,872 [salt.fileclient  :1219][INFO    ][8246] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-22 05:34:34,914 [salt.state       :1780][INFO    ][8246] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:34:34.914306
2019-06-22 05:34:34,914 [salt.state       :1813][INFO    ][8246] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-22 05:34:34,916 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8246] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-22 05:34:36,678 [salt.state       :300 ][INFO    ][8246] {'pid': 8263, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-22 05:34:36,679 [salt.state       :1951][INFO    ][8246] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:34:36.679631 duration_in_ms=1765.325
2019-06-22 05:34:36,683 [salt.state       :1780][INFO    ][8246] Running state [maas.wait_for_machine_status] at time 05:34:36.683535
2019-06-22 05:34:36,684 [salt.state       :1813][INFO    ][8246] Executing state module.run for [maas.wait_for_machine_status]
2019-06-22 05:34:36,684 [salt.utils.decorators:613 ][WARNING ][8246] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-22 05:34:39,608 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.086555s left)
2019-06-22 05:34:43,893 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622053443882606
2019-06-22 05:34:43,915 [salt.minion      :1432][INFO    ][8289] Starting a new job with PID 8289
2019-06-22 05:34:43,937 [salt.minion      :1711][INFO    ][8289] Returning information for job: 20190622053443882606
2019-06-22 05:35:12,711 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.98298597s left)
2019-06-22 05:35:13,969 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622053513953334
2019-06-22 05:35:13,996 [salt.minion      :1432][INFO    ][8341] Starting a new job with PID 8341
2019-06-22 05:35:14,018 [salt.minion      :1711][INFO    ][8341] Returning information for job: 20190622053513953334
2019-06-22 05:35:44,048 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622053544040531
2019-06-22 05:35:44,063 [salt.minion      :1432][INFO    ][8378] Starting a new job with PID 8378
2019-06-22 05:35:44,083 [salt.minion      :1711][INFO    ][8378] Returning information for job: 20190622053544040531
2019-06-22 05:35:45,452 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.24275899s left)
2019-06-22 05:36:14,116 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622053614099308
2019-06-22 05:36:14,144 [salt.minion      :1432][INFO    ][8431] Starting a new job with PID 8431
2019-06-22 05:36:14,171 [salt.minion      :1711][INFO    ][8431] Returning information for job: 20190622053614099308
2019-06-22 05:36:18,479 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.21548104s left)
2019-06-22 05:36:44,196 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622053644189247
2019-06-22 05:36:44,220 [salt.minion      :1432][INFO    ][8464] Starting a new job with PID 8464
2019-06-22 05:36:44,243 [salt.minion      :1711][INFO    ][8464] Returning information for job: 20190622053644189247
2019-06-22 05:36:51,221 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.47347689s left)
2019-06-22 05:37:14,279 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622053714260185
2019-06-22 05:37:14,309 [salt.minion      :1432][INFO    ][8526] Starting a new job with PID 8526
2019-06-22 05:37:14,329 [salt.minion      :1711][INFO    ][8526] Returning information for job: 20190622053714260185
2019-06-22 05:37:24,114 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.58072805s left)
2019-06-22 05:37:44,365 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622053744356984
2019-06-22 05:37:44,386 [salt.minion      :1432][INFO    ][8635] Starting a new job with PID 8635
2019-06-22 05:37:44,409 [salt.minion      :1711][INFO    ][8635] Returning information for job: 20190622053744356984
2019-06-22 05:37:57,500 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.19454002s left)
2019-06-22 05:38:14,469 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622053814457655
2019-06-22 05:38:14,501 [salt.minion      :1432][INFO    ][8839] Starting a new job with PID 8839
2019-06-22 05:38:14,523 [salt.minion      :1711][INFO    ][8839] Returning information for job: 20190622053814457655
2019-06-22 05:38:30,512 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.18204808s left)
2019-06-22 05:38:44,568 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622053844556462
2019-06-22 05:38:44,589 [salt.minion      :1432][INFO    ][8971] Starting a new job with PID 8971
2019-06-22 05:38:44,615 [salt.minion      :1711][INFO    ][8971] Returning information for job: 20190622053844556462
2019-06-22 05:39:03,281 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.41352701s left)
2019-06-22 05:39:14,692 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622053914676717
2019-06-22 05:39:14,716 [salt.minion      :1432][INFO    ][9210] Starting a new job with PID 9210
2019-06-22 05:39:14,739 [salt.minion      :1711][INFO    ][9210] Returning information for job: 20190622053914676717
2019-06-22 05:39:36,397 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.29768205s left)
2019-06-22 05:39:44,815 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622053944799625
2019-06-22 05:39:44,841 [salt.minion      :1432][INFO    ][9324] Starting a new job with PID 9324
2019-06-22 05:39:44,862 [salt.minion      :1711][INFO    ][9324] Returning information for job: 20190622053944799625
2019-06-22 05:40:09,905 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.78949594s left)
2019-06-22 05:40:14,977 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622054014969369
2019-06-22 05:40:15,005 [salt.minion      :1432][INFO    ][9712] Starting a new job with PID 9712
2019-06-22 05:40:15,027 [salt.minion      :1711][INFO    ][9712] Returning information for job: 20190622054014969369
2019-06-22 05:40:42,939 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.75704193s left)
2019-06-22 05:40:45,106 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622054045094989
2019-06-22 05:40:45,136 [salt.minion      :1432][INFO    ][9863] Starting a new job with PID 9863
2019-06-22 05:40:45,160 [salt.minion      :1711][INFO    ][9863] Returning information for job: 20190622054045094989
2019-06-22 05:41:15,247 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622054115237112
2019-06-22 05:41:15,264 [salt.minion      :1432][INFO    ][10125] Starting a new job with PID 10125
2019-06-22 05:41:15,286 [salt.minion      :1711][INFO    ][10125] Returning information for job: 20190622054115237112
2019-06-22 05:41:15,991 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.70380402s left)
2019-06-22 05:41:45,403 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622054145387272
2019-06-22 05:41:45,432 [salt.minion      :1432][INFO    ][10215] Starting a new job with PID 10215
2019-06-22 05:41:45,460 [salt.minion      :1711][INFO    ][10215] Returning information for job: 20190622054145387272
2019-06-22 05:41:48,966 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.72840691s left)
2019-06-22 05:42:15,579 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622054215567013
2019-06-22 05:42:15,595 [salt.minion      :1432][INFO    ][10401] Starting a new job with PID 10401
2019-06-22 05:42:15,620 [salt.minion      :1711][INFO    ][10401] Returning information for job: 20190622054215567013
2019-06-22 05:42:22,008 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1784.68601608s left)
2019-06-22 05:42:45,723 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622054245709466
2019-06-22 05:42:45,743 [salt.minion      :1432][INFO    ][10505] Starting a new job with PID 10505
2019-06-22 05:42:45,766 [salt.minion      :1711][INFO    ][10505] Returning information for job: 20190622054245709466
2019-06-22 05:42:54,816 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1751.87842202s left)
2019-06-22 05:43:15,895 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622054315881626
2019-06-22 05:43:15,922 [salt.minion      :1432][INFO    ][10695] Starting a new job with PID 10695
2019-06-22 05:43:15,944 [salt.minion      :1711][INFO    ][10695] Returning information for job: 20190622054315881626
2019-06-22 05:43:27,737 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1718.95769691s left)
2019-06-22 05:43:46,063 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622054346046855
2019-06-22 05:43:46,093 [salt.minion      :1432][INFO    ][10782] Starting a new job with PID 10782
2019-06-22 05:43:46,115 [salt.minion      :1711][INFO    ][10782] Returning information for job: 20190622054346046855
2019-06-22 05:44:00,997 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1685.69769597s left)
2019-06-22 05:44:16,245 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622054416233367
2019-06-22 05:44:16,275 [salt.minion      :1432][INFO    ][10937] Starting a new job with PID 10937
2019-06-22 05:44:16,299 [salt.minion      :1711][INFO    ][10937] Returning information for job: 20190622054416233367
2019-06-22 05:44:33,889 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1652.80581594s left)
2019-06-22 05:44:46,460 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622054446447506
2019-06-22 05:44:46,489 [salt.minion      :1432][INFO    ][11041] Starting a new job with PID 11041
2019-06-22 05:44:46,509 [salt.minion      :1711][INFO    ][11041] Returning information for job: 20190622054446447506
2019-06-22 05:45:06,982 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1619.71205211s left)
2019-06-22 05:45:16,651 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622054516639670
2019-06-22 05:45:16,674 [salt.minion      :1432][INFO    ][11147] Starting a new job with PID 11147
2019-06-22 05:45:16,702 [salt.minion      :1711][INFO    ][11147] Returning information for job: 20190622054516639670
2019-06-22 05:45:39,871 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1586.82300091s left)
2019-06-22 05:45:46,832 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622054546816017
2019-06-22 05:45:46,863 [salt.minion      :1432][INFO    ][11335] Starting a new job with PID 11335
2019-06-22 05:45:46,882 [salt.minion      :1711][INFO    ][11335] Returning information for job: 20190622054546816017
2019-06-22 05:46:12,816 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1553.87829208s left)
2019-06-22 05:46:17,036 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622054617021262
2019-06-22 05:46:17,067 [salt.minion      :1432][INFO    ][11394] Starting a new job with PID 11394
2019-06-22 05:46:17,089 [salt.minion      :1711][INFO    ][11394] Returning information for job: 20190622054617021262
2019-06-22 05:46:45,919 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1520.774822s left)
2019-06-22 05:46:47,238 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622054647224199
2019-06-22 05:46:47,263 [salt.minion      :1432][INFO    ][11450] Starting a new job with PID 11450
2019-06-22 05:46:47,291 [salt.minion      :1711][INFO    ][11450] Returning information for job: 20190622054647224199
2019-06-22 05:47:17,339 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622054717330666
2019-06-22 05:47:17,357 [salt.minion      :1432][INFO    ][11545] Starting a new job with PID 11545
2019-06-22 05:47:17,385 [salt.minion      :1711][INFO    ][11545] Returning information for job: 20190622054717330666
2019-06-22 05:47:19,020 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1487.67421699s left)
2019-06-22 05:47:47,360 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622054747351357
2019-06-22 05:47:47,381 [salt.minion      :1432][INFO    ][11609] Starting a new job with PID 11609
2019-06-22 05:47:47,406 [salt.minion      :1711][INFO    ][11609] Returning information for job: 20190622054747351357
2019-06-22 05:47:52,007 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1454.68761897s left)
2019-06-22 05:48:17,409 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622054817393255
2019-06-22 05:48:17,439 [salt.minion      :1432][INFO    ][11830] Starting a new job with PID 11830
2019-06-22 05:48:17,460 [salt.minion      :1711][INFO    ][11830] Returning information for job: 20190622054817393255
2019-06-22 05:48:24,839 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1421.85545397s left)
2019-06-22 05:48:47,446 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622054847432010
2019-06-22 05:48:47,479 [salt.minion      :1432][INFO    ][11875] Starting a new job with PID 11875
2019-06-22 05:48:47,501 [salt.minion      :1711][INFO    ][11875] Returning information for job: 20190622054847432010
2019-06-22 05:48:57,808 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1388.88631296s left)
2019-06-22 05:49:17,481 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622054917460679
2019-06-22 05:49:17,509 [salt.minion      :1432][INFO    ][11915] Starting a new job with PID 11915
2019-06-22 05:49:17,534 [salt.minion      :1711][INFO    ][11915] Returning information for job: 20190622054917460679
2019-06-22 05:49:30,961 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1355.73334599s left)
2019-06-22 05:49:47,545 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622054947529179
2019-06-22 05:49:47,575 [salt.minion      :1432][INFO    ][11956] Starting a new job with PID 11956
2019-06-22 05:49:47,598 [salt.minion      :1711][INFO    ][11956] Returning information for job: 20190622054947529179
2019-06-22 05:50:03,790 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1322.904495s left)
2019-06-22 05:50:17,628 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622055017614903
2019-06-22 05:50:17,651 [salt.minion      :1432][INFO    ][11997] Starting a new job with PID 11997
2019-06-22 05:50:17,676 [salt.minion      :1711][INFO    ][11997] Returning information for job: 20190622055017614903
2019-06-22 05:50:36,695 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1289.99909401s left)
2019-06-22 05:50:47,694 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622055047677258
2019-06-22 05:50:47,723 [salt.minion      :1432][INFO    ][12030] Starting a new job with PID 12030
2019-06-22 05:50:47,743 [salt.minion      :1711][INFO    ][12030] Returning information for job: 20190622055047677258
2019-06-22 05:51:09,573 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1257.12089205s left)
2019-06-22 05:51:17,788 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622055117770682
2019-06-22 05:51:17,818 [salt.minion      :1432][INFO    ][12072] Starting a new job with PID 12072
2019-06-22 05:51:17,838 [salt.minion      :1711][INFO    ][12072] Returning information for job: 20190622055117770682
2019-06-22 05:51:42,368 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1224.3259089s left)
2019-06-22 05:51:47,878 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622055147863702
2019-06-22 05:51:47,908 [salt.minion      :1432][INFO    ][12116] Starting a new job with PID 12116
2019-06-22 05:51:47,933 [salt.minion      :1711][INFO    ][12116] Returning information for job: 20190622055147863702
2019-06-22 05:52:15,140 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1191.55383611s left)
2019-06-22 05:52:17,983 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622055217970332
2019-06-22 05:52:18,015 [salt.minion      :1432][INFO    ][12159] Starting a new job with PID 12159
2019-06-22 05:52:18,043 [salt.minion      :1711][INFO    ][12159] Returning information for job: 20190622055217970332
2019-06-22 05:52:48,049 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1158.64532995s left)
2019-06-22 05:52:48,114 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622055248102926
2019-06-22 05:52:48,132 [salt.minion      :1432][INFO    ][12193] Starting a new job with PID 12193
2019-06-22 05:52:48,161 [salt.minion      :1711][INFO    ][12193] Returning information for job: 20190622055248102926
2019-06-22 05:53:18,255 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622055318245163
2019-06-22 05:53:18,282 [salt.minion      :1432][INFO    ][12236] Starting a new job with PID 12236
2019-06-22 05:53:18,304 [salt.minion      :1711][INFO    ][12236] Returning information for job: 20190622055318245163
2019-06-22 05:53:20,814 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1125.879987s left)
2019-06-22 05:53:48,403 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622055348388613
2019-06-22 05:53:48,432 [salt.minion      :1432][INFO    ][12268] Starting a new job with PID 12268
2019-06-22 05:53:48,454 [salt.minion      :1711][INFO    ][12268] Returning information for job: 20190622055348388613
2019-06-22 05:53:53,807 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1092.88741803s left)
2019-06-22 05:54:18,555 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622055418538494
2019-06-22 05:54:18,574 [salt.minion      :1432][INFO    ][12311] Starting a new job with PID 12311
2019-06-22 05:54:18,596 [salt.minion      :1711][INFO    ][12311] Returning information for job: 20190622055418538494
2019-06-22 05:54:26,682 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1060.01223207s left)
2019-06-22 05:54:48,732 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622055448716505
2019-06-22 05:54:48,760 [salt.minion      :1432][INFO    ][12344] Starting a new job with PID 12344
2019-06-22 05:54:48,786 [salt.minion      :1711][INFO    ][12344] Returning information for job: 20190622055448716505
2019-06-22 05:54:59,614 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1027.079844s left)
2019-06-22 05:55:18,944 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622055518933496
2019-06-22 05:55:18,969 [salt.minion      :1432][INFO    ][12389] Starting a new job with PID 12389
2019-06-22 05:55:18,995 [salt.minion      :1711][INFO    ][12389] Returning information for job: 20190622055518933496
2019-06-22 05:55:32,388 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (994.306075096s left)
2019-06-22 05:55:49,148 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622055549136884
2019-06-22 05:55:49,179 [salt.minion      :1432][INFO    ][12431] Starting a new job with PID 12431
2019-06-22 05:55:49,205 [salt.minion      :1711][INFO    ][12431] Returning information for job: 20190622055549136884
2019-06-22 05:56:05,337 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (961.357578039s left)
2019-06-22 05:56:19,171 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622055619157456
2019-06-22 05:56:19,198 [salt.minion      :1432][INFO    ][12491] Starting a new job with PID 12491
2019-06-22 05:56:19,224 [salt.minion      :1711][INFO    ][12491] Returning information for job: 20190622055619157456
2019-06-22 05:56:38,219 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (928.475641012s left)
2019-06-22 05:56:49,194 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622055649179027
2019-06-22 05:56:49,222 [salt.minion      :1432][INFO    ][12527] Starting a new job with PID 12527
2019-06-22 05:56:49,245 [salt.minion      :1711][INFO    ][12527] Returning information for job: 20190622055649179027
2019-06-22 05:57:10,929 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (895.765192986s left)
2019-06-22 05:57:19,257 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622055719241075
2019-06-22 05:57:19,282 [salt.minion      :1432][INFO    ][12570] Starting a new job with PID 12570
2019-06-22 05:57:19,311 [salt.minion      :1711][INFO    ][12570] Returning information for job: 20190622055719241075
2019-06-22 05:57:43,854 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (862.840540886s left)
2019-06-22 05:57:49,319 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622055749305051
2019-06-22 05:57:49,347 [salt.minion      :1432][INFO    ][12610] Starting a new job with PID 12610
2019-06-22 05:57:49,370 [salt.minion      :1711][INFO    ][12610] Returning information for job: 20190622055749305051
2019-06-22 05:58:16,708 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (829.986414909s left)
2019-06-22 05:58:19,407 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622055819392406
2019-06-22 05:58:19,437 [salt.minion      :1432][INFO    ][12650] Starting a new job with PID 12650
2019-06-22 05:58:19,461 [salt.minion      :1711][INFO    ][12650] Returning information for job: 20190622055819392406
2019-06-22 05:58:49,488 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (797.206315994s left)
2019-06-22 05:58:49,501 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622055849485092
2019-06-22 05:58:49,526 [salt.minion      :1432][INFO    ][12684] Starting a new job with PID 12684
2019-06-22 05:58:49,551 [salt.minion      :1711][INFO    ][12684] Returning information for job: 20190622055849485092
2019-06-22 05:59:19,666 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622055919653679
2019-06-22 05:59:19,690 [salt.minion      :1432][INFO    ][12723] Starting a new job with PID 12723
2019-06-22 05:59:19,711 [salt.minion      :1711][INFO    ][12723] Returning information for job: 20190622055919653679
2019-06-22 05:59:22,365 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (764.3297019s left)
2019-06-22 05:59:49,799 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622055949792176
2019-06-22 05:59:49,819 [salt.minion      :1432][INFO    ][12756] Starting a new job with PID 12756
2019-06-22 05:59:49,843 [salt.minion      :1711][INFO    ][12756] Returning information for job: 20190622055949792176
2019-06-22 05:59:55,292 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (731.402457952s left)
2019-06-22 06:00:19,942 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622060019928649
2019-06-22 06:00:19,966 [salt.minion      :1432][INFO    ][12801] Starting a new job with PID 12801
2019-06-22 06:00:19,988 [salt.minion      :1711][INFO    ][12801] Returning information for job: 20190622060019928649
2019-06-22 06:00:28,300 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (698.394480944s left)
2019-06-22 06:00:50,104 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622060050090784
2019-06-22 06:00:50,130 [salt.minion      :1432][INFO    ][12837] Starting a new job with PID 12837
2019-06-22 06:00:50,155 [salt.minion      :1711][INFO    ][12837] Returning information for job: 20190622060050090784
2019-06-22 06:01:01,141 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (665.553634882s left)
2019-06-22 06:01:20,314 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622060120302467
2019-06-22 06:01:20,347 [salt.minion      :1432][INFO    ][12878] Starting a new job with PID 12878
2019-06-22 06:01:20,372 [salt.minion      :1711][INFO    ][12878] Returning information for job: 20190622060120302467
2019-06-22 06:01:33,928 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (632.766249895s left)
2019-06-22 06:01:50,341 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622060150330920
2019-06-22 06:01:50,368 [salt.minion      :1432][INFO    ][12913] Starting a new job with PID 12913
2019-06-22 06:01:50,391 [salt.minion      :1711][INFO    ][12913] Returning information for job: 20190622060150330920
2019-06-22 06:02:06,673 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (600.021118879s left)
2019-06-22 06:02:20,552 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622060220543506
2019-06-22 06:02:20,579 [salt.minion      :1432][INFO    ][12954] Starting a new job with PID 12954
2019-06-22 06:02:20,600 [salt.minion      :1711][INFO    ][12954] Returning information for job: 20190622060220543506
2019-06-22 06:02:39,400 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (567.294641972s left)
2019-06-22 06:02:50,617 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622060250602701
2019-06-22 06:02:50,646 [salt.minion      :1432][INFO    ][12989] Starting a new job with PID 12989
2019-06-22 06:02:50,668 [salt.minion      :1711][INFO    ][12989] Returning information for job: 20190622060250602701
2019-06-22 06:03:12,288 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (534.405778885s left)
2019-06-22 06:03:20,659 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622060320639081
2019-06-22 06:03:20,686 [salt.minion      :1432][INFO    ][13031] Starting a new job with PID 13031
2019-06-22 06:03:20,716 [salt.minion      :1711][INFO    ][13031] Returning information for job: 20190622060320639081
2019-06-22 06:03:45,253 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (501.441673994s left)
2019-06-22 06:03:50,776 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622060350760285
2019-06-22 06:03:50,801 [salt.minion      :1432][INFO    ][13064] Starting a new job with PID 13064
2019-06-22 06:03:50,829 [salt.minion      :1711][INFO    ][13064] Returning information for job: 20190622060350760285
2019-06-22 06:04:18,120 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (468.574126959s left)
2019-06-22 06:04:20,889 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622060420877367
2019-06-22 06:04:20,913 [salt.minion      :1432][INFO    ][13105] Starting a new job with PID 13105
2019-06-22 06:04:20,936 [salt.minion      :1711][INFO    ][13105] Returning information for job: 20190622060420877367
2019-06-22 06:04:50,878 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (435.81595993s left)
2019-06-22 06:04:51,039 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622060451025619
2019-06-22 06:04:51,069 [salt.minion      :1432][INFO    ][13141] Starting a new job with PID 13141
2019-06-22 06:04:51,104 [salt.minion      :1711][INFO    ][13141] Returning information for job: 20190622060451025619
2019-06-22 06:05:21,177 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622060521169316
2019-06-22 06:05:21,197 [salt.minion      :1432][INFO    ][13182] Starting a new job with PID 13182
2019-06-22 06:05:21,222 [salt.minion      :1711][INFO    ][13182] Returning information for job: 20190622060521169316
2019-06-22 06:05:23,747 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (402.94689393s left)
2019-06-22 06:05:51,376 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622060551361282
2019-06-22 06:05:51,408 [salt.minion      :1432][INFO    ][13353] Starting a new job with PID 13353
2019-06-22 06:05:51,434 [salt.minion      :1711][INFO    ][13353] Returning information for job: 20190622060551361282
2019-06-22 06:05:56,607 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (370.087608099s left)
2019-06-22 06:06:21,576 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622060621564194
2019-06-22 06:06:21,597 [salt.minion      :1432][INFO    ][13408] Starting a new job with PID 13408
2019-06-22 06:06:21,626 [salt.minion      :1711][INFO    ][13408] Returning information for job: 20190622060621564194
2019-06-22 06:06:29,533 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (337.161195993s left)
2019-06-22 06:06:51,801 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622060651783847
2019-06-22 06:06:51,831 [salt.minion      :1432][INFO    ][13441] Starting a new job with PID 13441
2019-06-22 06:06:51,867 [salt.minion      :1711][INFO    ][13441] Returning information for job: 20190622060651783847
2019-06-22 06:07:02,403 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (304.291007996s left)
2019-06-22 06:07:21,858 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622060721842536
2019-06-22 06:07:21,888 [salt.minion      :1432][INFO    ][13487] Starting a new job with PID 13487
2019-06-22 06:07:21,918 [salt.minion      :1711][INFO    ][13487] Returning information for job: 20190622060721842536
2019-06-22 06:07:35,253 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (271.441402912s left)
2019-06-22 06:07:52,091 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622060752077867
2019-06-22 06:07:52,121 [salt.minion      :1432][INFO    ][13525] Starting a new job with PID 13525
2019-06-22 06:07:52,183 [salt.minion      :1711][INFO    ][13525] Returning information for job: 20190622060752077867
2019-06-22 06:08:08,015 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (238.679086924s left)
2019-06-22 06:08:22,207 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622060822192622
2019-06-22 06:08:22,237 [salt.minion      :1432][INFO    ][13568] Starting a new job with PID 13568
2019-06-22 06:08:22,269 [salt.minion      :1711][INFO    ][13568] Returning information for job: 20190622060822192622
2019-06-22 06:08:40,799 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (205.895241022s left)
2019-06-22 06:08:52,255 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622060852239660
2019-06-22 06:08:52,280 [salt.minion      :1432][INFO    ][13601] Starting a new job with PID 13601
2019-06-22 06:08:52,310 [salt.minion      :1711][INFO    ][13601] Returning information for job: 20190622060852239660
2019-06-22 06:09:13,696 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (172.997806072s left)
2019-06-22 06:09:22,380 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622060922364351
2019-06-22 06:09:22,409 [salt.minion      :1432][INFO    ][13642] Starting a new job with PID 13642
2019-06-22 06:09:22,449 [salt.minion      :1711][INFO    ][13642] Returning information for job: 20190622060922364351
2019-06-22 06:09:46,511 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (140.183444977s left)
2019-06-22 06:09:52,504 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622060952488462
2019-06-22 06:09:52,534 [salt.minion      :1432][INFO    ][13679] Starting a new job with PID 13679
2019-06-22 06:09:52,565 [salt.minion      :1711][INFO    ][13679] Returning information for job: 20190622060952488462
2019-06-22 06:10:19,252 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (107.442126989s left)
2019-06-22 06:10:22,676 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622061022664548
2019-06-22 06:10:22,707 [salt.minion      :1432][INFO    ][13722] Starting a new job with PID 13722
2019-06-22 06:10:22,736 [salt.minion      :1711][INFO    ][13722] Returning information for job: 20190622061022664548
2019-06-22 06:10:51,983 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (74.711785078s left)
2019-06-22 06:10:52,820 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622061052808443
2019-06-22 06:10:52,848 [salt.minion      :1432][INFO    ][13758] Starting a new job with PID 13758
2019-06-22 06:10:52,877 [salt.minion      :1711][INFO    ][13758] Returning information for job: 20190622061052808443
2019-06-22 06:11:22,991 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622061122982820
2019-06-22 06:11:23,015 [salt.minion      :1432][INFO    ][13801] Starting a new job with PID 13801
2019-06-22 06:11:23,043 [salt.minion      :1711][INFO    ][13801] Returning information for job: 20190622061122982820
2019-06-22 06:11:24,886 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (41.8079578876s left)
2019-06-22 06:11:53,155 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622061153143084
2019-06-22 06:11:53,179 [salt.minion      :1432][INFO    ][13835] Starting a new job with PID 13835
2019-06-22 06:11:53,210 [salt.minion      :1711][INFO    ][13835] Returning information for job: 20190622061153143084
2019-06-22 06:11:58,027 [salt.loaded.ext.module.maas:1023][INFO    ][8246] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (8.66718697548s left)
2019-06-22 06:12:23,348 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622061223338724
2019-06-22 06:12:23,377 [salt.minion      :1432][INFO    ][13878] Starting a new job with PID 13878
2019-06-22 06:12:23,421 [salt.minion      :1711][INFO    ][13878] Returning information for job: 20190622061223338724
2019-06-22 06:12:30,761 [salt.state       :302 ][ERROR   ][8246] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-06-22 06:12:30,762 [salt.state       :1951][INFO    ][8246] Completed state [maas.wait_for_machine_status] at time 06:12:30.762292 duration_in_ms=2274078.754
2019-06-22 06:12:30,768 [salt.minion      :1711][INFO    ][8246] Returning information for job: 20190622053428788512
2019-06-22 06:12:41,720 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command pillar.get with jid 20190622061241708239
2019-06-22 06:12:41,748 [salt.minion      :1432][INFO    ][13900] Starting a new job with PID 13900
2019-06-22 06:12:41,758 [salt.minion      :1711][INFO    ][13900] Returning information for job: 20190622061241708239
2019-06-22 06:12:42,536 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command service.status with jid 20190622061242521000
2019-06-22 06:12:42,559 [salt.minion      :1432][INFO    ][13918] Starting a new job with PID 13918
2019-06-22 06:12:43,359 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13918] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-22 06:12:43,404 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13918] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-22 06:12:43,425 [salt.minion      :1711][INFO    ][13918] Returning information for job: 20190622061242521000
2019-06-22 06:12:44,148 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command state.apply with jid 20190622061244135740
2019-06-22 06:12:44,178 [salt.minion      :1432][INFO    ][13929] Starting a new job with PID 13929
2019-06-22 06:12:50,068 [salt.state       :915 ][INFO    ][13929] Loading fresh modules for state activity
2019-06-22 06:12:50,716 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command 'salt-minion --version' in directory '/root'
2019-06-22 06:12:51,094 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command 'salt-minion --version' in directory '/root'
2019-06-22 06:12:52,219 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command 'salt-minion --version' in directory '/root'
2019-06-22 06:12:52,589 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command 'salt-minion --version' in directory '/root'
2019-06-22 06:12:54,919 [salt.state       :1780][INFO    ][13929] Running state [salt-minion] at time 06:12:54.918999
2019-06-22 06:12:54,919 [salt.state       :1813][INFO    ][13929] Executing state pkg.installed for [salt-minion]
2019-06-22 06:12:54,920 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-22 06:12:55,036 [salt.state       :300 ][INFO    ][13929] All specified packages are already installed
2019-06-22 06:12:55,036 [salt.state       :1951][INFO    ][13929] Completed state [salt-minion] at time 06:12:55.036656 duration_in_ms=117.657
2019-06-22 06:12:55,037 [salt.state       :1780][INFO    ][13929] Running state [salt_minion_dependency_packages] at time 06:12:55.037047
2019-06-22 06:12:55,037 [salt.state       :1813][INFO    ][13929] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-22 06:12:55,047 [salt.state       :300 ][INFO    ][13929] All specified packages are already installed
2019-06-22 06:12:55,047 [salt.state       :1951][INFO    ][13929] Completed state [salt_minion_dependency_packages] at time 06:12:55.047698 duration_in_ms=10.65
2019-06-22 06:12:55,050 [salt.state       :1780][INFO    ][13929] Running state [/etc/salt/minion.d/minion.conf] at time 06:12:55.050913
2019-06-22 06:12:55,051 [salt.state       :1813][INFO    ][13929] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-22 06:12:55,328 [salt.state       :300 ][INFO    ][13929] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-22 06:12:55,328 [salt.state       :1951][INFO    ][13929] Completed state [/etc/salt/minion.d/minion.conf] at time 06:12:55.328654 duration_in_ms=277.741
2019-06-22 06:12:55,329 [salt.state       :1780][INFO    ][13929] Running state [python-netaddr] at time 06:12:55.328952
2019-06-22 06:12:55,329 [salt.state       :1813][INFO    ][13929] Executing state pkg.installed for [python-netaddr]
2019-06-22 06:12:55,338 [salt.state       :300 ][INFO    ][13929] All specified packages are already installed
2019-06-22 06:12:55,339 [salt.state       :1951][INFO    ][13929] Completed state [python-netaddr] at time 06:12:55.339010 duration_in_ms=10.058
2019-06-22 06:12:55,342 [salt.state       :1780][INFO    ][13929] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:12:55.342708
2019-06-22 06:12:55,343 [salt.state       :1813][INFO    ][13929] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-22 06:12:55,352 [salt.state       :300 ][INFO    ][13929] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-22 06:12:55,352 [salt.state       :1951][INFO    ][13929] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:12:55.352798 duration_in_ms=10.09
2019-06-22 06:12:55,354 [salt.state       :1780][INFO    ][13929] Running state [salt-minion] at time 06:12:55.354237
2019-06-22 06:12:55,354 [salt.state       :1813][INFO    ][13929] Executing state service.running for [salt-minion]
2019-06-22 06:12:55,355 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-22 06:12:55,396 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-22 06:12:55,412 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-22 06:12:55,424 [salt.state       :300 ][INFO    ][13929] The service salt-minion is already running
2019-06-22 06:12:55,425 [salt.state       :1951][INFO    ][13929] Completed state [salt-minion] at time 06:12:55.425223 duration_in_ms=70.985
2019-06-22 06:12:55,426 [salt.state       :1780][INFO    ][13929] Running state [/etc/salt/grains.d] at time 06:12:55.426899
2019-06-22 06:12:55,427 [salt.state       :1813][INFO    ][13929] Executing state file.directory for [/etc/salt/grains.d]
2019-06-22 06:12:55,428 [salt.state       :300 ][INFO    ][13929] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-22 06:12:55,428 [salt.state       :1951][INFO    ][13929] Completed state [/etc/salt/grains.d] at time 06:12:55.428620 duration_in_ms=1.722
2019-06-22 06:12:55,429 [salt.state       :1780][INFO    ][13929] Running state [/etc/salt/grains] at time 06:12:55.429320
2019-06-22 06:12:55,429 [salt.state       :1813][INFO    ][13929] Executing state file.managed for [/etc/salt/grains]
2019-06-22 06:12:55,432 [salt.state       :300 ][INFO    ][13929] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-22 06:12:55,432 [salt.state       :1951][INFO    ][13929] Completed state [/etc/salt/grains] at time 06:12:55.432700 duration_in_ms=3.38
2019-06-22 06:12:55,433 [salt.state       :1780][INFO    ][13929] Running state [/etc/salt/grains.d/placeholder] at time 06:12:55.433207
2019-06-22 06:12:55,433 [salt.state       :1813][INFO    ][13929] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-22 06:12:55,434 [salt.state       :300 ][INFO    ][13929] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-22 06:12:55,434 [salt.state       :1951][INFO    ][13929] Completed state [/etc/salt/grains.d/placeholder] at time 06:12:55.434605 duration_in_ms=1.398
2019-06-22 06:12:55,435 [salt.state       :1780][INFO    ][13929] Running state [/etc/salt/grains.d/sphinx] at time 06:12:55.435120
2019-06-22 06:12:55,435 [salt.state       :1813][INFO    ][13929] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-22 06:12:55,436 [salt.state       :300 ][INFO    ][13929] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-22 06:12:55,437 [salt.state       :1951][INFO    ][13929] Completed state [/etc/salt/grains.d/sphinx] at time 06:12:55.436997 duration_in_ms=1.877
2019-06-22 06:12:55,439 [salt.state       :1780][INFO    ][13929] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:12:55.439163
2019-06-22 06:12:55,439 [salt.state       :1813][INFO    ][13929] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-22 06:12:55,439 [salt.state       :300 ][INFO    ][13929] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-22 06:12:55,440 [salt.state       :1951][INFO    ][13929] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:12:55.440020 duration_in_ms=0.857
2019-06-22 06:12:55,440 [salt.state       :1780][INFO    ][13929] Running state [/etc/salt/grains.d/dns_records] at time 06:12:55.440541
2019-06-22 06:12:55,440 [salt.state       :1813][INFO    ][13929] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-22 06:12:55,444 [salt.state       :300 ][INFO    ][13929] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-22 06:12:55,444 [salt.state       :1951][INFO    ][13929] Completed state [/etc/salt/grains.d/dns_records] at time 06:12:55.444723 duration_in_ms=4.182
2019-06-22 06:12:55,446 [salt.state       :1780][INFO    ][13929] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:12:55.445690
2019-06-22 06:12:55,446 [salt.state       :1813][INFO    ][13929] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-22 06:12:55,446 [salt.state       :300 ][INFO    ][13929] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-22 06:12:55,446 [salt.state       :1951][INFO    ][13929] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:12:55.446833 duration_in_ms=1.143
2019-06-22 06:12:55,447 [salt.state       :1780][INFO    ][13929] Running state [/etc/salt/grains.d/salt] at time 06:12:55.447350
2019-06-22 06:12:55,447 [salt.state       :1813][INFO    ][13929] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-22 06:12:55,448 [salt.state       :300 ][INFO    ][13929] File /etc/salt/grains.d/salt is in the correct state
2019-06-22 06:12:55,449 [salt.state       :1951][INFO    ][13929] Completed state [/etc/salt/grains.d/salt] at time 06:12:55.448965 duration_in_ms=1.615
2019-06-22 06:12:55,449 [salt.state       :1780][INFO    ][13929] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:12:55.449902
2019-06-22 06:12:55,450 [salt.state       :1813][INFO    ][13929] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-22 06:12:55,450 [salt.state       :300 ][INFO    ][13929] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-22 06:12:55,450 [salt.state       :1951][INFO    ][13929] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:12:55.450746 duration_in_ms=0.844
2019-06-22 06:12:55,452 [salt.state       :1780][INFO    ][13929] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:12:55.452704
2019-06-22 06:12:55,453 [salt.state       :1813][INFO    ][13929] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-22 06:12:55,453 [salt.state       :300 ][INFO    ][13929] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-22 06:12:55,453 [salt.state       :1951][INFO    ][13929] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:12:55.453564 duration_in_ms=0.86
2019-06-22 06:12:55,457 [salt.state       :1780][INFO    ][13929] Running state [mine.update] at time 06:12:55.456936
2019-06-22 06:12:55,457 [salt.state       :1813][INFO    ][13929] Executing state module.wait for [mine.update]
2019-06-22 06:12:55,457 [salt.state       :300 ][INFO    ][13929] No changes made for mine.update
2019-06-22 06:12:55,458 [salt.state       :1951][INFO    ][13929] Completed state [mine.update] at time 06:12:55.458041 duration_in_ms=1.105
2019-06-22 06:12:55,458 [salt.state       :1780][INFO    ][13929] Running state [ca-certificates] at time 06:12:55.458324
2019-06-22 06:12:55,458 [salt.state       :1813][INFO    ][13929] Executing state pkg.installed for [ca-certificates]
2019-06-22 06:12:55,469 [salt.state       :300 ][INFO    ][13929] All specified packages are already installed
2019-06-22 06:12:55,469 [salt.state       :1951][INFO    ][13929] Completed state [ca-certificates] at time 06:12:55.469638 duration_in_ms=11.314
2019-06-22 06:12:55,470 [salt.state       :1780][INFO    ][13929] Running state [update-ca-certificates] at time 06:12:55.470379
2019-06-22 06:12:55,470 [salt.state       :1813][INFO    ][13929] Executing state cmd.wait for [update-ca-certificates]
2019-06-22 06:12:55,471 [salt.state       :300 ][INFO    ][13929] No changes made for update-ca-certificates
2019-06-22 06:12:55,471 [salt.state       :1951][INFO    ][13929] Completed state [update-ca-certificates] at time 06:12:55.471199 duration_in_ms=0.821
2019-06-22 06:12:55,471 [salt.state       :1780][INFO    ][13929] Running state [iptables] at time 06:12:55.471474
2019-06-22 06:12:55,471 [salt.state       :1813][INFO    ][13929] Executing state pkg.installed for [iptables]
2019-06-22 06:12:55,482 [salt.state       :300 ][INFO    ][13929] All specified packages are already installed
2019-06-22 06:12:55,482 [salt.state       :1951][INFO    ][13929] Completed state [iptables] at time 06:12:55.482396 duration_in_ms=10.922
2019-06-22 06:12:55,482 [salt.state       :1780][INFO    ][13929] Running state [iptables-persistent] at time 06:12:55.482681
2019-06-22 06:12:55,482 [salt.state       :1813][INFO    ][13929] Executing state pkg.installed for [iptables-persistent]
2019-06-22 06:12:55,493 [salt.state       :300 ][INFO    ][13929] All specified packages are already installed
2019-06-22 06:12:55,493 [salt.state       :1951][INFO    ][13929] Completed state [iptables-persistent] at time 06:12:55.493592 duration_in_ms=10.911
2019-06-22 06:12:55,495 [salt.state       :1780][INFO    ][13929] Running state [iptables_modules_v4_load] at time 06:12:55.495191
2019-06-22 06:12:55,495 [salt.state       :1813][INFO    ][13929] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-22 06:12:55,496 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command 'lsmod' in directory '/root'
2019-06-22 06:12:55,515 [salt.state       :300 ][INFO    ][13929] Kernel modules iptable_filter, ip_tables are already present
2019-06-22 06:12:55,516 [salt.state       :1951][INFO    ][13929] Completed state [iptables_modules_v4_load] at time 06:12:55.515973 duration_in_ms=20.782
2019-06-22 06:12:55,516 [salt.state       :1780][INFO    ][13929] Running state [/etc/iptables/rules.v4] at time 06:12:55.516722
2019-06-22 06:12:55,517 [salt.state       :1813][INFO    ][13929] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-22 06:12:55,616 [salt.state       :300 ][INFO    ][13929] File /etc/iptables/rules.v4 is in the correct state
2019-06-22 06:12:55,617 [salt.state       :1951][INFO    ][13929] Completed state [/etc/iptables/rules.v4] at time 06:12:55.616953 duration_in_ms=100.232
2019-06-22 06:12:55,618 [salt.state       :1780][INFO    ][13929] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:12:55.618229
2019-06-22 06:12:55,618 [salt.state       :1813][INFO    ][13929] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-22 06:12:55,619 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-22 06:12:55,640 [salt.state       :300 ][INFO    ][13929] onlyif execution failed
2019-06-22 06:12:55,641 [salt.state       :1951][INFO    ][13929] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:12:55.641398 duration_in_ms=23.168
2019-06-22 06:12:55,643 [salt.state       :1780][INFO    ][13929] Running state [netfilter-persistent] at time 06:12:55.643749
2019-06-22 06:12:55,644 [salt.state       :1813][INFO    ][13929] Executing state service.running for [netfilter-persistent]
2019-06-22 06:12:55,648 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-22 06:12:55,664 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-22 06:12:55,681 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-22 06:12:55,701 [salt.state       :300 ][INFO    ][13929] The service netfilter-persistent is already running
2019-06-22 06:12:55,701 [salt.state       :1951][INFO    ][13929] Completed state [netfilter-persistent] at time 06:12:55.701469 duration_in_ms=57.721
2019-06-22 06:12:55,702 [salt.state       :1780][INFO    ][13929] Running state [iptables_extra.remove_stale_tables] at time 06:12:55.702873
2019-06-22 06:12:55,703 [salt.state       :1813][INFO    ][13929] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-22 06:12:55,703 [salt.state       :300 ][INFO    ][13929] No changes made for iptables_extra.remove_stale_tables
2019-06-22 06:12:55,704 [salt.state       :1951][INFO    ][13929] Completed state [iptables_extra.remove_stale_tables] at time 06:12:55.704195 duration_in_ms=1.322
2019-06-22 06:12:55,704 [salt.state       :1780][INFO    ][13929] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:12:55.704611
2019-06-22 06:12:55,705 [salt.state       :1813][INFO    ][13929] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-22 06:12:55,707 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-22 06:12:55,725 [salt.state       :300 ][INFO    ][13929] onlyif execution failed
2019-06-22 06:12:55,725 [salt.state       :1951][INFO    ][13929] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:12:55.725592 duration_in_ms=20.981
2019-06-22 06:12:55,727 [salt.state       :1780][INFO    ][13929] Running state [/etc/iptables/rules.v6] at time 06:12:55.727554
2019-06-22 06:12:55,728 [salt.state       :1813][INFO    ][13929] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-22 06:12:55,728 [salt.state       :300 ][INFO    ][13929] File /etc/iptables/rules.v6 is not present
2019-06-22 06:12:55,729 [salt.state       :1951][INFO    ][13929] Completed state [/etc/iptables/rules.v6] at time 06:12:55.728994 duration_in_ms=1.44
2019-06-22 06:12:55,732 [salt.state       :1780][INFO    ][13929] Running state [iptables_extra.flush_all] at time 06:12:55.732254
2019-06-22 06:12:55,732 [salt.state       :1813][INFO    ][13929] Executing state module.wait for [iptables_extra.flush_all]
2019-06-22 06:12:55,733 [salt.state       :300 ][INFO    ][13929] No changes made for iptables_extra.flush_all
2019-06-22 06:12:55,733 [salt.state       :1951][INFO    ][13929] Completed state [iptables_extra.flush_all] at time 06:12:55.733293 duration_in_ms=1.039
2019-06-22 06:12:55,737 [salt.minion      :1711][INFO    ][13929] Returning information for job: 20190622061244135740
2019-06-22 06:12:56,519 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command state.apply with jid 20190622061256511970
2019-06-22 06:12:56,541 [salt.minion      :1432][INFO    ][14020] Starting a new job with PID 14020
2019-06-22 06:12:57,598 [salt.state       :915 ][INFO    ][14020] Loading fresh modules for state activity
2019-06-22 06:12:58,877 [salt.state       :1780][INFO    ][14020] Running state [maas-rack-controller] at time 06:12:58.877318
2019-06-22 06:12:58,877 [salt.state       :1813][INFO    ][14020] Executing state pkg.installed for [maas-rack-controller]
2019-06-22 06:12:58,878 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14020] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-22 06:12:59,003 [salt.state       :300 ][INFO    ][14020] All specified packages are already installed
2019-06-22 06:12:59,003 [salt.state       :1951][INFO    ][14020] Completed state [maas-rack-controller] at time 06:12:59.003607 duration_in_ms=126.29
2019-06-22 06:12:59,004 [salt.state       :1780][INFO    ][14020] Running state [ipmitool] at time 06:12:59.003980
2019-06-22 06:12:59,004 [salt.state       :1813][INFO    ][14020] Executing state pkg.installed for [ipmitool]
2019-06-22 06:12:59,013 [salt.state       :300 ][INFO    ][14020] All specified packages are already installed
2019-06-22 06:12:59,014 [salt.state       :1951][INFO    ][14020] Completed state [ipmitool] at time 06:12:59.014021 duration_in_ms=10.041
2019-06-22 06:12:59,017 [salt.state       :1780][INFO    ][14020] Running state [/etc/maas/rackd.conf] at time 06:12:59.017044
2019-06-22 06:12:59,017 [salt.state       :1813][INFO    ][14020] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-22 06:12:59,019 [salt.state       :300 ][INFO    ][14020] No changes needed to be made
2019-06-22 06:12:59,019 [salt.state       :1951][INFO    ][14020] Completed state [/etc/maas/rackd.conf] at time 06:12:59.019589 duration_in_ms=2.545
2019-06-22 06:12:59,019 [salt.state       :1780][INFO    ][14020] Running state [/etc/maas/rackd.conf] at time 06:12:59.019871
2019-06-22 06:12:59,020 [salt.state       :1813][INFO    ][14020] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-22 06:12:59,020 [salt.loaded.int.states.file:2298][WARNING ][14020] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-22 06:12:59,021 [salt.state       :300 ][INFO    ][14020] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-22 06:12:59,021 [salt.state       :1951][INFO    ][14020] Completed state [/etc/maas/rackd.conf] at time 06:12:59.021511 duration_in_ms=1.64
2019-06-22 06:12:59,022 [salt.state       :1780][INFO    ][14020] Running state [maas-rackd] at time 06:12:59.022567
2019-06-22 06:12:59,022 [salt.state       :1813][INFO    ][14020] Executing state service.running for [maas-rackd]
2019-06-22 06:12:59,023 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14020] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-22 06:12:59,065 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14020] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-22 06:12:59,088 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14020] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-22 06:12:59,117 [salt.state       :300 ][INFO    ][14020] The service maas-rackd is already running
2019-06-22 06:12:59,118 [salt.state       :1951][INFO    ][14020] Completed state [maas-rackd] at time 06:12:59.118511 duration_in_ms=95.943
2019-06-22 06:12:59,120 [salt.minion      :1711][INFO    ][14020] Returning information for job: 20190622061256511970
2019-06-22 06:12:59,815 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command state.apply with jid 20190622061259803527
2019-06-22 06:12:59,843 [salt.minion      :1432][INFO    ][14056] Starting a new job with PID 14056
2019-06-22 06:13:00,810 [salt.state       :915 ][INFO    ][14056] Loading fresh modules for state activity
2019-06-22 06:13:02,264 [salt.state       :1780][INFO    ][14056] Running state [maas-region-controller] at time 06:13:02.263934
2019-06-22 06:13:02,264 [salt.state       :1813][INFO    ][14056] Executing state pkg.installed for [maas-region-controller]
2019-06-22 06:13:02,265 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14056] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-22 06:13:02,386 [salt.state       :300 ][INFO    ][14056] All specified packages are already installed
2019-06-22 06:13:02,387 [salt.state       :1951][INFO    ][14056] Completed state [maas-region-controller] at time 06:13:02.387298 duration_in_ms=123.365
2019-06-22 06:13:02,387 [salt.state       :1780][INFO    ][14056] Running state [python-oauth] at time 06:13:02.387673
2019-06-22 06:13:02,388 [salt.state       :1813][INFO    ][14056] Executing state pkg.installed for [python-oauth]
2019-06-22 06:13:02,397 [salt.state       :300 ][INFO    ][14056] All specified packages are already installed
2019-06-22 06:13:02,397 [salt.state       :1951][INFO    ][14056] Completed state [python-oauth] at time 06:13:02.397664 duration_in_ms=9.991
2019-06-22 06:13:02,406 [salt.state       :1780][INFO    ][14056] Running state [/etc/maas/regiond.conf] at time 06:13:02.405619
2019-06-22 06:13:02,406 [salt.state       :1813][INFO    ][14056] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-22 06:13:02,415 [salt.state       :300 ][INFO    ][14056] No changes needed to be made
2019-06-22 06:13:02,415 [salt.state       :1951][INFO    ][14056] Completed state [/etc/maas/regiond.conf] at time 06:13:02.415844 duration_in_ms=10.227
2019-06-22 06:13:02,416 [salt.state       :1780][INFO    ][14056] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:13:02.416370
2019-06-22 06:13:02,416 [salt.state       :1813][INFO    ][14056] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-22 06:13:02,477 [salt.state       :300 ][INFO    ][14056] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-22 06:13:02,477 [salt.state       :1951][INFO    ][14056] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:13:02.477626 duration_in_ms=61.256
2019-06-22 06:13:02,478 [salt.state       :1780][INFO    ][14056] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:13:02.478174
2019-06-22 06:13:02,478 [salt.state       :1813][INFO    ][14056] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-22 06:13:02,485 [salt.state       :300 ][INFO    ][14056] No changes needed to be made
2019-06-22 06:13:02,486 [salt.state       :1951][INFO    ][14056] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:13:02.485587 duration_in_ms=7.412
2019-06-22 06:13:02,487 [salt.state       :1780][INFO    ][14056] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:13:02.487662
2019-06-22 06:13:02,487 [salt.state       :1813][INFO    ][14056] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-22 06:13:02,492 [salt.state       :300 ][INFO    ][14056] No changes needed to be made
2019-06-22 06:13:02,492 [salt.state       :1951][INFO    ][14056] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:13:02.492740 duration_in_ms=5.078
2019-06-22 06:13:02,493 [salt.state       :1780][INFO    ][14056] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:13:02.493268
2019-06-22 06:13:02,493 [salt.state       :1813][INFO    ][14056] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-22 06:13:02,526 [salt.state       :300 ][INFO    ][14056] No changes needed to be made
2019-06-22 06:13:02,526 [salt.state       :1951][INFO    ][14056] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:13:02.526583 duration_in_ms=33.315
2019-06-22 06:13:02,527 [salt.state       :1780][INFO    ][14056] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:13:02.527153
2019-06-22 06:13:02,527 [salt.state       :1813][INFO    ][14056] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-22 06:13:02,547 [salt.state       :300 ][INFO    ][14056] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-22 06:13:02,547 [salt.state       :1951][INFO    ][14056] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:13:02.547312 duration_in_ms=20.158
2019-06-22 06:13:02,549 [salt.state       :1780][INFO    ][14056] Running state [a2enmod headers] at time 06:13:02.549017
2019-06-22 06:13:02,549 [salt.state       :1813][INFO    ][14056] Executing state cmd.run for [a2enmod headers]
2019-06-22 06:13:02,551 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14056] Executing command 'a2enmod headers' in directory '/root'
2019-06-22 06:13:02,625 [salt.state       :300 ][INFO    ][14056] {'pid': 14075, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-22 06:13:02,626 [salt.state       :1951][INFO    ][14056] Completed state [a2enmod headers] at time 06:13:02.626110 duration_in_ms=77.093
2019-06-22 06:13:02,626 [salt.state       :1780][INFO    ][14056] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:13:02.626618
2019-06-22 06:13:02,627 [salt.state       :1813][INFO    ][14056] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-22 06:13:02,649 [salt.state       :300 ][INFO    ][14056] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-22 06:13:02,649 [salt.state       :1951][INFO    ][14056] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:13:02.649813 duration_in_ms=23.195
2019-06-22 06:13:02,650 [salt.state       :1780][INFO    ][14056] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:13:02.650606
2019-06-22 06:13:02,651 [salt.state       :1813][INFO    ][14056] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-22 06:13:02,724 [salt.state       :300 ][INFO    ][14056] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-22 06:13:02,725 [salt.state       :1951][INFO    ][14056] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:13:02.725170 duration_in_ms=74.564
2019-06-22 06:13:02,726 [salt.state       :1780][INFO    ][14056] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:13:02.726479
2019-06-22 06:13:02,727 [salt.state       :1813][INFO    ][14056] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-22 06:13:02,792 [salt.state       :300 ][INFO    ][14056] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-22 06:13:02,793 [salt.state       :1951][INFO    ][14056] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:13:02.792992 duration_in_ms=66.513
2019-06-22 06:13:02,793 [salt.state       :1780][INFO    ][14056] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:13:02.793548
2019-06-22 06:13:02,793 [salt.state       :1813][INFO    ][14056] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-22 06:13:02,868 [salt.state       :300 ][INFO    ][14056] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-22 06:13:02,868 [salt.state       :1951][INFO    ][14056] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:13:02.868552 duration_in_ms=75.004
2019-06-22 06:13:02,868 [salt.state       :1780][INFO    ][14056] Running state [/root/.pgpass] at time 06:13:02.868846
2019-06-22 06:13:02,869 [salt.state       :1813][INFO    ][14056] Executing state file.managed for [/root/.pgpass]
2019-06-22 06:13:02,924 [salt.state       :300 ][INFO    ][14056] File /root/.pgpass is in the correct state
2019-06-22 06:13:02,925 [salt.state       :1951][INFO    ][14056] Completed state [/root/.pgpass] at time 06:13:02.925155 duration_in_ms=56.309
2019-06-22 06:13:02,930 [salt.state       :1780][INFO    ][14056] Running state [maas-region syncdb --noinput] at time 06:13:02.930479
2019-06-22 06:13:02,930 [salt.state       :1813][INFO    ][14056] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-22 06:13:02,931 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14056] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-22 06:13:05,687 [salt.state       :300 ][INFO    ][14056] {'pid': 14088, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: metadataserver, maasserver, auth, sessions, contenttypes, sites, piston3\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-22 06:13:05,687 [salt.state       :1951][INFO    ][14056] Completed state [maas-region syncdb --noinput] at time 06:13:05.687824 duration_in_ms=2757.344
2019-06-22 06:13:05,688 [salt.state       :2022][WARNING ][14056] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-22 06:13:05,692 [salt.state       :1780][INFO    ][14056] Running state [maas-regiond] at time 06:13:05.692784
2019-06-22 06:13:05,693 [salt.state       :1813][INFO    ][14056] Executing state service.running for [maas-regiond]
2019-06-22 06:13:05,695 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14056] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-22 06:13:05,747 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14056] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-22 06:13:05,769 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14056] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-22 06:13:05,801 [salt.state       :300 ][INFO    ][14056] The service maas-regiond is already running
2019-06-22 06:13:05,802 [salt.state       :1951][INFO    ][14056] Completed state [maas-regiond] at time 06:13:05.802756 duration_in_ms=109.966
2019-06-22 06:13:05,805 [salt.state       :1780][INFO    ][14056] Running state [bind9] at time 06:13:05.805636
2019-06-22 06:13:05,806 [salt.state       :1813][INFO    ][14056] Executing state service.running for [bind9]
2019-06-22 06:13:05,807 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14056] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-22 06:13:05,836 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14056] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-22 06:13:05,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14056] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-22 06:13:05,881 [salt.state       :300 ][INFO    ][14056] The service bind9 is already running
2019-06-22 06:13:05,882 [salt.state       :1951][INFO    ][14056] Completed state [bind9] at time 06:13:05.882245 duration_in_ms=76.609
2019-06-22 06:13:05,885 [salt.state       :1780][INFO    ][14056] Running state [apache2] at time 06:13:05.885478
2019-06-22 06:13:05,887 [salt.state       :1813][INFO    ][14056] Executing state service.running for [apache2]
2019-06-22 06:13:05,888 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14056] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-22 06:13:05,912 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14056] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-22 06:13:05,931 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14056] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-22 06:13:05,960 [salt.state       :300 ][INFO    ][14056] The service apache2 is already running
2019-06-22 06:13:05,960 [salt.state       :1951][INFO    ][14056] Completed state [apache2] at time 06:13:05.960814 duration_in_ms=75.336
2019-06-22 06:13:05,968 [salt.state       :1780][INFO    ][14056] Running state [maasng.wait_for_http_code] at time 06:13:05.965697
2019-06-22 06:13:05,969 [salt.state       :1813][INFO    ][14056] Executing state module.run for [maasng.wait_for_http_code]
2019-06-22 06:13:05,970 [salt.utils.decorators:613 ][WARNING ][14056] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-22 06:13:06,101 [salt.state       :300 ][INFO    ][14056] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-22 06:13:06,102 [salt.state       :1951][INFO    ][14056] Completed state [maasng.wait_for_http_code] at time 06:13:06.102413 duration_in_ms=136.717
2019-06-22 06:13:06,103 [salt.state       :1780][INFO    ][14056] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:13:06.103726
2019-06-22 06:13:06,104 [salt.state       :1813][INFO    ][14056] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-22 06:13:06,104 [salt.state       :300 ][INFO    ][14056] /var/lib/maas/.setup_admin exists
2019-06-22 06:13:06,105 [salt.state       :1951][INFO    ][14056] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:13:06.105271 duration_in_ms=1.545
2019-06-22 06:13:06,107 [salt.state       :1780][INFO    ][14056] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:13:06.106995
2019-06-22 06:13:06,107 [salt.state       :1813][INFO    ][14056] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-22 06:13:06,108 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14056] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-22 06:13:08,012 [salt.state       :300 ][INFO    ][14056] {'pid': 14109, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-22 06:13:08,013 [salt.state       :1951][INFO    ][14056] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:13:08.013159 duration_in_ms=1906.163
2019-06-22 06:13:08,022 [salt.state       :1780][INFO    ][14056] Running state [maas_region_boot_source_resources_mirror] at time 06:13:08.022665
2019-06-22 06:13:08,023 [salt.state       :1813][INFO    ][14056] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-22 06:13:08,118 [salt.state       :300 ][INFO    ][14056] {'changes': {}}
2019-06-22 06:13:08,119 [salt.state       :1951][INFO    ][14056] Completed state [maas_region_boot_source_resources_mirror] at time 06:13:08.119202 duration_in_ms=96.537
2019-06-22 06:13:08,120 [salt.state       :1780][INFO    ][14056] Running state [maasng.boot_resources_import] at time 06:13:08.120315
2019-06-22 06:13:08,120 [salt.state       :1813][INFO    ][14056] Executing state module.run for [maasng.boot_resources_import]
2019-06-22 06:13:08,121 [salt.utils.decorators:613 ][WARNING ][14056] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-22 06:13:08,228 [salt.loaded.ext.module.maasng:1600][INFO    ][14056] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-22 06:13:13,284 [salt.loaded.ext.module.maasng:1600][INFO    ][14056] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-22 06:13:14,864 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622061314850768
2019-06-22 06:13:14,887 [salt.minion      :1432][INFO    ][14142] Starting a new job with PID 14142
2019-06-22 06:13:14,920 [salt.minion      :1711][INFO    ][14142] Returning information for job: 20190622061314850768
2019-06-22 06:13:18,388 [salt.state       :300 ][INFO    ][14056] {'ret': True}
2019-06-22 06:13:18,389 [salt.state       :1951][INFO    ][14056] Completed state [maasng.boot_resources_import] at time 06:13:18.389362 duration_in_ms=10269.045
2019-06-22 06:13:18,391 [salt.state       :1780][INFO    ][14056] Running state [maas_region_boot_sources_selection_xenial] at time 06:13:18.391118
2019-06-22 06:13:18,391 [salt.state       :1813][INFO    ][14056] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-22 06:13:18,576 [salt.state       :300 ][INFO    ][14056] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-22 06:13:18,576 [salt.state       :1951][INFO    ][14056] Completed state [maas_region_boot_sources_selection_xenial] at time 06:13:18.576408 duration_in_ms=185.289
2019-06-22 06:13:18,577 [salt.state       :1780][INFO    ][14056] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 06:13:18.577505
2019-06-22 06:13:18,579 [salt.state       :1813][INFO    ][14056] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-22 06:13:18,579 [salt.utils.decorators:613 ][WARNING ][14056] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-22 06:13:18,580 [salt.loaded.ext.module.maasng:1771][INFO    ][14056] boot-sources sync initiated for ALL Rack's
2019-06-22 06:13:19,657 [salt.state       :300 ][INFO    ][14056] {'ret': True}
2019-06-22 06:13:19,658 [salt.state       :1951][INFO    ][14056] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 06:13:19.658414 duration_in_ms=1080.909
2019-06-22 06:13:19,661 [salt.state       :1780][INFO    ][14056] Running state [maas.process_maas_config] at time 06:13:19.660904
2019-06-22 06:13:19,661 [salt.state       :1813][INFO    ][14056] Executing state module.run for [maas.process_maas_config]
2019-06-22 06:13:19,662 [salt.utils.decorators:613 ][WARNING ][14056] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-22 06:13:19,663 [salt.loaded.ext.module.maas:92  ][INFO    ][14056] maasconfig name=enable_http_proxy value=True
2019-06-22 06:13:19,719 [salt.loaded.ext.module.maas:92  ][INFO    ][14056] maasconfig name=upstream_dns value=8.8.8.8
2019-06-22 06:13:19,777 [salt.loaded.ext.module.maas:92  ][INFO    ][14056] maasconfig name=commissioning_distro_series value=xenial
2019-06-22 06:13:19,874 [salt.loaded.ext.module.maas:92  ][INFO    ][14056] maasconfig name=default_osystem value=ubuntu
2019-06-22 06:13:19,931 [salt.loaded.ext.module.maas:92  ][INFO    ][14056] maasconfig name=active_discovery_interval value=600
2019-06-22 06:13:19,981 [salt.loaded.ext.module.maas:92  ][INFO    ][14056] maasconfig name=dnssec_validation value=no
2019-06-22 06:13:21,307 [salt.loaded.ext.module.maas:92  ][INFO    ][14056] maasconfig name=maas_name value=mas01
2019-06-22 06:13:21,365 [salt.loaded.ext.module.maas:92  ][INFO    ][14056] maasconfig name=network_discovery value=enabled
2019-06-22 06:13:21,468 [salt.loaded.ext.module.maas:92  ][INFO    ][14056] maasconfig name=enable_third_party_drivers value=True
2019-06-22 06:13:21,535 [salt.loaded.ext.module.maas:92  ][INFO    ][14056] maasconfig name=default_storage_layout value=lvm
2019-06-22 06:13:21,591 [salt.loaded.ext.module.maas:92  ][INFO    ][14056] maasconfig name=ntp_external_only value=True
2019-06-22 06:13:21,640 [salt.loaded.ext.module.maas:92  ][INFO    ][14056] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-22 06:13:21,703 [salt.loaded.ext.module.maas:92  ][INFO    ][14056] maasconfig name=default_distro_series value=xenial
2019-06-22 06:13:21,760 [salt.loaded.ext.module.maas:92  ][INFO    ][14056] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-06-22 06:13:21,892 [salt.state       :300 ][INFO    ][14056] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-06-22 06:13:21,892 [salt.state       :1951][INFO    ][14056] Completed state [maas.process_maas_config] at time 06:13:21.892377 duration_in_ms=2231.473
2019-06-22 06:13:21,893 [salt.state       :1780][INFO    ][14056] Running state [pxe_admin] at time 06:13:21.893263
2019-06-22 06:13:21,895 [salt.state       :1813][INFO    ][14056] Executing state maasng.fabric_present for [pxe_admin]
2019-06-22 06:13:21,954 [salt.loaded.ext.module.maasng:945 ][INFO    ][14056] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'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'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'yq8mrs', 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/'}]
2019-06-22 06:13:22,016 [salt.loaded.ext.module.maasng:1008][WARNING ][14056] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-22 06:13:22,016 [salt.loaded.ext.module.maasng:1011][WARNING ][14056] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-22 06:13:22,102 [salt.state       :300 ][INFO    ][14056] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-22 06:13:22,103 [salt.state       :1951][INFO    ][14056] Completed state [pxe_admin] at time 06:13:22.103328 duration_in_ms=210.064
2019-06-22 06:13:22,103 [salt.state       :1780][INFO    ][14056] Running state [vlan 0] at time 06:13:22.103868
2019-06-22 06:13:22,104 [salt.state       :1813][INFO    ][14056] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-22 06:13:22,160 [salt.loaded.ext.module.maasng:945 ][INFO    ][14056] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'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'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'yq8mrs', 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/'}]
2019-06-22 06:13:22,417 [salt.loaded.ext.module.maasng:945 ][INFO    ][14056] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'yq8mrs', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-22 06:13:22,660 [salt.loaded.ext.module.maasng:945 ][INFO    ][14056] [{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'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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-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'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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-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'primary_rack': u'yq8mrs', 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-06-22 06:13:22,743 [salt.state       :300 ][INFO    ][14056] {'new': 'Vlan untagged was updated'}
2019-06-22 06:13:22,743 [salt.state       :1951][INFO    ][14056] Completed state [vlan 0] at time 06:13:22.743777 duration_in_ms=639.909
2019-06-22 06:13:22,745 [salt.state       :1780][INFO    ][14056] Running state [192.168.11.0/24] at time 06:13:22.745047
2019-06-22 06:13:22,745 [salt.state       :1813][INFO    ][14056] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-22 06:13:22,933 [salt.loaded.ext.module.maasng:945 ][INFO    ][14056] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'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'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'yq8mrs', 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/'}]
2019-06-22 06:13:22,937 [salt.loaded.ext.module.maasng:1235][WARNING ][14056] Ignoring parameter vlan:0
2019-06-22 06:13:23,017 [salt.state       :300 ][INFO    ][14056] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-22 06:13:23,019 [salt.state       :1951][INFO    ][14056] Completed state [192.168.11.0/24] at time 06:13:23.019827 duration_in_ms=274.781
2019-06-22 06:13:23,021 [salt.state       :1780][INFO    ][14056] Running state [maas_create_iprange_1] at time 06:13:23.021315
2019-06-22 06:13:23,021 [salt.state       :1813][INFO    ][14056] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-22 06:13:23,090 [salt.state       :300 ][INFO    ][14056] Iprange maas_create_iprange_1 already exist.
2019-06-22 06:13:23,091 [salt.state       :1951][INFO    ][14056] Completed state [maas_create_iprange_1] at time 06:13:23.091274 duration_in_ms=69.958
2019-06-22 06:13:23,091 [salt.state       :1780][INFO    ][14056] Running state [vlan 0] at time 06:13:23.091599
2019-06-22 06:13:23,091 [salt.state       :1813][INFO    ][14056] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-22 06:13:23,142 [salt.loaded.ext.module.maasng:945 ][INFO    ][14056] [{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'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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-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'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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-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'primary_rack': u'yq8mrs', 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-06-22 06:13:23,239 [salt.loaded.ext.module.maasng:945 ][INFO    ][14056] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'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'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'yq8mrs', 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/'}]
2019-06-22 06:13:23,518 [salt.loaded.ext.module.maasng:945 ][INFO    ][14056] [{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'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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-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'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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-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'primary_rack': u'yq8mrs', 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-06-22 06:13:23,621 [salt.state       :300 ][INFO    ][14056] {'new': 'Vlan untagged was updated'}
2019-06-22 06:13:23,621 [salt.state       :1951][INFO    ][14056] Completed state [vlan 0] at time 06:13:23.621539 duration_in_ms=529.939
2019-06-22 06:13:23,622 [salt.state       :1780][INFO    ][14056] Running state [opnfv] at time 06:13:23.622601
2019-06-22 06:13:23,623 [salt.state       :1813][INFO    ][14056] Executing state maasng.sshkey_present for [opnfv]
2019-06-22 06:13:23,673 [salt.loaded.ext.module.maasng:1903][INFO    ][14056] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-06-22 06:13:23,674 [salt.state       :300 ][INFO    ][14056] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-06-22 06:13:23,674 [salt.state       :1951][INFO    ][14056] Completed state [opnfv] at time 06:13:23.674347 duration_in_ms=51.745
2019-06-22 06:13:23,675 [salt.state       :1780][INFO    ][14056] Running state [maas.process_tags] at time 06:13:23.675082
2019-06-22 06:13:23,675 [salt.state       :1813][INFO    ][14056] Executing state module.run for [maas.process_tags]
2019-06-22 06:13:23,675 [salt.utils.decorators:613 ][WARNING ][14056] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-22 06:13:23,720 [salt.loaded.ext.module.maas:92  ][INFO    ][14056] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-06-22 06:13:23,799 [salt.state       :300 ][INFO    ][14056] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-06-22 06:13:23,800 [salt.state       :1951][INFO    ][14056] Completed state [maas.process_tags] at time 06:13:23.800196 duration_in_ms=125.114
2019-06-22 06:13:23,803 [salt.minion      :1711][INFO    ][14056] Returning information for job: 20190622061259803527
2019-06-22 06:13:24,562 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command state.apply with jid 20190622061324551579
2019-06-22 06:13:24,582 [salt.minion      :1432][INFO    ][14497] Starting a new job with PID 14497
2019-06-22 06:13:30,679 [salt.state       :915 ][INFO    ][14497] Loading fresh modules for state activity
2019-06-22 06:13:30,795 [salt.state       :1780][INFO    ][14497] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:13:30.795353
2019-06-22 06:13:30,795 [salt.state       :1813][INFO    ][14497] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-22 06:13:30,797 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14497] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-22 06:13:32,781 [salt.state       :300 ][INFO    ][14497] {'pid': 14530, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-22 06:13:32,782 [salt.state       :1951][INFO    ][14497] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:13:32.782046 duration_in_ms=1986.693
2019-06-22 06:13:32,785 [salt.state       :1780][INFO    ][14497] Running state [maas.process_machines] at time 06:13:32.784871
2019-06-22 06:13:32,785 [salt.state       :1813][INFO    ][14497] Executing state module.run for [maas.process_machines]
2019-06-22 06:13:32,787 [salt.utils.decorators:613 ][WARNING ][14497] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-22 06:13:33,367 [salt.loaded.ext.module.maas:412 ][WARNING ][14497] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-22 06:13:33,368 [salt.loaded.ext.module.maas:92  ][INFO    ][14497] 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=xedpfd architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-22 06:13:34,616 [salt.loaded.ext.module.maas:412 ][WARNING ][14497] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-22 06:13:34,617 [salt.loaded.ext.module.maas:92  ][INFO    ][14497] 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=dhg6rx architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-22 06:13:35,848 [salt.loaded.ext.module.maas:412 ][WARNING ][14497] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-22 06:13:35,850 [salt.loaded.ext.module.maas:92  ][INFO    ][14497] 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=h3wbe7 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-22 06:13:37,090 [salt.loaded.ext.module.maas:412 ][WARNING ][14497] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-22 06:13:37,091 [salt.loaded.ext.module.maas:92  ][INFO    ][14497] 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=68d3sm architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-22 06:13:38,336 [salt.loaded.ext.module.maas:412 ][WARNING ][14497] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-22 06:13:38,336 [salt.loaded.ext.module.maas:92  ][INFO    ][14497] 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=dmwr6t architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-22 06:13:39,547 [salt.state       :300 ][INFO    ][14497] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-22 06:13:39,547 [salt.state       :1951][INFO    ][14497] Completed state [maas.process_machines] at time 06:13:39.547737 duration_in_ms=6762.867
2019-06-22 06:13:39,551 [salt.minion      :1711][INFO    ][14497] Returning information for job: 20190622061324551579
2019-06-22 06:14:13,536 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command state.apply with jid 20190622061413522847
2019-06-22 06:14:13,560 [salt.minion      :1432][INFO    ][14795] Starting a new job with PID 14795
2019-06-22 06:14:19,458 [salt.state       :915 ][INFO    ][14795] Loading fresh modules for state activity
2019-06-22 06:14:19,561 [salt.state       :1780][INFO    ][14795] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:14:19.561397
2019-06-22 06:14:19,562 [salt.state       :1813][INFO    ][14795] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-22 06:14:19,564 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14795] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-22 06:14:21,453 [salt.state       :300 ][INFO    ][14795] {'pid': 14802, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-22 06:14:21,454 [salt.state       :1951][INFO    ][14795] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:14:21.454295 duration_in_ms=1892.898
2019-06-22 06:14:21,457 [salt.state       :1780][INFO    ][14795] Running state [maas.wait_for_machine_status] at time 06:14:21.457345
2019-06-22 06:14:21,458 [salt.state       :1813][INFO    ][14795] Executing state module.run for [maas.wait_for_machine_status]
2019-06-22 06:14:21,458 [salt.utils.decorators:613 ][WARNING ][14795] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-22 06:14:24,204 [salt.state       :300 ][INFO    ][14795] {'ret': True}
2019-06-22 06:14:24,205 [salt.state       :1951][INFO    ][14795] Completed state [maas.wait_for_machine_status] at time 06:14:24.205298 duration_in_ms=2747.951
2019-06-22 06:14:24,211 [salt.minion      :1711][INFO    ][14795] Returning information for job: 20190622061413522847
2019-06-22 06:14:24,918 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command state.apply with jid 20190622061424906368
2019-06-22 06:14:24,943 [salt.minion      :1432][INFO    ][14815] Starting a new job with PID 14815
2019-06-22 06:14:25,922 [salt.state       :915 ][INFO    ][14815] Loading fresh modules for state activity
2019-06-22 06:14:26,083 [salt.state       :1780][INFO    ][14815] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:14:26.083466
2019-06-22 06:14:26,083 [salt.state       :1813][INFO    ][14815] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-22 06:14:26,086 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14815] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-22 06:14:28,063 [salt.state       :300 ][INFO    ][14815] {'pid': 14826, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-22 06:14:28,064 [salt.state       :1951][INFO    ][14815] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:14:28.064421 duration_in_ms=1980.954
2019-06-22 06:14:28,068 [salt.state       :1780][INFO    ][14815] Running state [maas_machines_storage_cmp002_lvm] at time 06:14:28.068789
2019-06-22 06:14:28,069 [salt.state       :1813][INFO    ][14815] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-22 06:14:28,656 [salt.state       :300 ][INFO    ][14815] Machine cmp002 is not in Ready state.
2019-06-22 06:14:28,657 [salt.state       :1951][INFO    ][14815] Completed state [maas_machines_storage_cmp002_lvm] at time 06:14:28.657248 duration_in_ms=588.458
2019-06-22 06:14:28,659 [salt.state       :1780][INFO    ][14815] Running state [maas_machines_storage_cmp001_lvm] at time 06:14:28.659553
2019-06-22 06:14:28,659 [salt.state       :1813][INFO    ][14815] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-22 06:14:29,200 [salt.state       :300 ][INFO    ][14815] Machine cmp001 is not in Ready state.
2019-06-22 06:14:29,201 [salt.state       :1951][INFO    ][14815] Completed state [maas_machines_storage_cmp001_lvm] at time 06:14:29.201103 duration_in_ms=541.549
2019-06-22 06:14:29,204 [salt.minion      :1711][INFO    ][14815] Returning information for job: 20190622061424906368
2019-06-22 06:14:29,908 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command state.apply with jid 20190622061429893522
2019-06-22 06:14:29,933 [salt.minion      :1432][INFO    ][14842] Starting a new job with PID 14842
2019-06-22 06:14:30,923 [salt.state       :915 ][INFO    ][14842] Loading fresh modules for state activity
2019-06-22 06:14:31,030 [salt.state       :1780][INFO    ][14842] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:14:31.030105
2019-06-22 06:14:31,030 [salt.state       :1813][INFO    ][14842] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-22 06:14:31,035 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14842] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-22 06:14:32,981 [salt.state       :300 ][INFO    ][14842] {'pid': 14849, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-22 06:14:32,983 [salt.state       :1951][INFO    ][14842] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:14:32.983224 duration_in_ms=1953.118
2019-06-22 06:14:32,986 [salt.state       :1780][INFO    ][14842] Running state [maas.deploy_machines] at time 06:14:32.986280
2019-06-22 06:14:32,987 [salt.state       :1813][INFO    ][14842] Executing state module.run for [maas.deploy_machines]
2019-06-22 06:14:32,987 [salt.utils.decorators:613 ][WARNING ][14842] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-22 06:14:33,654 [salt.loaded.ext.module.maas:684 ][INFO    ][14842] deploymachines hwe_kernel=hwe-16.04 system_id=dmwr6t distro_series=xenial
2019-06-22 06:14:36,156 [salt.state       :300 ][INFO    ][14842] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-06-22 06:14:36,157 [salt.state       :1951][INFO    ][14842] Completed state [maas.deploy_machines] at time 06:14:36.157397 duration_in_ms=3171.117
2019-06-22 06:14:36,164 [salt.minion      :1711][INFO    ][14842] Returning information for job: 20190622061429893522
2019-06-22 06:14:36,886 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command state.apply with jid 20190622061436870843
2019-06-22 06:14:36,912 [salt.minion      :1432][INFO    ][14909] Starting a new job with PID 14909
2019-06-22 06:14:42,945 [salt.state       :915 ][INFO    ][14909] Loading fresh modules for state activity
2019-06-22 06:14:43,049 [salt.state       :1780][INFO    ][14909] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:14:43.048931
2019-06-22 06:14:43,049 [salt.state       :1813][INFO    ][14909] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-22 06:14:43,052 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14909] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-22 06:14:45,019 [salt.state       :300 ][INFO    ][14909] {'pid': 14932, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-22 06:14:45,020 [salt.state       :1951][INFO    ][14909] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:14:45.020574 duration_in_ms=1971.642
2019-06-22 06:14:45,024 [salt.state       :1780][INFO    ][14909] Running state [maas.wait_for_machine_status] at time 06:14:45.024269
2019-06-22 06:14:45,025 [salt.state       :1813][INFO    ][14909] Executing state module.run for [maas.wait_for_machine_status]
2019-06-22 06:14:45,026 [salt.utils.decorators:613 ][WARNING ][14909] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-22 06:14:47,792 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.25170302s left)
2019-06-22 06:14:51,908 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622061451897109
2019-06-22 06:14:51,936 [salt.minion      :1432][INFO    ][14944] Starting a new job with PID 14944
2019-06-22 06:14:51,964 [salt.minion      :1711][INFO    ][14944] Returning information for job: 20190622061451897109
2019-06-22 06:15:20,676 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.36695385s left)
2019-06-22 06:15:22,007 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622061521948829
2019-06-22 06:15:22,037 [salt.minion      :1432][INFO    ][14992] Starting a new job with PID 14992
2019-06-22 06:15:22,075 [salt.minion      :1711][INFO    ][14992] Returning information for job: 20190622061521948829
2019-06-22 06:15:52,097 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622061552087731
2019-06-22 06:15:52,123 [salt.minion      :1432][INFO    ][15034] Starting a new job with PID 15034
2019-06-22 06:15:52,156 [salt.minion      :1711][INFO    ][15034] Returning information for job: 20190622061552087731
2019-06-22 06:15:53,628 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.41533184s left)
2019-06-22 06:16:22,186 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622061622174142
2019-06-22 06:16:22,212 [salt.minion      :1432][INFO    ][15086] Starting a new job with PID 15086
2019-06-22 06:16:22,240 [salt.minion      :1711][INFO    ][15086] Returning information for job: 20190622061622174142
2019-06-22 06:16:26,763 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.28027701s left)
2019-06-22 06:16:52,284 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622061652265263
2019-06-22 06:16:52,312 [salt.minion      :1432][INFO    ][15119] Starting a new job with PID 15119
2019-06-22 06:16:52,342 [salt.minion      :1711][INFO    ][15119] Returning information for job: 20190622061652265263
2019-06-22 06:16:59,655 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.38816404s left)
2019-06-22 06:17:22,377 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622061722363494
2019-06-22 06:17:22,402 [salt.minion      :1432][INFO    ][15167] Starting a new job with PID 15167
2019-06-22 06:17:22,433 [salt.minion      :1711][INFO    ][15167] Returning information for job: 20190622061722363494
2019-06-22 06:17:32,396 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2082.64694881s left)
2019-06-22 06:17:52,484 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622061752465448
2019-06-22 06:17:52,512 [salt.minion      :1432][INFO    ][15203] Starting a new job with PID 15203
2019-06-22 06:17:52,547 [salt.minion      :1711][INFO    ][15203] Returning information for job: 20190622061752465448
2019-06-22 06:18:05,319 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2049.72465301s left)
2019-06-22 06:18:22,597 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622061822584907
2019-06-22 06:18:22,619 [salt.minion      :1432][INFO    ][15248] Starting a new job with PID 15248
2019-06-22 06:18:22,647 [salt.minion      :1711][INFO    ][15248] Returning information for job: 20190622061822584907
2019-06-22 06:18:38,176 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2016.86789799s left)
2019-06-22 06:18:52,685 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622061852675304
2019-06-22 06:18:52,704 [salt.minion      :1432][INFO    ][15306] Starting a new job with PID 15306
2019-06-22 06:18:52,735 [salt.minion      :1711][INFO    ][15306] Returning information for job: 20190622061852675304
2019-06-22 06:19:11,065 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1983.97828698s left)
2019-06-22 06:19:22,786 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622061922774875
2019-06-22 06:19:22,817 [salt.minion      :1432][INFO    ][15353] Starting a new job with PID 15353
2019-06-22 06:19:22,847 [salt.minion      :1711][INFO    ][15353] Returning information for job: 20190622061922774875
2019-06-22 06:19:43,888 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.15559387s left)
2019-06-22 06:19:52,911 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622061952897636
2019-06-22 06:19:52,936 [salt.minion      :1432][INFO    ][15430] Starting a new job with PID 15430
2019-06-22 06:19:52,969 [salt.minion      :1711][INFO    ][15430] Returning information for job: 20190622061952897636
2019-06-22 06:20:16,743 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.30012798s left)
2019-06-22 06:20:23,051 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622062023036243
2019-06-22 06:20:23,077 [salt.minion      :1432][INFO    ][15557] Starting a new job with PID 15557
2019-06-22 06:20:23,108 [salt.minion      :1711][INFO    ][15557] Returning information for job: 20190622062023036243
2019-06-22 06:20:49,997 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.04643989s left)
2019-06-22 06:20:53,166 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622062053154926
2019-06-22 06:20:53,191 [salt.minion      :1432][INFO    ][15626] Starting a new job with PID 15626
2019-06-22 06:20:53,221 [salt.minion      :1711][INFO    ][15626] Returning information for job: 20190622062053154926
2019-06-22 06:21:22,804 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.23923802s left)
2019-06-22 06:21:23,308 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622062123287034
2019-06-22 06:21:23,337 [salt.minion      :1432][INFO    ][15694] Starting a new job with PID 15694
2019-06-22 06:21:23,372 [salt.minion      :1711][INFO    ][15694] Returning information for job: 20190622062123287034
2019-06-22 06:21:53,453 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622062153442746
2019-06-22 06:21:53,476 [salt.minion      :1432][INFO    ][15730] Starting a new job with PID 15730
2019-06-22 06:21:53,518 [salt.minion      :1711][INFO    ][15730] Returning information for job: 20190622062153442746
2019-06-22 06:21:55,615 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.42866182s left)
2019-06-22 06:22:23,629 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622062223615210
2019-06-22 06:22:23,655 [salt.minion      :1432][INFO    ][15807] Starting a new job with PID 15807
2019-06-22 06:22:23,692 [salt.minion      :1711][INFO    ][15807] Returning information for job: 20190622062223615210
2019-06-22 06:22:28,942 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.10099196s left)
2019-06-22 06:22:53,788 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622062253774220
2019-06-22 06:22:53,819 [salt.minion      :1432][INFO    ][15881] Starting a new job with PID 15881
2019-06-22 06:22:53,852 [salt.minion      :1711][INFO    ][15881] Returning information for job: 20190622062253774220
2019-06-22 06:23:01,943 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1753.100142s left)
2019-06-22 06:23:23,975 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622062323962495
2019-06-22 06:23:24,005 [salt.minion      :1432][INFO    ][16003] Starting a new job with PID 16003
2019-06-22 06:23:24,038 [salt.minion      :1711][INFO    ][16003] Returning information for job: 20190622062323962495
2019-06-22 06:23:34,781 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.26257896s left)
2019-06-22 06:23:54,142 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622062354129166
2019-06-22 06:23:54,163 [salt.minion      :1432][INFO    ][16053] Starting a new job with PID 16053
2019-06-22 06:23:54,192 [salt.minion      :1711][INFO    ][16053] Returning information for job: 20190622062354129166
2019-06-22 06:24:07,749 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.29460287s left)
2019-06-22 06:24:24,317 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622062424299173
2019-06-22 06:24:24,341 [salt.minion      :1432][INFO    ][16095] Starting a new job with PID 16095
2019-06-22 06:24:24,372 [salt.minion      :1711][INFO    ][16095] Returning information for job: 20190622062424299173
2019-06-22 06:24:40,810 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1654.23310184s left)
2019-06-22 06:24:54,511 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622062454497155
2019-06-22 06:24:54,539 [salt.minion      :1432][INFO    ][16127] Starting a new job with PID 16127
2019-06-22 06:24:54,568 [salt.minion      :1711][INFO    ][16127] Returning information for job: 20190622062454497155
2019-06-22 06:25:13,964 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1621.07931089s left)
2019-06-22 06:25:24,691 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622062524675892
2019-06-22 06:25:24,720 [salt.minion      :1432][INFO    ][16241] Starting a new job with PID 16241
2019-06-22 06:25:24,756 [salt.minion      :1711][INFO    ][16241] Returning information for job: 20190622062524675892
2019-06-22 06:25:46,815 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1588.22826982s left)
2019-06-22 06:25:54,884 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622062554875019
2019-06-22 06:25:54,913 [salt.minion      :1432][INFO    ][16285] Starting a new job with PID 16285
2019-06-22 06:25:54,947 [salt.minion      :1711][INFO    ][16285] Returning information for job: 20190622062554875019
2019-06-22 06:26:19,636 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1555.40753102s left)
2019-06-22 06:26:25,086 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622062625073013
2019-06-22 06:26:25,116 [salt.minion      :1432][INFO    ][16479] Starting a new job with PID 16479
2019-06-22 06:26:25,148 [salt.minion      :1711][INFO    ][16479] Returning information for job: 20190622062625073013
2019-06-22 06:26:50,764 [salt.utils.schedule:1377][INFO    ][5628] Running scheduled job: __mine_interval
2019-06-22 06:26:52,566 [salt.loaded.ext.module.maas:1023][INFO    ][14909] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1522.47770095s left)
2019-06-22 06:26:55,296 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622062655282980
2019-06-22 06:26:55,326 [salt.minion      :1432][INFO    ][16541] Starting a new job with PID 16541
2019-06-22 06:26:55,356 [salt.minion      :1711][INFO    ][16541] Returning information for job: 20190622062655282980
2019-06-22 06:27:25,433 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command saltutil.find_job with jid 20190622062725420616
2019-06-22 06:27:25,459 [salt.minion      :1432][INFO    ][16619] Starting a new job with PID 16619
2019-06-22 06:27:25,491 [salt.minion      :1711][INFO    ][16619] Returning information for job: 20190622062725420616
2019-06-22 06:27:25,891 [salt.state       :300 ][INFO    ][14909] {'ret': True}
2019-06-22 06:27:25,892 [salt.state       :1951][INFO    ][14909] Completed state [maas.wait_for_machine_status] at time 06:27:25.892115 duration_in_ms=760867.842
2019-06-22 06:27:25,901 [salt.minion      :1711][INFO    ][14909] Returning information for job: 20190622061436870843
2019-06-22 07:26:50,764 [salt.utils.schedule:1377][INFO    ][5628] Running scheduled job: __mine_interval
2019-06-22 07:50:04,489 [salt.minion      :1308][INFO    ][5628] User sudo_ubuntu Executing command cp.push_dir with jid 20190622075004475900
2019-06-22 07:50:04,517 [salt.minion      :1432][INFO    ][22327] Starting a new job with PID 22327
