2020-02-26 16:18:38,045 [salt.utils.decorators:613 ][WARNING ][1531] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-26 16:18:39,487 [salt.utils.decorators:613 ][WARNING ][1531] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-26 16:18:48,537 [salt.loaded.int.states.file:2298][WARNING ][1687] 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.
2020-02-26 16:18:55,758 [salt.state       :2022][WARNING ][1891] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-02-26 16:18:56,514 [salt.utils.decorators:613 ][WARNING ][1891] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-26 16:19:07,279 [salt.utils.decorators:613 ][WARNING ][1891] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-26 16:19:22,133 [salt.utils.decorators:613 ][WARNING ][1891] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-26 16:19:22,942 [salt.utils.decorators:613 ][WARNING ][1891] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-26 16:19:25,604 [salt.loaded.ext.module.maasng:1008][WARNING ][1891] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2020-02-26 16:19:25,604 [salt.loaded.ext.module.maasng:1011][WARNING ][1891] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2020-02-26 16:19:26,131 [salt.loaded.ext.module.maasng:1235][WARNING ][1891] Ignoring parameter vlan:0
2020-02-26 16:19:26,838 [salt.utils.decorators:613 ][WARNING ][1891] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-26 16:19:36,711 [salt.utils.decorators:613 ][WARNING ][3020] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-26 16:19:36,752 [salt.loaded.ext.module.maas:412 ][WARNING ][3020] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-26 16:19:37,568 [salt.loaded.ext.module.maas:412 ][WARNING ][3020] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-26 16:19:38,422 [salt.loaded.ext.module.maas:412 ][WARNING ][3020] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-26 16:19:39,141 [salt.loaded.ext.module.maas:412 ][WARNING ][3020] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-26 16:19:39,919 [salt.loaded.ext.module.maas:412 ][WARNING ][3020] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-26 16:19:45,072 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3308] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2020-02-26 16:19:45,115 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3308] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2020-02-26 16:19:45,179 [salt.utils.parsers:1051][WARNING ][154] Minion received a SIGTERM. Exiting.
2020-02-26 16:19:46,277 [salt.cli.daemons :293 ][INFO    ][3362] Setting up the Salt Minion "mas01.mcp-odl-noha.local"
2020-02-26 16:19:46,466 [salt.cli.daemons :82  ][INFO    ][3362] Starting up the Salt Minion
2020-02-26 16:19:46,467 [salt.utils.event :1017][INFO    ][3362] Starting pull socket on /var/run/salt/minion/minion_event_0f3e0c7467_pull.ipc
2020-02-26 16:19:47,779 [salt.minion      :976 ][INFO    ][3362] Creating minion process manager
2020-02-26 16:19:49,590 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3362] Executing command ['date', '+%z'] in directory '/root'
2020-02-26 16:19:49,618 [salt.utils.schedule:568 ][INFO    ][3362] Updating job settings for scheduled job: __mine_interval
2020-02-26 16:19:49,623 [salt.minion      :1108][INFO    ][3362] Added mine.update to scheduler
2020-02-26 16:19:49,628 [salt.minion      :1975][INFO    ][3362] Minion is starting as user 'root'
2020-02-26 16:19:49,647 [salt.minion      :2336][INFO    ][3362] Minion is ready to receive requests!
2020-02-26 16:20:12,281 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command state.apply with jid 20200226162012263999
2020-02-26 16:20:12,310 [salt.minion      :1432][INFO    ][3470] Starting a new job with PID 3470
2020-02-26 16:20:18,691 [salt.state       :915 ][INFO    ][3470] Loading fresh modules for state activity
2020-02-26 16:20:18,751 [salt.fileclient  :1219][INFO    ][3470] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2020-02-26 16:20:18,807 [salt.state       :1780][INFO    ][3470] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:20:18.806951
2020-02-26 16:20:18,807 [salt.state       :1813][INFO    ][3470] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-26 16:20:18,809 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3470] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-26 16:20:21,083 [salt.state       :300 ][INFO    ][3470] {'pid': 3477, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-26 16:20:21,085 [salt.state       :1951][INFO    ][3470] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:20:21.084951 duration_in_ms=2278.0
2020-02-26 16:20:21,087 [salt.state       :1780][INFO    ][3470] Running state [maas.wait_for_machine_status] at time 16:20:21.087798
2020-02-26 16:20:21,088 [salt.state       :1813][INFO    ][3470] Executing state module.run for [maas.wait_for_machine_status]
2020-02-26 16:20:21,089 [salt.utils.decorators:613 ][WARNING ][3470] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-26 16:20:22,089 [salt.loaded.ext.module.maas:1024][INFO    ][3470] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.01181912s left)
2020-02-26 16:20:27,331 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226162027316231
2020-02-26 16:20:27,359 [salt.minion      :1432][INFO    ][3493] Starting a new job with PID 3493
2020-02-26 16:20:27,381 [salt.minion      :1711][INFO    ][3493] Returning information for job: 20200226162027316231
2020-02-26 16:20:53,059 [salt.loaded.ext.module.maas:1024][INFO    ][3470] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.04153013s left)
2020-02-26 16:20:57,413 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226162057399421
2020-02-26 16:20:57,439 [salt.minion      :1432][INFO    ][3511] Starting a new job with PID 3511
2020-02-26 16:20:57,462 [salt.minion      :1711][INFO    ][3511] Returning information for job: 20200226162057399421
2020-02-26 16:21:24,042 [salt.loaded.ext.module.maas:1024][INFO    ][3470] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1437.059201s left)
2020-02-26 16:21:27,493 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226162127482260
2020-02-26 16:21:27,515 [salt.minion      :1432][INFO    ][3544] Starting a new job with PID 3544
2020-02-26 16:21:27,538 [salt.minion      :1711][INFO    ][3544] Returning information for job: 20200226162127482260
2020-02-26 16:21:55,047 [salt.loaded.ext.module.maas:1024][INFO    ][3470] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1406.05367494s left)
2020-02-26 16:21:57,578 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226162157558457
2020-02-26 16:21:57,605 [salt.minion      :1432][INFO    ][3562] Starting a new job with PID 3562
2020-02-26 16:21:57,627 [salt.minion      :1711][INFO    ][3562] Returning information for job: 20200226162157558457
2020-02-26 16:22:26,167 [salt.loaded.ext.module.maas:1024][INFO    ][3470] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1374.93387103s left)
2020-02-26 16:22:27,666 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226162227651934
2020-02-26 16:22:27,689 [salt.minion      :1432][INFO    ][3607] Starting a new job with PID 3607
2020-02-26 16:22:27,710 [salt.minion      :1711][INFO    ][3607] Returning information for job: 20200226162227651934
2020-02-26 16:22:57,495 [salt.loaded.ext.module.maas:1024][INFO    ][3470] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1343.60596395s left)
2020-02-26 16:22:57,737 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226162257723429
2020-02-26 16:22:57,760 [salt.minion      :1432][INFO    ][3649] Starting a new job with PID 3649
2020-02-26 16:22:57,779 [salt.minion      :1711][INFO    ][3649] Returning information for job: 20200226162257723429
2020-02-26 16:23:27,838 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226162327823514
2020-02-26 16:23:27,862 [salt.minion      :1432][INFO    ][3710] Starting a new job with PID 3710
2020-02-26 16:23:27,884 [salt.minion      :1711][INFO    ][3710] Returning information for job: 20200226162327823514
2020-02-26 16:23:28,853 [salt.loaded.ext.module.maas:1024][INFO    ][3470] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1312.24771905s left)
2020-02-26 16:23:57,933 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226162357923354
2020-02-26 16:23:57,954 [salt.minion      :1432][INFO    ][3762] Starting a new job with PID 3762
2020-02-26 16:23:57,975 [salt.minion      :1711][INFO    ][3762] Returning information for job: 20200226162357923354
2020-02-26 16:24:00,642 [salt.loaded.ext.module.maas:1024][INFO    ][3470] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1280.45871592s left)
2020-02-26 16:24:28,018 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226162428006227
2020-02-26 16:24:28,038 [salt.minion      :1432][INFO    ][3813] Starting a new job with PID 3813
2020-02-26 16:24:28,065 [salt.minion      :1711][INFO    ][3813] Returning information for job: 20200226162428006227
2020-02-26 16:24:32,361 [salt.loaded.ext.module.maas:1024][INFO    ][3470] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1248.73986292s left)
2020-02-26 16:24:58,146 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226162458134116
2020-02-26 16:24:58,171 [salt.minion      :1432][INFO    ][3906] Starting a new job with PID 3906
2020-02-26 16:24:58,194 [salt.minion      :1711][INFO    ][3906] Returning information for job: 20200226162458134116
2020-02-26 16:25:04,872 [salt.loaded.ext.module.maas:1024][INFO    ][3470] Waiting status:Ready|Deployed for machines:['gtw01', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1216.22941804s left)
2020-02-26 16:25:28,255 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226162528241632
2020-02-26 16:25:28,280 [salt.minion      :1432][INFO    ][3943] Starting a new job with PID 3943
2020-02-26 16:25:28,305 [salt.minion      :1711][INFO    ][3943] Returning information for job: 20200226162528241632
2020-02-26 16:25:37,583 [salt.loaded.ext.module.maas:1024][INFO    ][3470] Waiting status:Ready|Deployed for machines:['gtw01', 'odl01', 'ctl01']
sleep for:30s Timeout:1500s (1183.51812696s left)
2020-02-26 16:25:58,377 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226162558362959
2020-02-26 16:25:58,410 [salt.minion      :1432][INFO    ][3971] Starting a new job with PID 3971
2020-02-26 16:25:58,432 [salt.minion      :1711][INFO    ][3971] Returning information for job: 20200226162558362959
2020-02-26 16:26:10,210 [salt.loaded.ext.module.maas:1024][INFO    ][3470] Waiting status:Ready|Deployed for machines:['gtw01', 'odl01', 'ctl01']
sleep for:30s Timeout:1500s (1150.89119005s left)
2020-02-26 16:26:28,463 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226162628455823
2020-02-26 16:26:28,486 [salt.minion      :1432][INFO    ][4000] Starting a new job with PID 4000
2020-02-26 16:26:28,507 [salt.minion      :1711][INFO    ][4000] Returning information for job: 20200226162628455823
2020-02-26 16:26:43,455 [salt.loaded.ext.module.maas:1024][INFO    ][3470] Waiting status:Ready|Deployed for machines:['gtw01', 'odl01', 'ctl01']
sleep for:30s Timeout:1500s (1117.64632893s left)
2020-02-26 16:26:58,610 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226162658594565
2020-02-26 16:26:58,635 [salt.minion      :1432][INFO    ][4085] Starting a new job with PID 4085
2020-02-26 16:26:58,655 [salt.minion      :1711][INFO    ][4085] Returning information for job: 20200226162658594565
2020-02-26 16:27:16,754 [salt.state       :300 ][INFO    ][3470] {'ret': True}
2020-02-26 16:27:16,754 [salt.state       :1951][INFO    ][3470] Completed state [maas.wait_for_machine_status] at time 16:27:16.754919 duration_in_ms=415667.12
2020-02-26 16:27:16,757 [salt.minion      :1711][INFO    ][3470] Returning information for job: 20200226162012263999
2020-02-26 16:27:17,575 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command state.apply with jid 20200226162717565078
2020-02-26 16:27:17,601 [salt.minion      :1432][INFO    ][4102] Starting a new job with PID 4102
2020-02-26 16:27:23,966 [salt.state       :915 ][INFO    ][4102] Loading fresh modules for state activity
2020-02-26 16:27:24,027 [salt.fileclient  :1219][INFO    ][4102] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2020-02-26 16:27:24,131 [salt.state       :1780][INFO    ][4102] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:27:24.131818
2020-02-26 16:27:24,132 [salt.state       :1813][INFO    ][4102] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-26 16:27:24,134 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4102] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-26 16:27:26,174 [salt.state       :300 ][INFO    ][4102] {'pid': 4122, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-26 16:27:26,175 [salt.state       :1951][INFO    ][4102] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:27:26.175149 duration_in_ms=2043.328
2020-02-26 16:27:26,179 [salt.state       :1780][INFO    ][4102] Running state [maas_machines_storage_cmp002_lvm] at time 16:27:26.178947
2020-02-26 16:27:26,179 [salt.state       :1813][INFO    ][4102] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2020-02-26 16:27:27,518 [salt.loaded.ext.module.maasng:610 ][INFO    ][4102] hc4tnx
2020-02-26 16:27:27,518 [salt.loaded.ext.module.maasng:626 ][INFO    ][4102] sda
2020-02-26 16:27:28,219 [salt.loaded.ext.module.maasng:361 ][INFO    ][4102] hc4tnx
2020-02-26 16:27:28,309 [salt.loaded.ext.module.maasng:367 ][INFO    ][4102] [{u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'ef9a0c0c-6a9e-4489-a236-3db1a45d72bf', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hc4tnx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'9ef92b2d-d6f3-442a-a036-789c5cb356e3', 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'device_id': 1}], u'filesystem': None, u'uuid': None, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hc4tnx', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/3/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'0cf4673a-8887-4710-bdae-4d6cc3c85a26', u'fstype': u'ext4'}, u'uuid': u'ae34c485-ac2a-4e60-bd05-d1e13b90e108', u'id': 3, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hc4tnx', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'serial': None, u'size': 800097042432}]
2020-02-26 16:27:28,309 [salt.loaded.ext.module.maasng:632 ][INFO    ][4102] vgroot
2020-02-26 16:27:28,310 [salt.loaded.ext.module.maasng:635 ][INFO    ][4102] lvroot
2020-02-26 16:27:28,310 [salt.loaded.ext.module.maasng:639 ][INFO    ][4102] 107374182400
2020-02-26 16:27:28,948 [salt.loaded.ext.module.maasng:645 ][INFO    ][4102] {u'hwe_kernel': None, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', 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'hm7gpr', 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.38', u'id': 20, 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'hm7gpr', 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'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'primary_rack': u'hm7gpr', 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.38'}], u'children': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'hc4tnx', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/interfaces/5/'}, u'min_hwe_kernel': u'ga-18.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'cpu_speed': 2800, u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/hc4tnx/', u'hardware_info': {u'mainboard_firmware_version': u'I31', u'mainboard_product': u'Unknown', u'system_vendor': u'HP', u'cpu_model': u'Intel(R) Xeon(R) CPU E5-2680 v2', u'mainboard_firmware_date': u'12/20/2013', u'mainboard_vendor': u'Unknown', u'system_serial': u'USE410T2Y0', u'system_version': u'Unknown', u'system_product': u'ProLiant BL460c Gen8 (735151-B21)'}, u'hostname': u'cmp002', u'storage': 800109.715456, u'owner_data': {}, u'system_id': u'hc4tnx', 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'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'e37462bd-6ac4-4b06-9e5f-58d70276a3e7', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hc4tnx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'ae30226e-a9f9-4773-9a76-c34638c08116', 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'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'hc4tnx', 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'id': 1, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/11/', u'uuid': u'03f8f059-e9bf-44f4-9688-589d683ea665', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'hc4tnx', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'4e97a7d0-16a3-4e8f-b79d-490d5aff2fd1', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'e37462bd-6ac4-4b06-9e5f-58d70276a3e7', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hc4tnx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'ae30226e-a9f9-4773-9a76-c34638c08116', 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'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'hc4tnx', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'hc4tnx', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', 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'hm7gpr', 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.38', u'id': 20, 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'hm7gpr', 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'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'primary_rack': u'hm7gpr', 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.38'}], u'children': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'hc4tnx', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/interfaces/5/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', 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'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'hc4tnx', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/interfaces/11/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', 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'hm7gpr', 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': 21, 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'hm7gpr', 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'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'primary_rack': u'hm7gpr', 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.40'}], u'children': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'hc4tnx', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/interfaces/12/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', 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'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'hc4tnx', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/interfaces/13/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'hc4tnx', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/interfaces/14/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 23, 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'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'hc4tnx', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/interfaces/15/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'pool': {u'id': 0, u'resource_uri': u'/MAAS/api/2.0/resourcepool/0/', u'name': u'default', u'description': u'Default pool'}, u'storage_test_status': 2, u'locked': False, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/11/', u'uuid': u'03f8f059-e9bf-44f4-9688-589d683ea665', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'hc4tnx', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'4e97a7d0-16a3-4e8f-b79d-490d5aff2fd1', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_disk': {u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'e37462bd-6ac4-4b06-9e5f-58d70276a3e7', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hc4tnx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'ae30226e-a9f9-4773-9a76-c34638c08116', 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'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'hc4tnx', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2020-02-26 16:27:28,951 [salt.state       :300 ][INFO    ][4102] {'new': {'storage_layout': 'lvm'}}
2020-02-26 16:27:28,951 [salt.state       :1951][INFO    ][4102] Completed state [maas_machines_storage_cmp002_lvm] at time 16:27:28.951443 duration_in_ms=2772.494
2020-02-26 16:27:28,952 [salt.state       :1780][INFO    ][4102] Running state [maas_machines_storage_cmp001_lvm] at time 16:27:28.952176
2020-02-26 16:27:28,952 [salt.state       :1813][INFO    ][4102] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2020-02-26 16:27:30,238 [salt.loaded.ext.module.maasng:610 ][INFO    ][4102] qp8qxx
2020-02-26 16:27:30,239 [salt.loaded.ext.module.maasng:626 ][INFO    ][4102] sda
2020-02-26 16:27:30,885 [salt.loaded.ext.module.maasng:361 ][INFO    ][4102] qp8qxx
2020-02-26 16:27:30,965 [salt.loaded.ext.module.maasng:367 ][INFO    ][4102] [{u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/2/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'81f36ed3-35bb-49ea-ab8c-ee6e479ee44c', u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qp8qxx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'91bea860-29df-4c9d-937f-be78462be23b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'size': 800101236736}], u'uuid': None, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qp8qxx', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/4/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'uuid': u'd52a7b28-349f-4f81-9070-448a30e70475', u'id': 4, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qp8qxx', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'd80a351c-5f79-401e-baec-4433a6507165', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 800097042432}]
2020-02-26 16:27:30,966 [salt.loaded.ext.module.maasng:632 ][INFO    ][4102] vgroot
2020-02-26 16:27:30,966 [salt.loaded.ext.module.maasng:635 ][INFO    ][4102] lvroot
2020-02-26 16:27:30,966 [salt.loaded.ext.module.maasng:639 ][INFO    ][4102] 107374182400
2020-02-26 16:27:31,617 [salt.loaded.ext.module.maasng:645 ][INFO    ][4102] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': None, u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'372ed631-ffe9-4827-b4c1-ad6ceb2b49c1', u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qp8qxx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'145067c7-025b-4d90-8718-09014eb08f5b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}], u'filesystem': None, u'uuid': None, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qp8qxx', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'62dfc7b4-8c2b-4395-94b4-39a45c7ebfcd', u'fstype': u'ext4'}, u'uuid': u'110fb5c5-f503-4d3a-8eae-98e99fb48434', u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qp8qxx', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/qp8qxx/', u'hardware_info': {u'mainboard_firmware_version': u'I31', u'mainboard_product': u'Unknown', u'system_vendor': u'HP', u'cpu_model': u'Intel(R) Xeon(R) CPU E5-2680 v2', u'mainboard_firmware_date': u'06/01/2015', u'mainboard_vendor': u'Unknown', u'system_serial': u'USE410T2XY', u'system_version': u'Unknown', u'system_product': u'ProLiant BL460c Gen8 (735151-B21)'}, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'qp8qxx', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 12, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'62dfc7b4-8c2b-4395-94b4-39a45c7ebfcd', u'fstype': u'ext4'}, u'uuid': u'110fb5c5-f503-4d3a-8eae-98e99fb48434', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/12/', u'system_id': u'qp8qxx', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'serial': None, u'partitions': []}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/2/', u'system_id': u'qp8qxx', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'size': 800109715456, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'372ed631-ffe9-4827-b4c1-ad6ceb2b49c1', u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qp8qxx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'145067c7-025b-4d90-8718-09014eb08f5b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'qp8qxx', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 8, u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 29, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'id': 7, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'qp8qxx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/interfaces/7/'}, u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 29, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'id': 7, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'qp8qxx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/interfaces/7/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'id': 16, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'qp8qxx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/interfaces/16/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 30, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'id': 17, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.44'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'qp8qxx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/interfaces/17/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'id': 18, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'qp8qxx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/interfaces/18/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 31, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'fabric': u'fabric-0'}, u'effective_mtu': 1500, u'id': 19, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'qp8qxx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/interfaces/19/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 32, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'fabric': u'fabric-0'}, u'effective_mtu': 1500, u'id': 20, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'qp8qxx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/interfaces/20/'}], u'current_testing_result_id': 9, u'cpu_test_status': -1, u'pool': {u'id': 0, u'resource_uri': u'/MAAS/api/2.0/resourcepool/0/', u'name': u'default', u'description': u'Default pool'}, u'bcaches': [], u'locked': False, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'commissioning_status_name': u'Passed', u'boot_disk': {u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/2/', u'system_id': u'qp8qxx', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'size': 800109715456, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'372ed631-ffe9-4827-b4c1-ad6ceb2b49c1', u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qp8qxx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'145067c7-025b-4d90-8718-09014eb08f5b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}]}, u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2020-02-26 16:27:31,618 [salt.state       :300 ][INFO    ][4102] {'new': {'storage_layout': 'lvm'}}
2020-02-26 16:27:31,618 [salt.state       :1951][INFO    ][4102] Completed state [maas_machines_storage_cmp001_lvm] at time 16:27:31.618883 duration_in_ms=2666.707
2020-02-26 16:27:31,623 [salt.minion      :1711][INFO    ][4102] Returning information for job: 20200226162717565078
2020-02-26 16:27:32,433 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command state.apply with jid 20200226162732417308
2020-02-26 16:27:32,452 [salt.minion      :1432][INFO    ][4139] Starting a new job with PID 4139
2020-02-26 16:27:33,315 [salt.state       :915 ][INFO    ][4139] Loading fresh modules for state activity
2020-02-26 16:27:33,370 [salt.fileclient  :1219][INFO    ][4139] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2020-02-26 16:27:33,422 [salt.state       :1780][INFO    ][4139] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:27:33.422898
2020-02-26 16:27:33,423 [salt.state       :1813][INFO    ][4139] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-26 16:27:33,424 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4139] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-26 16:27:35,474 [salt.state       :300 ][INFO    ][4139] {'pid': 4146, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-26 16:27:35,475 [salt.state       :1951][INFO    ][4139] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:27:35.474921 duration_in_ms=2052.025
2020-02-26 16:27:35,477 [salt.state       :1780][INFO    ][4139] Running state [maas.deploy_machines] at time 16:27:35.477789
2020-02-26 16:27:35,478 [salt.state       :1813][INFO    ][4139] Executing state module.run for [maas.deploy_machines]
2020-02-26 16:27:35,478 [salt.utils.decorators:613 ][WARNING ][4139] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-26 16:27:36,147 [salt.loaded.ext.module.maas:684 ][INFO    ][4139] deploymachines hwe_kernel=ga-18.04 system_id=nkp4yk distro_series=bionic
2020-02-26 16:27:38,283 [salt.loaded.ext.module.maas:684 ][INFO    ][4139] deploymachines hwe_kernel=ga-18.04 system_id=hc4tnx distro_series=bionic
2020-02-26 16:27:40,246 [salt.loaded.ext.module.maas:684 ][INFO    ][4139] deploymachines hwe_kernel=ga-18.04 system_id=636f3n distro_series=bionic
2020-02-26 16:27:42,225 [salt.loaded.ext.module.maas:684 ][INFO    ][4139] deploymachines hwe_kernel=ga-18.04 system_id=qp8qxx distro_series=bionic
2020-02-26 16:27:44,235 [salt.loaded.ext.module.maas:684 ][INFO    ][4139] deploymachines hwe_kernel=ga-18.04 system_id=bdm6g3 distro_series=bionic
2020-02-26 16:27:46,279 [salt.state       :300 ][INFO    ][4139] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']}}
2020-02-26 16:27:46,280 [salt.state       :1951][INFO    ][4139] Completed state [maas.deploy_machines] at time 16:27:46.280045 duration_in_ms=10802.254
2020-02-26 16:27:46,283 [salt.minion      :1711][INFO    ][4139] Returning information for job: 20200226162732417308
2020-02-26 16:27:47,097 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command state.apply with jid 20200226162747084862
2020-02-26 16:27:47,121 [salt.minion      :1432][INFO    ][4187] Starting a new job with PID 4187
2020-02-26 16:27:53,385 [salt.state       :915 ][INFO    ][4187] Loading fresh modules for state activity
2020-02-26 16:27:53,451 [salt.fileclient  :1219][INFO    ][4187] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2020-02-26 16:27:53,509 [salt.state       :1780][INFO    ][4187] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:27:53.509363
2020-02-26 16:27:53,509 [salt.state       :1813][INFO    ][4187] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-26 16:27:53,511 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4187] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-26 16:27:55,443 [salt.state       :300 ][INFO    ][4187] {'pid': 4211, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-26 16:27:55,444 [salt.state       :1951][INFO    ][4187] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:27:55.444822 duration_in_ms=1935.458
2020-02-26 16:27:55,448 [salt.state       :1780][INFO    ][4187] Running state [maas.wait_for_machine_status] at time 16:27:55.448180
2020-02-26 16:27:55,449 [salt.state       :1813][INFO    ][4187] Executing state module.run for [maas.wait_for_machine_status]
2020-02-26 16:27:55,449 [salt.utils.decorators:613 ][WARNING ][4187] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-26 16:27:59,007 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2246.45303297s left)
2020-02-26 16:28:02,139 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226162802128812
2020-02-26 16:28:02,164 [salt.minion      :1432][INFO    ][4245] Starting a new job with PID 4245
2020-02-26 16:28:02,183 [salt.minion      :1711][INFO    ][4245] Returning information for job: 20200226162802128812
2020-02-26 16:28:32,202 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226162832190740
2020-02-26 16:28:32,224 [salt.minion      :1432][INFO    ][4284] Starting a new job with PID 4284
2020-02-26 16:28:32,242 [salt.minion      :1711][INFO    ][4284] Returning information for job: 20200226162832190740
2020-02-26 16:28:32,361 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2213.09898996s left)
2020-02-26 16:29:02,265 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226162902253184
2020-02-26 16:29:02,289 [salt.minion      :1432][INFO    ][4331] Starting a new job with PID 4331
2020-02-26 16:29:02,310 [salt.minion      :1711][INFO    ][4331] Returning information for job: 20200226162902253184
2020-02-26 16:29:06,034 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2179.42585802s left)
2020-02-26 16:29:32,335 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226162932324163
2020-02-26 16:29:32,366 [salt.minion      :1432][INFO    ][4366] Starting a new job with PID 4366
2020-02-26 16:29:32,386 [salt.minion      :1711][INFO    ][4366] Returning information for job: 20200226162932324163
2020-02-26 16:29:39,475 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2145.98456001s left)
2020-02-26 16:30:02,407 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226163002397624
2020-02-26 16:30:02,435 [salt.minion      :1432][INFO    ][4396] Starting a new job with PID 4396
2020-02-26 16:30:02,458 [salt.minion      :1711][INFO    ][4396] Returning information for job: 20200226163002397624
2020-02-26 16:30:12,802 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2112.65780807s left)
2020-02-26 16:30:32,477 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226163032463521
2020-02-26 16:30:32,500 [salt.minion      :1432][INFO    ][4422] Starting a new job with PID 4422
2020-02-26 16:30:32,520 [salt.minion      :1711][INFO    ][4422] Returning information for job: 20200226163032463521
2020-02-26 16:30:46,467 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2078.99212694s left)
2020-02-26 16:31:02,523 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226163102512445
2020-02-26 16:31:02,551 [salt.minion      :1432][INFO    ][4462] Starting a new job with PID 4462
2020-02-26 16:31:02,573 [salt.minion      :1711][INFO    ][4462] Returning information for job: 20200226163102512445
2020-02-26 16:31:19,762 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2045.69718003s left)
2020-02-26 16:31:32,607 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226163132593696
2020-02-26 16:31:32,633 [salt.minion      :1432][INFO    ][4521] Starting a new job with PID 4521
2020-02-26 16:31:32,651 [salt.minion      :1711][INFO    ][4521] Returning information for job: 20200226163132593696
2020-02-26 16:31:53,303 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2012.15706205s left)
2020-02-26 16:32:02,705 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226163202697329
2020-02-26 16:32:02,731 [salt.minion      :1432][INFO    ][4576] Starting a new job with PID 4576
2020-02-26 16:32:02,751 [salt.minion      :1711][INFO    ][4576] Returning information for job: 20200226163202697329
2020-02-26 16:32:24,768 [salt.loaded.ext.module.maas:993 ][INFO    ][4187] Machine hc4tnx mark broken
2020-02-26 16:32:32,783 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226163232776035
2020-02-26 16:32:32,796 [salt.minion      :1432][INFO    ][4635] Starting a new job with PID 4635
2020-02-26 16:32:32,816 [salt.minion      :1711][INFO    ][4635] Returning information for job: 20200226163232776035
2020-02-26 16:32:55,335 [salt.loaded.ext.module.maas:997 ][INFO    ][4187] Machine hc4tnx mark fixed
2020-02-26 16:32:56,434 [salt.loaded.ext.module.maas:684 ][INFO    ][4187] deploymachines hwe_kernel=ga-18.04 system_id=hc4tnx distro_series=bionic
2020-02-26 16:32:59,500 [salt.loaded.ext.module.maas:993 ][INFO    ][4187] Machine qp8qxx mark broken
2020-02-26 16:33:02,838 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226163302830011
2020-02-26 16:33:02,865 [salt.minion      :1432][INFO    ][4686] Starting a new job with PID 4686
2020-02-26 16:33:02,887 [salt.minion      :1711][INFO    ][4686] Returning information for job: 20200226163302830011
2020-02-26 16:33:30,049 [salt.loaded.ext.module.maas:997 ][INFO    ][4187] Machine qp8qxx mark fixed
2020-02-26 16:33:31,229 [salt.loaded.ext.module.maas:684 ][INFO    ][4187] deploymachines hwe_kernel=ga-18.04 system_id=qp8qxx distro_series=bionic
2020-02-26 16:33:32,962 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226163332951244
2020-02-26 16:33:32,981 [salt.minion      :1432][INFO    ][4772] Starting a new job with PID 4772
2020-02-26 16:33:33,007 [salt.minion      :1711][INFO    ][4772] Returning information for job: 20200226163332951244
2020-02-26 16:33:33,896 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1911.56327605s left)
2020-02-26 16:34:03,070 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226163403059128
2020-02-26 16:34:03,092 [salt.minion      :1432][INFO    ][4814] Starting a new job with PID 4814
2020-02-26 16:34:03,117 [salt.minion      :1711][INFO    ][4814] Returning information for job: 20200226163403059128
2020-02-26 16:34:07,355 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1878.10497594s left)
2020-02-26 16:34:33,189 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226163433176913
2020-02-26 16:34:33,215 [salt.minion      :1432][INFO    ][4838] Starting a new job with PID 4838
2020-02-26 16:34:33,241 [salt.minion      :1711][INFO    ][4838] Returning information for job: 20200226163433176913
2020-02-26 16:34:40,930 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1844.52934003s left)
2020-02-26 16:35:03,303 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226163503286464
2020-02-26 16:35:03,327 [salt.minion      :1432][INFO    ][4871] Starting a new job with PID 4871
2020-02-26 16:35:03,347 [salt.minion      :1711][INFO    ][4871] Returning information for job: 20200226163503286464
2020-02-26 16:35:14,270 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1811.18944812s left)
2020-02-26 16:35:33,431 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226163533417331
2020-02-26 16:35:33,459 [salt.minion      :1432][INFO    ][4895] Starting a new job with PID 4895
2020-02-26 16:35:33,482 [salt.minion      :1711][INFO    ][4895] Returning information for job: 20200226163533417331
2020-02-26 16:35:47,414 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1778.04603195s left)
2020-02-26 16:36:03,555 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226163603544021
2020-02-26 16:36:03,575 [salt.minion      :1432][INFO    ][4949] Starting a new job with PID 4949
2020-02-26 16:36:03,598 [salt.minion      :1711][INFO    ][4949] Returning information for job: 20200226163603544021
2020-02-26 16:36:20,895 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1744.56511497s left)
2020-02-26 16:36:33,695 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226163633685589
2020-02-26 16:36:33,716 [salt.minion      :1432][INFO    ][5003] Starting a new job with PID 5003
2020-02-26 16:36:33,747 [salt.minion      :1711][INFO    ][5003] Returning information for job: 20200226163633685589
2020-02-26 16:36:52,187 [salt.loaded.ext.module.maas:993 ][INFO    ][4187] Machine hc4tnx mark broken
2020-02-26 16:37:03,842 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226163703833271
2020-02-26 16:37:03,863 [salt.minion      :1432][INFO    ][5049] Starting a new job with PID 5049
2020-02-26 16:37:03,886 [salt.minion      :1711][INFO    ][5049] Returning information for job: 20200226163703833271
2020-02-26 16:37:22,764 [salt.loaded.ext.module.maas:997 ][INFO    ][4187] Machine hc4tnx mark fixed
2020-02-26 16:37:23,265 [salt.loaded.ext.module.maas:1001][INFO    ][4187] Machine hc4tnx fio test
2020-02-26 16:37:26,620 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1678.83940601s left)
2020-02-26 16:37:34,000 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226163733985628
2020-02-26 16:37:34,030 [salt.minion      :1432][INFO    ][5097] Starting a new job with PID 5097
2020-02-26 16:37:34,052 [salt.minion      :1711][INFO    ][5097] Returning information for job: 20200226163733985628
2020-02-26 16:37:59,237 [salt.loaded.ext.module.maas:993 ][INFO    ][4187] Machine qp8qxx mark broken
2020-02-26 16:38:04,154 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226163804138440
2020-02-26 16:38:04,179 [salt.minion      :1432][INFO    ][5160] Starting a new job with PID 5160
2020-02-26 16:38:04,201 [salt.minion      :1711][INFO    ][5160] Returning information for job: 20200226163804138440
2020-02-26 16:38:29,848 [salt.loaded.ext.module.maas:997 ][INFO    ][4187] Machine qp8qxx mark fixed
2020-02-26 16:38:30,375 [salt.loaded.ext.module.maas:1001][INFO    ][4187] Machine qp8qxx fio test
2020-02-26 16:38:32,312 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1613.147578s left)
2020-02-26 16:38:34,326 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226163834312837
2020-02-26 16:38:34,355 [salt.minion      :1432][INFO    ][5186] Starting a new job with PID 5186
2020-02-26 16:38:34,376 [salt.minion      :1711][INFO    ][5186] Returning information for job: 20200226163834312837
2020-02-26 16:39:04,494 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226163904481605
2020-02-26 16:39:04,519 [salt.minion      :1432][INFO    ][5234] Starting a new job with PID 5234
2020-02-26 16:39:04,539 [salt.minion      :1711][INFO    ][5234] Returning information for job: 20200226163904481605
2020-02-26 16:39:05,193 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1580.26634002s left)
2020-02-26 16:39:34,665 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226163934652844
2020-02-26 16:39:34,689 [salt.minion      :1432][INFO    ][5258] Starting a new job with PID 5258
2020-02-26 16:39:34,713 [salt.minion      :1711][INFO    ][5258] Returning information for job: 20200226163934652844
2020-02-26 16:39:38,332 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1547.12814403s left)
2020-02-26 16:40:04,830 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226164004822439
2020-02-26 16:40:04,845 [salt.minion      :1432][INFO    ][5303] Starting a new job with PID 5303
2020-02-26 16:40:04,865 [salt.minion      :1711][INFO    ][5303] Returning information for job: 20200226164004822439
2020-02-26 16:40:11,114 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1514.34582806s left)
2020-02-26 16:40:34,992 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226164034982642
2020-02-26 16:40:35,015 [salt.minion      :1432][INFO    ][5329] Starting a new job with PID 5329
2020-02-26 16:40:35,039 [salt.minion      :1711][INFO    ][5329] Returning information for job: 20200226164034982642
2020-02-26 16:40:44,014 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1481.44576406s left)
2020-02-26 16:41:05,188 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226164105174631
2020-02-26 16:41:05,214 [salt.minion      :1432][INFO    ][5372] Starting a new job with PID 5372
2020-02-26 16:41:05,235 [salt.minion      :1711][INFO    ][5372] Returning information for job: 20200226164105174631
2020-02-26 16:41:17,042 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1448.41747403s left)
2020-02-26 16:41:35,391 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226164135381372
2020-02-26 16:41:35,409 [salt.minion      :1432][INFO    ][5417] Starting a new job with PID 5417
2020-02-26 16:41:35,429 [salt.minion      :1711][INFO    ][5417] Returning information for job: 20200226164135381372
2020-02-26 16:41:50,143 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1415.31698394s left)
2020-02-26 16:42:05,524 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226164205514947
2020-02-26 16:42:05,549 [salt.minion      :1432][INFO    ][5482] Starting a new job with PID 5482
2020-02-26 16:42:05,566 [salt.minion      :1711][INFO    ][5482] Returning information for job: 20200226164205514947
2020-02-26 16:42:23,513 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1381.94628215s left)
2020-02-26 16:42:35,570 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226164235554875
2020-02-26 16:42:35,598 [salt.minion      :1432][INFO    ][5537] Starting a new job with PID 5537
2020-02-26 16:42:35,621 [salt.minion      :1711][INFO    ][5537] Returning information for job: 20200226164235554875
2020-02-26 16:42:56,618 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1348.84162498s left)
2020-02-26 16:43:05,614 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226164305600703
2020-02-26 16:43:05,637 [salt.minion      :1432][INFO    ][5574] Starting a new job with PID 5574
2020-02-26 16:43:05,659 [salt.minion      :1711][INFO    ][5574] Returning information for job: 20200226164305600703
2020-02-26 16:43:29,792 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1315.66730094s left)
2020-02-26 16:43:35,646 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226164335633607
2020-02-26 16:43:35,670 [salt.minion      :1432][INFO    ][5595] Starting a new job with PID 5595
2020-02-26 16:43:35,693 [salt.minion      :1711][INFO    ][5595] Returning information for job: 20200226164335633607
2020-02-26 16:44:03,330 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1282.12936997s left)
2020-02-26 16:44:05,709 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226164405696653
2020-02-26 16:44:05,740 [salt.minion      :1432][INFO    ][5653] Starting a new job with PID 5653
2020-02-26 16:44:05,762 [salt.minion      :1711][INFO    ][5653] Returning information for job: 20200226164405696653
2020-02-26 16:44:35,775 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226164435763493
2020-02-26 16:44:35,794 [salt.minion      :1432][INFO    ][5671] Starting a new job with PID 5671
2020-02-26 16:44:35,817 [salt.minion      :1711][INFO    ][5671] Returning information for job: 20200226164435763493
2020-02-26 16:44:36,777 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1248.68281603s left)
2020-02-26 16:45:05,847 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226164505838614
2020-02-26 16:45:05,865 [salt.minion      :1432][INFO    ][5709] Starting a new job with PID 5709
2020-02-26 16:45:05,889 [salt.minion      :1711][INFO    ][5709] Returning information for job: 20200226164505838614
2020-02-26 16:45:10,162 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1215.29775715s left)
2020-02-26 16:45:35,922 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226164535906078
2020-02-26 16:45:35,945 [salt.minion      :1432][INFO    ][5729] Starting a new job with PID 5729
2020-02-26 16:45:35,970 [salt.minion      :1711][INFO    ][5729] Returning information for job: 20200226164535906078
2020-02-26 16:45:43,468 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1181.99154305s left)
2020-02-26 16:46:06,030 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226164606019158
2020-02-26 16:46:06,057 [salt.minion      :1432][INFO    ][5765] Starting a new job with PID 5765
2020-02-26 16:46:06,084 [salt.minion      :1711][INFO    ][5765] Returning information for job: 20200226164606019158
2020-02-26 16:46:16,954 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1148.50518513s left)
2020-02-26 16:46:36,160 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226164636149260
2020-02-26 16:46:36,184 [salt.minion      :1432][INFO    ][5792] Starting a new job with PID 5792
2020-02-26 16:46:36,202 [salt.minion      :1711][INFO    ][5792] Returning information for job: 20200226164636149260
2020-02-26 16:46:50,351 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1115.10868216s left)
2020-02-26 16:47:06,300 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226164706290496
2020-02-26 16:47:06,325 [salt.minion      :1432][INFO    ][5827] Starting a new job with PID 5827
2020-02-26 16:47:06,351 [salt.minion      :1711][INFO    ][5827] Returning information for job: 20200226164706290496
2020-02-26 16:47:23,881 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1081.57823205s left)
2020-02-26 16:47:36,447 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226164736439331
2020-02-26 16:47:36,472 [salt.minion      :1432][INFO    ][5847] Starting a new job with PID 5847
2020-02-26 16:47:36,499 [salt.minion      :1711][INFO    ][5847] Returning information for job: 20200226164736439331
2020-02-26 16:47:57,281 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1048.17820311s left)
2020-02-26 16:48:06,599 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226164806585851
2020-02-26 16:48:06,626 [salt.minion      :1432][INFO    ][5883] Starting a new job with PID 5883
2020-02-26 16:48:06,651 [salt.minion      :1711][INFO    ][5883] Returning information for job: 20200226164806585851
2020-02-26 16:48:30,710 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1014.74961901s left)
2020-02-26 16:48:36,779 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226164836767391
2020-02-26 16:48:36,807 [salt.minion      :1432][INFO    ][5901] Starting a new job with PID 5901
2020-02-26 16:48:36,832 [salt.minion      :1711][INFO    ][5901] Returning information for job: 20200226164836767391
2020-02-26 16:49:04,107 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (981.353016138s left)
2020-02-26 16:49:06,966 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226164906952006
2020-02-26 16:49:06,990 [salt.minion      :1432][INFO    ][5946] Starting a new job with PID 5946
2020-02-26 16:49:07,016 [salt.minion      :1711][INFO    ][5946] Returning information for job: 20200226164906952006
2020-02-26 16:49:37,178 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226164937167677
2020-02-26 16:49:37,197 [salt.minion      :1432][INFO    ][5971] Starting a new job with PID 5971
2020-02-26 16:49:37,220 [salt.minion      :1711][INFO    ][5971] Returning information for job: 20200226164937167677
2020-02-26 16:49:37,398 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (948.062014103s left)
2020-02-26 16:50:07,371 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226165007355988
2020-02-26 16:50:07,399 [salt.minion      :1432][INFO    ][6005] Starting a new job with PID 6005
2020-02-26 16:50:07,427 [salt.minion      :1711][INFO    ][6005] Returning information for job: 20200226165007355988
2020-02-26 16:50:10,672 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (914.788089991s left)
2020-02-26 16:50:37,425 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226165037417455
2020-02-26 16:50:37,446 [salt.minion      :1432][INFO    ][6030] Starting a new job with PID 6030
2020-02-26 16:50:37,471 [salt.minion      :1711][INFO    ][6030] Returning information for job: 20200226165037417455
2020-02-26 16:50:43,961 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (881.499100924s left)
2020-02-26 16:51:07,481 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226165107463365
2020-02-26 16:51:07,506 [salt.minion      :1432][INFO    ][6066] Starting a new job with PID 6066
2020-02-26 16:51:07,543 [salt.minion      :1711][INFO    ][6066] Returning information for job: 20200226165107463365
2020-02-26 16:51:17,490 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (847.969676018s left)
2020-02-26 16:51:37,567 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226165137558008
2020-02-26 16:51:37,587 [salt.minion      :1432][INFO    ][6093] Starting a new job with PID 6093
2020-02-26 16:51:37,610 [salt.minion      :1711][INFO    ][6093] Returning information for job: 20200226165137558008
2020-02-26 16:51:50,846 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (814.613781929s left)
2020-02-26 16:52:07,614 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226165207602412
2020-02-26 16:52:07,635 [salt.minion      :1432][INFO    ][6137] Starting a new job with PID 6137
2020-02-26 16:52:07,673 [salt.minion      :1711][INFO    ][6137] Returning information for job: 20200226165207602412
2020-02-26 16:52:24,146 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (781.31382513s left)
2020-02-26 16:52:37,714 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226165237702820
2020-02-26 16:52:37,739 [salt.minion      :1432][INFO    ][6156] Starting a new job with PID 6156
2020-02-26 16:52:37,771 [salt.minion      :1711][INFO    ][6156] Returning information for job: 20200226165237702820
2020-02-26 16:52:57,143 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (748.316272974s left)
2020-02-26 16:53:07,810 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226165307795309
2020-02-26 16:53:07,839 [salt.minion      :1432][INFO    ][6193] Starting a new job with PID 6193
2020-02-26 16:53:07,865 [salt.minion      :1711][INFO    ][6193] Returning information for job: 20200226165307795309
2020-02-26 16:53:30,237 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (715.223089933s left)
2020-02-26 16:53:37,962 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226165337946321
2020-02-26 16:53:37,991 [salt.minion      :1432][INFO    ][6215] Starting a new job with PID 6215
2020-02-26 16:53:38,022 [salt.minion      :1711][INFO    ][6215] Returning information for job: 20200226165337946321
2020-02-26 16:54:03,446 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (682.015825033s left)
2020-02-26 16:54:08,117 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226165408104860
2020-02-26 16:54:08,135 [salt.minion      :1432][INFO    ][6252] Starting a new job with PID 6252
2020-02-26 16:54:08,165 [salt.minion      :1711][INFO    ][6252] Returning information for job: 20200226165408104860
2020-02-26 16:54:36,746 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (648.713474989s left)
2020-02-26 16:54:38,283 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226165438266534
2020-02-26 16:54:38,313 [salt.minion      :1432][INFO    ][6271] Starting a new job with PID 6271
2020-02-26 16:54:38,338 [salt.minion      :1711][INFO    ][6271] Returning information for job: 20200226165438266534
2020-02-26 16:55:08,465 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226165508452067
2020-02-26 16:55:08,483 [salt.minion      :1432][INFO    ][6310] Starting a new job with PID 6310
2020-02-26 16:55:08,511 [salt.minion      :1711][INFO    ][6310] Returning information for job: 20200226165508452067
2020-02-26 16:55:09,969 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (615.490814924s left)
2020-02-26 16:55:38,647 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226165538637837
2020-02-26 16:55:38,673 [salt.minion      :1432][INFO    ][6328] Starting a new job with PID 6328
2020-02-26 16:55:38,701 [salt.minion      :1711][INFO    ][6328] Returning information for job: 20200226165538637837
2020-02-26 16:55:43,075 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (582.384752989s left)
2020-02-26 16:56:08,662 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226165608654175
2020-02-26 16:56:08,686 [salt.minion      :1432][INFO    ][6361] Starting a new job with PID 6361
2020-02-26 16:56:08,714 [salt.minion      :1711][INFO    ][6361] Returning information for job: 20200226165608654175
2020-02-26 16:56:16,166 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (549.294050932s left)
2020-02-26 16:56:38,868 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226165638854696
2020-02-26 16:56:38,892 [salt.minion      :1432][INFO    ][6379] Starting a new job with PID 6379
2020-02-26 16:56:38,920 [salt.minion      :1711][INFO    ][6379] Returning information for job: 20200226165638854696
2020-02-26 16:56:49,409 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (516.050147057s left)
2020-02-26 16:57:08,944 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226165708931721
2020-02-26 16:57:08,969 [salt.minion      :1432][INFO    ][6414] Starting a new job with PID 6414
2020-02-26 16:57:08,994 [salt.minion      :1711][INFO    ][6414] Returning information for job: 20200226165708931721
2020-02-26 16:57:22,566 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (482.893779993s left)
2020-02-26 16:57:38,987 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226165738977394
2020-02-26 16:57:39,010 [salt.minion      :1432][INFO    ][6435] Starting a new job with PID 6435
2020-02-26 16:57:39,045 [salt.minion      :1711][INFO    ][6435] Returning information for job: 20200226165738977394
2020-02-26 16:57:55,713 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (449.746263027s left)
2020-02-26 16:58:09,077 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226165809057011
2020-02-26 16:58:09,103 [salt.minion      :1432][INFO    ][6471] Starting a new job with PID 6471
2020-02-26 16:58:09,129 [salt.minion      :1711][INFO    ][6471] Returning information for job: 20200226165809057011
2020-02-26 16:58:28,902 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (416.557659149s left)
2020-02-26 16:58:39,138 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226165839125293
2020-02-26 16:58:39,162 [salt.minion      :1432][INFO    ][6489] Starting a new job with PID 6489
2020-02-26 16:58:39,187 [salt.minion      :1711][INFO    ][6489] Returning information for job: 20200226165839125293
2020-02-26 16:59:02,252 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (383.207230091s left)
2020-02-26 16:59:09,274 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226165909262652
2020-02-26 16:59:09,298 [salt.minion      :1432][INFO    ][6540] Starting a new job with PID 6540
2020-02-26 16:59:09,332 [salt.minion      :1711][INFO    ][6540] Returning information for job: 20200226165909262652
2020-02-26 16:59:35,548 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (349.911473989s left)
2020-02-26 16:59:39,402 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226165939387363
2020-02-26 16:59:39,426 [salt.minion      :1432][INFO    ][6564] Starting a new job with PID 6564
2020-02-26 16:59:39,461 [salt.minion      :1711][INFO    ][6564] Returning information for job: 20200226165939387363
2020-02-26 17:00:08,722 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (316.738003016s left)
2020-02-26 17:00:09,570 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226170009557151
2020-02-26 17:00:09,595 [salt.minion      :1432][INFO    ][6598] Starting a new job with PID 6598
2020-02-26 17:00:09,622 [salt.minion      :1711][INFO    ][6598] Returning information for job: 20200226170009557151
2020-02-26 17:00:39,726 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226170039715052
2020-02-26 17:00:39,743 [salt.minion      :1432][INFO    ][6619] Starting a new job with PID 6619
2020-02-26 17:00:39,770 [salt.minion      :1711][INFO    ][6619] Returning information for job: 20200226170039715052
2020-02-26 17:00:41,960 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (283.499339104s left)
2020-02-26 17:01:09,935 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226170109925645
2020-02-26 17:01:09,956 [salt.minion      :1432][INFO    ][6653] Starting a new job with PID 6653
2020-02-26 17:01:09,980 [salt.minion      :1711][INFO    ][6653] Returning information for job: 20200226170109925645
2020-02-26 17:01:15,049 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (250.41030097s left)
2020-02-26 17:01:40,139 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226170140131390
2020-02-26 17:01:40,155 [salt.minion      :1432][INFO    ][6671] Starting a new job with PID 6671
2020-02-26 17:01:40,181 [salt.minion      :1711][INFO    ][6671] Returning information for job: 20200226170140131390
2020-02-26 17:01:48,230 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (217.229663134s left)
2020-02-26 17:02:10,180 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226170210169479
2020-02-26 17:02:10,197 [salt.minion      :1432][INFO    ][6705] Starting a new job with PID 6705
2020-02-26 17:02:10,220 [salt.minion      :1711][INFO    ][6705] Returning information for job: 20200226170210169479
2020-02-26 17:02:21,437 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (184.022228956s left)
2020-02-26 17:02:40,233 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226170240224157
2020-02-26 17:02:40,253 [salt.minion      :1432][INFO    ][6725] Starting a new job with PID 6725
2020-02-26 17:02:40,278 [salt.minion      :1711][INFO    ][6725] Returning information for job: 20200226170240224157
2020-02-26 17:02:54,785 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (150.675084114s left)
2020-02-26 17:03:10,308 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226170310294710
2020-02-26 17:03:10,332 [salt.minion      :1432][INFO    ][6762] Starting a new job with PID 6762
2020-02-26 17:03:10,359 [salt.minion      :1711][INFO    ][6762] Returning information for job: 20200226170310294710
2020-02-26 17:03:27,971 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (117.488409996s left)
2020-02-26 17:03:40,378 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226170340369931
2020-02-26 17:03:40,402 [salt.minion      :1432][INFO    ][6781] Starting a new job with PID 6781
2020-02-26 17:03:40,426 [salt.minion      :1711][INFO    ][6781] Returning information for job: 20200226170340369931
2020-02-26 17:04:01,531 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (83.9286129475s left)
2020-02-26 17:04:10,439 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226170410432275
2020-02-26 17:04:10,463 [salt.minion      :1432][INFO    ][6818] Starting a new job with PID 6818
2020-02-26 17:04:10,490 [salt.minion      :1711][INFO    ][6818] Returning information for job: 20200226170410432275
2020-02-26 17:04:34,846 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (50.6136651039s left)
2020-02-26 17:04:40,555 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226170440546328
2020-02-26 17:04:40,580 [salt.minion      :1432][INFO    ][6838] Starting a new job with PID 6838
2020-02-26 17:04:40,604 [salt.minion      :1711][INFO    ][6838] Returning information for job: 20200226170440546328
2020-02-26 17:05:08,032 [salt.loaded.ext.module.maas:1024][INFO    ][4187] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (17.4275741577s left)
2020-02-26 17:05:10,689 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226170510679278
2020-02-26 17:05:10,712 [salt.minion      :1432][INFO    ][6872] Starting a new job with PID 6872
2020-02-26 17:05:10,737 [salt.minion      :1711][INFO    ][6872] Returning information for job: 20200226170510679278
2020-02-26 17:05:40,863 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226170540853438
2020-02-26 17:05:40,878 [salt.minion      :1432][INFO    ][6890] Starting a new job with PID 6890
2020-02-26 17:05:40,903 [salt.minion      :1711][INFO    ][6890] Returning information for job: 20200226170540853438
2020-02-26 17:05:41,069 [salt.state       :302 ][ERROR   ][4187] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['cmp002', 'cmp001']not in Deployed state
2020-02-26 17:05:41,070 [salt.state       :1951][INFO    ][4187] Completed state [maas.wait_for_machine_status] at time 17:05:41.070165 duration_in_ms=2265621.981
2020-02-26 17:05:41,079 [salt.minion      :1711][INFO    ][4187] Returning information for job: 20200226162747084862
2020-02-26 17:05:52,262 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command pillar.get with jid 20200226170552253585
2020-02-26 17:05:52,282 [salt.minion      :1432][INFO    ][6904] Starting a new job with PID 6904
2020-02-26 17:05:52,292 [salt.minion      :1711][INFO    ][6904] Returning information for job: 20200226170552253585
2020-02-26 17:05:53,238 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command service.status with jid 20200226170553218596
2020-02-26 17:05:53,265 [salt.minion      :1432][INFO    ][6909] Starting a new job with PID 6909
2020-02-26 17:05:54,065 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6909] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2020-02-26 17:05:54,117 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6909] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2020-02-26 17:05:54,141 [salt.minion      :1711][INFO    ][6909] Returning information for job: 20200226170553218596
2020-02-26 17:05:55,083 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command state.apply with jid 20200226170555069365
2020-02-26 17:05:55,100 [salt.minion      :1432][INFO    ][6920] Starting a new job with PID 6920
2020-02-26 17:06:01,321 [salt.state       :915 ][INFO    ][6920] Loading fresh modules for state activity
2020-02-26 17:06:01,947 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6920] Executing command 'salt-minion --version' in directory '/root'
2020-02-26 17:06:02,335 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6920] Executing command 'salt-minion --version' in directory '/root'
2020-02-26 17:06:03,402 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6920] Executing command 'salt-minion --version' in directory '/root'
2020-02-26 17:06:03,788 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6920] Executing command 'salt-minion --version' in directory '/root'
2020-02-26 17:06:06,049 [salt.state       :1780][INFO    ][6920] Running state [salt-minion] at time 17:06:06.049519
2020-02-26 17:06:06,050 [salt.state       :1813][INFO    ][6920] Executing state pkg.installed for [salt-minion]
2020-02-26 17:06:06,050 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6920] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-26 17:06:06,169 [salt.state       :300 ][INFO    ][6920] All specified packages are already installed
2020-02-26 17:06:06,170 [salt.state       :1951][INFO    ][6920] Completed state [salt-minion] at time 17:06:06.170263 duration_in_ms=120.745
2020-02-26 17:06:06,170 [salt.state       :1780][INFO    ][6920] Running state [salt_minion_dependency_packages] at time 17:06:06.170644
2020-02-26 17:06:06,171 [salt.state       :1813][INFO    ][6920] Executing state pkg.installed for [salt_minion_dependency_packages]
2020-02-26 17:06:06,183 [salt.state       :300 ][INFO    ][6920] All specified packages are already installed
2020-02-26 17:06:06,183 [salt.state       :1951][INFO    ][6920] Completed state [salt_minion_dependency_packages] at time 17:06:06.183896 duration_in_ms=13.251
2020-02-26 17:06:06,189 [salt.state       :1780][INFO    ][6920] Running state [/etc/salt/minion.d/minion.conf] at time 17:06:06.189075
2020-02-26 17:06:06,189 [salt.state       :1813][INFO    ][6920] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2020-02-26 17:06:06,483 [salt.state       :300 ][INFO    ][6920] File /etc/salt/minion.d/minion.conf is in the correct state
2020-02-26 17:06:06,485 [salt.state       :1951][INFO    ][6920] Completed state [/etc/salt/minion.d/minion.conf] at time 17:06:06.485246 duration_in_ms=296.17
2020-02-26 17:06:06,490 [salt.state       :1780][INFO    ][6920] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 17:06:06.490770
2020-02-26 17:06:06,491 [salt.state       :1813][INFO    ][6920] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2020-02-26 17:06:06,509 [salt.state       :300 ][INFO    ][6920] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2020-02-26 17:06:06,509 [salt.state       :1951][INFO    ][6920] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 17:06:06.509613 duration_in_ms=18.842
2020-02-26 17:06:06,511 [salt.state       :1780][INFO    ][6920] Running state [salt-minion] at time 17:06:06.511808
2020-02-26 17:06:06,512 [salt.state       :1813][INFO    ][6920] Executing state service.running for [salt-minion]
2020-02-26 17:06:06,513 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6920] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2020-02-26 17:06:06,561 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6920] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2020-02-26 17:06:06,585 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6920] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2020-02-26 17:06:06,607 [salt.state       :300 ][INFO    ][6920] The service salt-minion is already running
2020-02-26 17:06:06,608 [salt.state       :1951][INFO    ][6920] Completed state [salt-minion] at time 17:06:06.608383 duration_in_ms=96.574
2020-02-26 17:06:06,611 [salt.state       :1780][INFO    ][6920] Running state [/etc/salt/grains.d] at time 17:06:06.611203
2020-02-26 17:06:06,611 [salt.state       :1813][INFO    ][6920] Executing state file.directory for [/etc/salt/grains.d]
2020-02-26 17:06:06,614 [salt.state       :300 ][INFO    ][6920] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2020-02-26 17:06:06,614 [salt.state       :1951][INFO    ][6920] Completed state [/etc/salt/grains.d] at time 17:06:06.614798 duration_in_ms=3.595
2020-02-26 17:06:06,616 [salt.state       :1780][INFO    ][6920] Running state [/etc/salt/grains] at time 17:06:06.616064
2020-02-26 17:06:06,616 [salt.state       :1813][INFO    ][6920] Executing state file.managed for [/etc/salt/grains]
2020-02-26 17:06:06,617 [salt.state       :300 ][INFO    ][6920] File /etc/salt/grains exists with proper permissions. No changes made.
2020-02-26 17:06:06,618 [salt.state       :1951][INFO    ][6920] Completed state [/etc/salt/grains] at time 17:06:06.618333 duration_in_ms=2.269
2020-02-26 17:06:06,619 [salt.state       :1780][INFO    ][6920] Running state [/etc/salt/grains.d/placeholder] at time 17:06:06.619182
2020-02-26 17:06:06,619 [salt.state       :1813][INFO    ][6920] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2020-02-26 17:06:06,620 [salt.state       :300 ][INFO    ][6920] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2020-02-26 17:06:06,621 [salt.state       :1951][INFO    ][6920] Completed state [/etc/salt/grains.d/placeholder] at time 17:06:06.621144 duration_in_ms=1.962
2020-02-26 17:06:06,621 [salt.state       :1780][INFO    ][6920] Running state [/etc/salt/grains.d/sphinx] at time 17:06:06.621927
2020-02-26 17:06:06,622 [salt.state       :1813][INFO    ][6920] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2020-02-26 17:06:06,625 [salt.state       :300 ][INFO    ][6920] File /etc/salt/grains.d/sphinx is in the correct state
2020-02-26 17:06:06,625 [salt.state       :1951][INFO    ][6920] Completed state [/etc/salt/grains.d/sphinx] at time 17:06:06.625837 duration_in_ms=3.91
2020-02-26 17:06:06,631 [salt.state       :1780][INFO    ][6920] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 17:06:06.631015
2020-02-26 17:06:06,631 [salt.state       :1813][INFO    ][6920] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2020-02-26 17:06:06,632 [salt.state       :300 ][INFO    ][6920] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2020-02-26 17:06:06,633 [salt.state       :1951][INFO    ][6920] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 17:06:06.632891 duration_in_ms=1.877
2020-02-26 17:06:06,633 [salt.state       :1780][INFO    ][6920] Running state [/etc/salt/grains.d/dns_records] at time 17:06:06.633808
2020-02-26 17:06:06,634 [salt.state       :1813][INFO    ][6920] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2020-02-26 17:06:06,638 [salt.state       :300 ][INFO    ][6920] File /etc/salt/grains.d/dns_records is in the correct state
2020-02-26 17:06:06,638 [salt.state       :1951][INFO    ][6920] Completed state [/etc/salt/grains.d/dns_records] at time 17:06:06.638569 duration_in_ms=4.762
2020-02-26 17:06:06,639 [salt.state       :1780][INFO    ][6920] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 17:06:06.639511
2020-02-26 17:06:06,639 [salt.state       :1813][INFO    ][6920] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2020-02-26 17:06:06,640 [salt.state       :300 ][INFO    ][6920] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2020-02-26 17:06:06,640 [salt.state       :1951][INFO    ][6920] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 17:06:06.640725 duration_in_ms=1.214
2020-02-26 17:06:06,641 [salt.state       :1780][INFO    ][6920] Running state [/etc/salt/grains.d/salt] at time 17:06:06.641246
2020-02-26 17:06:06,641 [salt.state       :1813][INFO    ][6920] Executing state file.managed for [/etc/salt/grains.d/salt]
2020-02-26 17:06:06,642 [salt.state       :300 ][INFO    ][6920] File /etc/salt/grains.d/salt is in the correct state
2020-02-26 17:06:06,642 [salt.state       :1951][INFO    ][6920] Completed state [/etc/salt/grains.d/salt] at time 17:06:06.642864 duration_in_ms=1.618
2020-02-26 17:06:06,643 [salt.state       :1780][INFO    ][6920] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 17:06:06.643773
2020-02-26 17:06:06,644 [salt.state       :1813][INFO    ][6920] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2020-02-26 17:06:06,644 [salt.state       :300 ][INFO    ][6920] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2020-02-26 17:06:06,645 [salt.state       :1951][INFO    ][6920] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 17:06:06.645001 duration_in_ms=1.228
2020-02-26 17:06:06,646 [salt.state       :1780][INFO    ][6920] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 17:06:06.646911
2020-02-26 17:06:06,647 [salt.state       :1813][INFO    ][6920] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2020-02-26 17:06:06,647 [salt.state       :300 ][INFO    ][6920] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2020-02-26 17:06:06,647 [salt.state       :1951][INFO    ][6920] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 17:06:06.647742 duration_in_ms=0.831
2020-02-26 17:06:06,649 [salt.state       :1780][INFO    ][6920] Running state [mine.update] at time 17:06:06.649350
2020-02-26 17:06:06,649 [salt.state       :1813][INFO    ][6920] Executing state module.wait for [mine.update]
2020-02-26 17:06:06,649 [salt.state       :300 ][INFO    ][6920] No changes made for mine.update
2020-02-26 17:06:06,650 [salt.state       :1951][INFO    ][6920] Completed state [mine.update] at time 17:06:06.650126 duration_in_ms=0.776
2020-02-26 17:06:06,650 [salt.state       :1780][INFO    ][6920] Running state [ca-certificates] at time 17:06:06.650402
2020-02-26 17:06:06,650 [salt.state       :1813][INFO    ][6920] Executing state pkg.installed for [ca-certificates]
2020-02-26 17:06:06,661 [salt.state       :300 ][INFO    ][6920] All specified packages are already installed
2020-02-26 17:06:06,662 [salt.state       :1951][INFO    ][6920] Completed state [ca-certificates] at time 17:06:06.662053 duration_in_ms=11.65
2020-02-26 17:06:06,663 [salt.state       :1780][INFO    ][6920] Running state [update-ca-certificates] at time 17:06:06.663016
2020-02-26 17:06:06,663 [salt.state       :1813][INFO    ][6920] Executing state cmd.wait for [update-ca-certificates]
2020-02-26 17:06:06,663 [salt.state       :300 ][INFO    ][6920] No changes made for update-ca-certificates
2020-02-26 17:06:06,663 [salt.state       :1951][INFO    ][6920] Completed state [update-ca-certificates] at time 17:06:06.663832 duration_in_ms=0.817
2020-02-26 17:06:06,664 [salt.state       :1780][INFO    ][6920] Running state [iptables] at time 17:06:06.664115
2020-02-26 17:06:06,664 [salt.state       :1813][INFO    ][6920] Executing state pkg.installed for [iptables]
2020-02-26 17:06:06,674 [salt.state       :300 ][INFO    ][6920] All specified packages are already installed
2020-02-26 17:06:06,674 [salt.state       :1951][INFO    ][6920] Completed state [iptables] at time 17:06:06.674201 duration_in_ms=10.085
2020-02-26 17:06:06,674 [salt.state       :1780][INFO    ][6920] Running state [iptables-persistent] at time 17:06:06.674501
2020-02-26 17:06:06,674 [salt.state       :1813][INFO    ][6920] Executing state pkg.installed for [iptables-persistent]
2020-02-26 17:06:06,683 [salt.state       :300 ][INFO    ][6920] All specified packages are already installed
2020-02-26 17:06:06,683 [salt.state       :1951][INFO    ][6920] Completed state [iptables-persistent] at time 17:06:06.683891 duration_in_ms=9.39
2020-02-26 17:06:06,685 [salt.state       :1780][INFO    ][6920] Running state [iptables_modules_v4_load] at time 17:06:06.685537
2020-02-26 17:06:06,685 [salt.state       :1813][INFO    ][6920] Executing state kmod.present for [iptables_modules_v4_load]
2020-02-26 17:06:06,686 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6920] Executing command 'lsmod' in directory '/root'
2020-02-26 17:06:06,710 [salt.state       :300 ][INFO    ][6920] Kernel modules iptable_filter, ip_tables are already present
2020-02-26 17:06:06,711 [salt.state       :1951][INFO    ][6920] Completed state [iptables_modules_v4_load] at time 17:06:06.711486 duration_in_ms=25.948
2020-02-26 17:06:06,713 [salt.state       :1780][INFO    ][6920] Running state [/etc/iptables/rules.v4] at time 17:06:06.713216
2020-02-26 17:06:06,713 [salt.state       :1813][INFO    ][6920] Executing state file.managed for [/etc/iptables/rules.v4]
2020-02-26 17:06:06,816 [salt.state       :300 ][INFO    ][6920] File /etc/iptables/rules.v4 is in the correct state
2020-02-26 17:06:06,816 [salt.state       :1951][INFO    ][6920] Completed state [/etc/iptables/rules.v4] at time 17:06:06.816833 duration_in_ms=103.618
2020-02-26 17:06:06,818 [salt.state       :1780][INFO    ][6920] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 17:06:06.818011
2020-02-26 17:06:06,818 [salt.state       :1813][INFO    ][6920] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2020-02-26 17:06:06,818 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6920] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2020-02-26 17:06:06,836 [salt.state       :300 ][INFO    ][6920] onlyif execution failed
2020-02-26 17:06:06,837 [salt.state       :1951][INFO    ][6920] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 17:06:06.836905 duration_in_ms=18.894
2020-02-26 17:06:06,838 [salt.state       :1780][INFO    ][6920] Running state [netfilter-persistent] at time 17:06:06.838413
2020-02-26 17:06:06,838 [salt.state       :1813][INFO    ][6920] Executing state service.running for [netfilter-persistent]
2020-02-26 17:06:06,839 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6920] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2020-02-26 17:06:06,863 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6920] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2020-02-26 17:06:06,887 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6920] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2020-02-26 17:06:06,914 [salt.state       :300 ][INFO    ][6920] The service netfilter-persistent is already running
2020-02-26 17:06:06,915 [salt.state       :1951][INFO    ][6920] Completed state [netfilter-persistent] at time 17:06:06.915445 duration_in_ms=77.033
2020-02-26 17:06:06,917 [salt.state       :1780][INFO    ][6920] Running state [iptables_extra.remove_stale_tables] at time 17:06:06.917298
2020-02-26 17:06:06,917 [salt.state       :1813][INFO    ][6920] Executing state module.wait for [iptables_extra.remove_stale_tables]
2020-02-26 17:06:06,918 [salt.state       :300 ][INFO    ][6920] No changes made for iptables_extra.remove_stale_tables
2020-02-26 17:06:06,919 [salt.state       :1951][INFO    ][6920] Completed state [iptables_extra.remove_stale_tables] at time 17:06:06.918990 duration_in_ms=1.692
2020-02-26 17:06:06,919 [salt.state       :1780][INFO    ][6920] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 17:06:06.919509
2020-02-26 17:06:06,920 [salt.state       :1813][INFO    ][6920] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2020-02-26 17:06:06,921 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6920] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2020-02-26 17:06:06,940 [salt.state       :300 ][INFO    ][6920] onlyif execution failed
2020-02-26 17:06:06,940 [salt.state       :1951][INFO    ][6920] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 17:06:06.940662 duration_in_ms=21.153
2020-02-26 17:06:06,942 [salt.state       :1780][INFO    ][6920] Running state [/etc/iptables/rules.v6] at time 17:06:06.941945
2020-02-26 17:06:06,942 [salt.state       :1813][INFO    ][6920] Executing state file.absent for [/etc/iptables/rules.v6]
2020-02-26 17:06:06,943 [salt.state       :300 ][INFO    ][6920] File /etc/iptables/rules.v6 is not present
2020-02-26 17:06:06,943 [salt.state       :1951][INFO    ][6920] Completed state [/etc/iptables/rules.v6] at time 17:06:06.943798 duration_in_ms=1.852
2020-02-26 17:06:06,945 [salt.state       :1780][INFO    ][6920] Running state [iptables_extra.flush_all] at time 17:06:06.945623
2020-02-26 17:06:06,946 [salt.state       :1813][INFO    ][6920] Executing state module.wait for [iptables_extra.flush_all]
2020-02-26 17:06:06,946 [salt.state       :300 ][INFO    ][6920] No changes made for iptables_extra.flush_all
2020-02-26 17:06:06,947 [salt.state       :1951][INFO    ][6920] Completed state [iptables_extra.flush_all] at time 17:06:06.947299 duration_in_ms=1.678
2020-02-26 17:06:06,954 [salt.minion      :1711][INFO    ][6920] Returning information for job: 20200226170555069365
2020-02-26 17:06:07,877 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command state.apply with jid 20200226170607866311
2020-02-26 17:06:07,903 [salt.minion      :1432][INFO    ][7016] Starting a new job with PID 7016
2020-02-26 17:06:08,762 [salt.state       :915 ][INFO    ][7016] Loading fresh modules for state activity
2020-02-26 17:06:10,087 [salt.state       :1780][INFO    ][7016] Running state [maas-rack-controller] at time 17:06:10.087439
2020-02-26 17:06:10,087 [salt.state       :1813][INFO    ][7016] Executing state pkg.installed for [maas-rack-controller]
2020-02-26 17:06:10,088 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7016] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-26 17:06:10,208 [salt.state       :300 ][INFO    ][7016] All specified packages are already installed
2020-02-26 17:06:10,208 [salt.state       :1951][INFO    ][7016] Completed state [maas-rack-controller] at time 17:06:10.208606 duration_in_ms=121.168
2020-02-26 17:06:10,209 [salt.state       :1780][INFO    ][7016] Running state [ipmitool] at time 17:06:10.208985
2020-02-26 17:06:10,209 [salt.state       :1813][INFO    ][7016] Executing state pkg.installed for [ipmitool]
2020-02-26 17:06:10,220 [salt.state       :300 ][INFO    ][7016] All specified packages are already installed
2020-02-26 17:06:10,221 [salt.state       :1951][INFO    ][7016] Completed state [ipmitool] at time 17:06:10.220950 duration_in_ms=11.964
2020-02-26 17:06:10,226 [salt.state       :1780][INFO    ][7016] Running state [/etc/maas/rackd.conf] at time 17:06:10.225981
2020-02-26 17:06:10,226 [salt.state       :1813][INFO    ][7016] Executing state file.line for [/etc/maas/rackd.conf]
2020-02-26 17:06:10,227 [salt.state       :300 ][INFO    ][7016] No changes needed to be made
2020-02-26 17:06:10,227 [salt.state       :1951][INFO    ][7016] Completed state [/etc/maas/rackd.conf] at time 17:06:10.227560 duration_in_ms=1.579
2020-02-26 17:06:10,227 [salt.state       :1780][INFO    ][7016] Running state [/etc/maas/rackd.conf] at time 17:06:10.227839
2020-02-26 17:06:10,228 [salt.state       :1813][INFO    ][7016] Executing state file.managed for [/etc/maas/rackd.conf]
2020-02-26 17:06:10,229 [salt.loaded.int.states.file:2298][WARNING ][7016] 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.
2020-02-26 17:06:10,230 [salt.state       :300 ][INFO    ][7016] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2020-02-26 17:06:10,230 [salt.state       :1951][INFO    ][7016] Completed state [/etc/maas/rackd.conf] at time 17:06:10.230248 duration_in_ms=2.409
2020-02-26 17:06:10,231 [salt.state       :1780][INFO    ][7016] Running state [maas-rackd] at time 17:06:10.231463
2020-02-26 17:06:10,231 [salt.state       :1813][INFO    ][7016] Executing state service.running for [maas-rackd]
2020-02-26 17:06:10,232 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7016] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2020-02-26 17:06:10,284 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7016] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2020-02-26 17:06:10,310 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7016] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2020-02-26 17:06:10,338 [salt.state       :300 ][INFO    ][7016] The service maas-rackd is already running
2020-02-26 17:06:10,338 [salt.state       :1951][INFO    ][7016] Completed state [maas-rackd] at time 17:06:10.338535 duration_in_ms=107.071
2020-02-26 17:06:10,341 [salt.minion      :1711][INFO    ][7016] Returning information for job: 20200226170607866311
2020-02-26 17:06:11,271 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command state.apply with jid 20200226170611259463
2020-02-26 17:06:11,301 [salt.minion      :1432][INFO    ][7039] Starting a new job with PID 7039
2020-02-26 17:06:12,256 [salt.state       :915 ][INFO    ][7039] Loading fresh modules for state activity
2020-02-26 17:06:13,705 [salt.state       :1780][INFO    ][7039] Running state [maas-region-controller] at time 17:06:13.704508
2020-02-26 17:06:13,705 [salt.state       :1813][INFO    ][7039] Executing state pkg.installed for [maas-region-controller]
2020-02-26 17:06:13,706 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7039] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-26 17:06:13,815 [salt.state       :300 ][INFO    ][7039] All specified packages are already installed
2020-02-26 17:06:13,816 [salt.state       :1951][INFO    ][7039] Completed state [maas-region-controller] at time 17:06:13.815955 duration_in_ms=111.714
2020-02-26 17:06:13,816 [salt.state       :1780][INFO    ][7039] Running state [python-oauth] at time 17:06:13.816640
2020-02-26 17:06:13,817 [salt.state       :1813][INFO    ][7039] Executing state pkg.installed for [python-oauth]
2020-02-26 17:06:13,828 [salt.state       :300 ][INFO    ][7039] All specified packages are already installed
2020-02-26 17:06:13,829 [salt.state       :1951][INFO    ][7039] Completed state [python-oauth] at time 17:06:13.829016 duration_in_ms=12.376
2020-02-26 17:06:13,832 [salt.state       :1780][INFO    ][7039] Running state [/etc/maas/regiond.conf] at time 17:06:13.832773
2020-02-26 17:06:13,833 [salt.state       :1813][INFO    ][7039] Executing state file.replace for [/etc/maas/regiond.conf]
2020-02-26 17:06:13,839 [salt.state       :300 ][INFO    ][7039] No changes needed to be made
2020-02-26 17:06:13,839 [salt.state       :1951][INFO    ][7039] Completed state [/etc/maas/regiond.conf] at time 17:06:13.839267 duration_in_ms=6.494
2020-02-26 17:06:13,839 [salt.state       :1780][INFO    ][7039] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 17:06:13.839807
2020-02-26 17:06:13,840 [salt.state       :1813][INFO    ][7039] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2020-02-26 17:06:13,903 [salt.state       :300 ][INFO    ][7039] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2020-02-26 17:06:13,903 [salt.state       :1951][INFO    ][7039] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 17:06:13.903489 duration_in_ms=63.682
2020-02-26 17:06:13,904 [salt.state       :1780][INFO    ][7039] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 17:06:13.904013
2020-02-26 17:06:13,904 [salt.state       :1813][INFO    ][7039] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-02-26 17:06:13,910 [salt.state       :300 ][INFO    ][7039] No changes needed to be made
2020-02-26 17:06:13,910 [salt.state       :1951][INFO    ][7039] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 17:06:13.910806 duration_in_ms=6.793
2020-02-26 17:06:13,911 [salt.state       :1780][INFO    ][7039] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 17:06:13.911318
2020-02-26 17:06:13,911 [salt.state       :1813][INFO    ][7039] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-02-26 17:06:13,915 [salt.state       :300 ][INFO    ][7039] No changes needed to be made
2020-02-26 17:06:13,916 [salt.state       :1951][INFO    ][7039] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 17:06:13.915967 duration_in_ms=4.649
2020-02-26 17:06:13,916 [salt.state       :1780][INFO    ][7039] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 17:06:13.916492
2020-02-26 17:06:13,916 [salt.state       :1813][INFO    ][7039] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2020-02-26 17:06:13,948 [salt.state       :300 ][INFO    ][7039] No changes needed to be made
2020-02-26 17:06:13,948 [salt.state       :1951][INFO    ][7039] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 17:06:13.948825 duration_in_ms=32.332
2020-02-26 17:06:13,949 [salt.state       :1780][INFO    ][7039] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 17:06:13.949155
2020-02-26 17:06:13,949 [salt.state       :1813][INFO    ][7039] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2020-02-26 17:06:13,971 [salt.state       :300 ][INFO    ][7039] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2020-02-26 17:06:13,971 [salt.state       :1951][INFO    ][7039] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 17:06:13.971810 duration_in_ms=22.654
2020-02-26 17:06:13,972 [salt.state       :1780][INFO    ][7039] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 17:06:13.972480
2020-02-26 17:06:13,972 [salt.state       :1813][INFO    ][7039] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2020-02-26 17:06:14,027 [salt.state       :300 ][INFO    ][7039] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2020-02-26 17:06:14,027 [salt.state       :1951][INFO    ][7039] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 17:06:14.027415 duration_in_ms=54.935
2020-02-26 17:06:14,028 [salt.state       :1780][INFO    ][7039] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 17:06:14.027955
2020-02-26 17:06:14,029 [salt.state       :1813][INFO    ][7039] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2020-02-26 17:06:14,094 [salt.state       :300 ][INFO    ][7039] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2020-02-26 17:06:14,094 [salt.state       :1951][INFO    ][7039] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 17:06:14.094822 duration_in_ms=66.866
2020-02-26 17:06:14,095 [salt.state       :1780][INFO    ][7039] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 17:06:14.095395
2020-02-26 17:06:14,095 [salt.state       :1813][INFO    ][7039] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2020-02-26 17:06:14,172 [salt.state       :300 ][INFO    ][7039] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2020-02-26 17:06:14,173 [salt.state       :1951][INFO    ][7039] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 17:06:14.173003 duration_in_ms=77.607
2020-02-26 17:06:14,173 [salt.state       :1780][INFO    ][7039] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 17:06:14.173571
2020-02-26 17:06:14,173 [salt.state       :1813][INFO    ][7039] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic]
2020-02-26 17:06:14,249 [salt.state       :300 ][INFO    ][7039] File /etc/maas/preseeds/curtin_userdata_amd64_generic_bionic is in the correct state
2020-02-26 17:06:14,250 [salt.state       :1951][INFO    ][7039] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 17:06:14.250040 duration_in_ms=76.469
2020-02-26 17:06:14,250 [salt.state       :1780][INFO    ][7039] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 17:06:14.250573
2020-02-26 17:06:14,250 [salt.state       :1813][INFO    ][7039] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic]
2020-02-26 17:06:14,321 [salt.state       :300 ][INFO    ][7039] File /etc/maas/preseeds/curtin_userdata_arm64_generic_bionic is in the correct state
2020-02-26 17:06:14,321 [salt.state       :1951][INFO    ][7039] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 17:06:14.321913 duration_in_ms=71.34
2020-02-26 17:06:14,322 [salt.state       :1780][INFO    ][7039] Running state [/root/.pgpass] at time 17:06:14.322237
2020-02-26 17:06:14,322 [salt.state       :1813][INFO    ][7039] Executing state file.managed for [/root/.pgpass]
2020-02-26 17:06:14,377 [salt.state       :300 ][INFO    ][7039] File /root/.pgpass is in the correct state
2020-02-26 17:06:14,378 [salt.state       :1951][INFO    ][7039] Completed state [/root/.pgpass] at time 17:06:14.378220 duration_in_ms=55.982
2020-02-26 17:06:14,385 [salt.state       :1780][INFO    ][7039] Running state [maas-region migrate --noinput] at time 17:06:14.385572
2020-02-26 17:06:14,385 [salt.state       :1813][INFO    ][7039] Executing state cmd.run for [maas-region migrate --noinput]
2020-02-26 17:06:14,386 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7039] Executing command 'maas-region migrate --noinput' in directory '/root'
2020-02-26 17:06:17,563 [salt.state       :300 ][INFO    ][7039] {'pid': 7070, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Apply all migrations: auth, contenttypes, maasserver, metadataserver, piston3, sessions, sites\nRunning migrations:\n  No migrations to apply.'}
2020-02-26 17:06:17,564 [salt.state       :1951][INFO    ][7039] Completed state [maas-region migrate --noinput] at time 17:06:17.563900 duration_in_ms=3178.326
2020-02-26 17:06:17,564 [salt.state       :2022][WARNING ][7039] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-02-26 17:06:17,567 [salt.state       :1780][INFO    ][7039] Running state [maas-regiond] at time 17:06:17.567549
2020-02-26 17:06:17,570 [salt.state       :1813][INFO    ][7039] Executing state service.running for [maas-regiond]
2020-02-26 17:06:17,570 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7039] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2020-02-26 17:06:17,621 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7039] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2020-02-26 17:06:17,647 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7039] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2020-02-26 17:06:17,673 [salt.state       :300 ][INFO    ][7039] The service maas-regiond is already running
2020-02-26 17:06:17,673 [salt.state       :1951][INFO    ][7039] Completed state [maas-regiond] at time 17:06:17.673546 duration_in_ms=105.998
2020-02-26 17:06:17,675 [salt.state       :1780][INFO    ][7039] Running state [bind9] at time 17:06:17.675328
2020-02-26 17:06:17,675 [salt.state       :1813][INFO    ][7039] Executing state service.running for [bind9]
2020-02-26 17:06:17,679 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7039] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2020-02-26 17:06:17,707 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7039] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2020-02-26 17:06:17,732 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7039] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2020-02-26 17:06:17,757 [salt.state       :300 ][INFO    ][7039] The service bind9 is already running
2020-02-26 17:06:17,758 [salt.state       :1951][INFO    ][7039] Completed state [bind9] at time 17:06:17.758358 duration_in_ms=83.029
2020-02-26 17:06:17,762 [salt.state       :1780][INFO    ][7039] Running state [maasng.wait_for_http_code] at time 17:06:17.762211
2020-02-26 17:06:17,762 [salt.state       :1813][INFO    ][7039] Executing state module.run for [maasng.wait_for_http_code]
2020-02-26 17:06:17,763 [salt.utils.decorators:613 ][WARNING ][7039] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-26 17:06:17,827 [salt.state       :300 ][INFO    ][7039] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2020-02-26 17:06:17,828 [salt.state       :1951][INFO    ][7039] Completed state [maasng.wait_for_http_code] at time 17:06:17.827980 duration_in_ms=65.769
2020-02-26 17:06:17,829 [salt.state       :1780][INFO    ][7039] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 17:06:17.829011
2020-02-26 17:06:17,829 [salt.state       :1813][INFO    ][7039] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2020-02-26 17:06:17,829 [salt.state       :300 ][INFO    ][7039] /var/lib/maas/.setup_admin exists
2020-02-26 17:06:17,830 [salt.state       :1951][INFO    ][7039] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 17:06:17.830186 duration_in_ms=1.175
2020-02-26 17:06:17,831 [salt.state       :1780][INFO    ][7039] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:06:17.831032
2020-02-26 17:06:17,831 [salt.state       :1813][INFO    ][7039] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-26 17:06:17,832 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7039] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-26 17:06:20,102 [salt.state       :300 ][INFO    ][7039] {'pid': 7084, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-26 17:06:20,103 [salt.state       :1951][INFO    ][7039] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:06:20.102975 duration_in_ms=2271.942
2020-02-26 17:06:20,110 [salt.state       :1780][INFO    ][7039] Running state [maas_region_boot_source_resources_mirror] at time 17:06:20.110702
2020-02-26 17:06:20,111 [salt.state       :1813][INFO    ][7039] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2020-02-26 17:06:20,174 [salt.state       :300 ][INFO    ][7039] {'changes': {}}
2020-02-26 17:06:20,175 [salt.state       :1951][INFO    ][7039] Completed state [maas_region_boot_source_resources_mirror] at time 17:06:20.175176 duration_in_ms=64.474
2020-02-26 17:06:20,176 [salt.state       :1780][INFO    ][7039] Running state [maasng.boot_resources_import] at time 17:06:20.175998
2020-02-26 17:06:20,177 [salt.state       :1813][INFO    ][7039] Executing state module.run for [maasng.boot_resources_import]
2020-02-26 17:06:20,177 [salt.utils.decorators:613 ][WARNING ][7039] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-26 17:06:20,234 [salt.loaded.ext.module.maasng:1600][INFO    ][7039] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2020-02-26 17:06:25,311 [salt.state       :300 ][INFO    ][7039] {'ret': True}
2020-02-26 17:06:25,312 [salt.state       :1951][INFO    ][7039] Completed state [maasng.boot_resources_import] at time 17:06:25.311905 duration_in_ms=5135.906
2020-02-26 17:06:25,313 [salt.state       :1780][INFO    ][7039] Running state [maas_region_boot_sources_selection_bionic] at time 17:06:25.313144
2020-02-26 17:06:25,313 [salt.state       :1813][INFO    ][7039] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_bionic]
2020-02-26 17:06:25,439 [salt.state       :300 ][INFO    ][7039] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2020-02-26 17:06:25,439 [salt.state       :1951][INFO    ][7039] Completed state [maas_region_boot_sources_selection_bionic] at time 17:06:25.439409 duration_in_ms=126.265
2020-02-26 17:06:25,441 [salt.state       :1780][INFO    ][7039] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 17:06:25.441646
2020-02-26 17:06:25,442 [salt.state       :1813][INFO    ][7039] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2020-02-26 17:06:25,442 [salt.utils.decorators:613 ][WARNING ][7039] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-26 17:06:25,443 [salt.loaded.ext.module.maasng:1771][INFO    ][7039] boot-sources sync initiated for ALL Rack's
2020-02-26 17:06:26,195 [salt.state       :300 ][INFO    ][7039] {'ret': True}
2020-02-26 17:06:26,196 [salt.state       :1951][INFO    ][7039] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 17:06:26.195891 duration_in_ms=754.243
2020-02-26 17:06:26,198 [salt.state       :1780][INFO    ][7039] Running state [maas.process_maas_config] at time 17:06:26.198803
2020-02-26 17:06:26,199 [salt.state       :1813][INFO    ][7039] Executing state module.run for [maas.process_maas_config]
2020-02-26 17:06:26,200 [salt.utils.decorators:613 ][WARNING ][7039] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-26 17:06:26,201 [salt.loaded.ext.module.maas:92  ][INFO    ][7039] maasconfig name=enable_http_proxy value=True
2020-02-26 17:06:26,246 [salt.loaded.ext.module.maas:92  ][INFO    ][7039] maasconfig name=upstream_dns value=8.8.8.8
2020-02-26 17:06:26,327 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226170626312273
2020-02-26 17:06:26,359 [salt.minion      :1432][INFO    ][7123] Starting a new job with PID 7123
2020-02-26 17:06:26,392 [salt.minion      :1711][INFO    ][7123] Returning information for job: 20200226170626312273
2020-02-26 17:06:27,594 [salt.loaded.ext.module.maas:92  ][INFO    ][7039] maasconfig name=commissioning_distro_series value=bionic
2020-02-26 17:06:27,839 [salt.loaded.ext.module.maas:92  ][INFO    ][7039] maasconfig name=default_osystem value=ubuntu
2020-02-26 17:06:27,883 [salt.loaded.ext.module.maas:92  ][INFO    ][7039] maasconfig name=active_discovery_interval value=600
2020-02-26 17:06:28,111 [salt.loaded.ext.module.maas:92  ][INFO    ][7039] maasconfig name=dnssec_validation value=no
2020-02-26 17:06:28,369 [salt.loaded.ext.module.maas:92  ][INFO    ][7039] maasconfig name=kernel_opts value=spectre_v2=off nopti kpti=off nospec_store_bypass_disable noibrs noibpb
2020-02-26 17:06:28,401 [salt.loaded.ext.module.maas:92  ][INFO    ][7039] maasconfig name=network_discovery value=enabled
2020-02-26 17:06:28,470 [salt.loaded.ext.module.maas:92  ][INFO    ][7039] maasconfig name=enable_third_party_drivers value=True
2020-02-26 17:06:28,643 [salt.loaded.ext.module.maas:92  ][INFO    ][7039] maasconfig name=default_storage_layout value=lvm
2020-02-26 17:06:28,687 [salt.loaded.ext.module.maas:92  ][INFO    ][7039] maasconfig name=maas_name value=mas01
2020-02-26 17:06:28,721 [salt.loaded.ext.module.maas:92  ][INFO    ][7039] maasconfig name=disk_erase_with_secure_erase value=False
2020-02-26 17:06:28,756 [salt.loaded.ext.module.maas:92  ][INFO    ][7039] maasconfig name=default_distro_series value=bionic
2020-02-26 17:06:28,817 [salt.loaded.ext.module.maas:92  ][INFO    ][7039] maasconfig name=ntp_external_only value=True
2020-02-26 17:06:28,859 [salt.loaded.ext.module.maas:92  ][INFO    ][7039] maasconfig name=default_min_hwe_kernel value=ga-18.04
2020-02-26 17:06:28,943 [salt.state       :300 ][INFO    ][7039] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'kernel_opts', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'maas_name', 'disk_erase_with_secure_erase', 'default_distro_series', 'ntp_external_only', 'default_min_hwe_kernel']}}
2020-02-26 17:06:28,944 [salt.state       :1951][INFO    ][7039] Completed state [maas.process_maas_config] at time 17:06:28.944006 duration_in_ms=2745.203
2020-02-26 17:06:28,945 [salt.state       :1780][INFO    ][7039] Running state [pxe_admin] at time 17:06:28.945753
2020-02-26 17:06:28,946 [salt.state       :1813][INFO    ][7039] Executing state maasng.fabric_present for [pxe_admin]
2020-02-26 17:06:28,994 [salt.loaded.ext.module.maasng:945 ][INFO    ][7039] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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': None, u'name': u'pxe_admin', u'id': 1}]
2020-02-26 17:06:29,074 [salt.loaded.ext.module.maasng:1008][WARNING ][7039] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2020-02-26 17:06:29,074 [salt.loaded.ext.module.maasng:1011][WARNING ][7039] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2020-02-26 17:06:29,123 [salt.state       :300 ][INFO    ][7039] {'new': 'Fabric  pxe_admin created', 'result': True}
2020-02-26 17:06:29,123 [salt.state       :1951][INFO    ][7039] Completed state [pxe_admin] at time 17:06:29.123575 duration_in_ms=177.822
2020-02-26 17:06:29,123 [salt.state       :1780][INFO    ][7039] Running state [vlan 0] at time 17:06:29.123904
2020-02-26 17:06:29,124 [salt.state       :1813][INFO    ][7039] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-02-26 17:06:29,181 [salt.loaded.ext.module.maasng:945 ][INFO    ][7039] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-02-26 17:06:29,269 [salt.loaded.ext.module.maasng:945 ][INFO    ][7039] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'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'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'fabric': u'fabric-2'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'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'hm7gpr', 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'fabric': u'pxe_admin'}], u'class_type': None, u'name': u'pxe_admin', u'id': 1}]
2020-02-26 17:06:29,462 [salt.loaded.ext.module.maasng:945 ][INFO    ][7039] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'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'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'fabric': u'fabric-2'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'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'hm7gpr', 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'fabric': u'pxe_admin'}], u'class_type': None, u'name': u'pxe_admin', u'id': 1}]
2020-02-26 17:06:29,519 [salt.state       :300 ][INFO    ][7039] {'new': 'Vlan untagged was updated'}
2020-02-26 17:06:29,519 [salt.state       :1951][INFO    ][7039] Completed state [vlan 0] at time 17:06:29.519480 duration_in_ms=395.575
2020-02-26 17:06:29,521 [salt.state       :1780][INFO    ][7039] Running state [192.168.11.0/24] at time 17:06:29.521882
2020-02-26 17:06:29,522 [salt.state       :1813][INFO    ][7039] Executing state maasng.subnet_present for [192.168.11.0/24]
2020-02-26 17:06:29,663 [salt.loaded.ext.module.maasng:945 ][INFO    ][7039] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'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'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'fabric': u'fabric-2'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'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'hm7gpr', 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'fabric': u'pxe_admin'}], u'class_type': None, u'name': u'pxe_admin', u'id': 1}]
2020-02-26 17:06:29,665 [salt.loaded.ext.module.maasng:1235][WARNING ][7039] Ignoring parameter vlan:0
2020-02-26 17:06:29,719 [salt.state       :300 ][INFO    ][7039] Subnet 192.168.11.0/24 has been updated for pxe_admin
2020-02-26 17:06:29,719 [salt.state       :1951][INFO    ][7039] Completed state [192.168.11.0/24] at time 17:06:29.719473 duration_in_ms=197.59
2020-02-26 17:06:29,721 [salt.state       :1780][INFO    ][7039] Running state [maas_create_iprange_1] at time 17:06:29.721228
2020-02-26 17:06:29,721 [salt.state       :1813][INFO    ][7039] Executing state maasng.iprange_present for [maas_create_iprange_1]
2020-02-26 17:06:29,764 [salt.state       :300 ][INFO    ][7039] Iprange maas_create_iprange_1 already exist.
2020-02-26 17:06:29,765 [salt.state       :1951][INFO    ][7039] Completed state [maas_create_iprange_1] at time 17:06:29.765193 duration_in_ms=43.964
2020-02-26 17:06:29,765 [salt.state       :1780][INFO    ][7039] Running state [vlan 0] at time 17:06:29.765714
2020-02-26 17:06:29,766 [salt.state       :1813][INFO    ][7039] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-02-26 17:06:29,810 [salt.loaded.ext.module.maasng:945 ][INFO    ][7039] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'fabric': u'fabric-0'}], 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'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'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'fabric': u'fabric-2'}], 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'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'hm7gpr', 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'fabric': u'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-02-26 17:06:29,907 [salt.loaded.ext.module.maasng:945 ][INFO    ][7039] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-02-26 17:06:30,142 [salt.loaded.ext.module.maasng:945 ][INFO    ][7039] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'fabric': u'fabric-0'}], 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'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'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'fabric': u'fabric-2'}], 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'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'hm7gpr', 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'fabric': u'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-02-26 17:06:30,231 [salt.state       :300 ][INFO    ][7039] {'new': 'Vlan untagged was updated'}
2020-02-26 17:06:30,231 [salt.state       :1951][INFO    ][7039] Completed state [vlan 0] at time 17:06:30.231754 duration_in_ms=466.04
2020-02-26 17:06:30,233 [salt.state       :1780][INFO    ][7039] Running state [opnfv] at time 17:06:30.233121
2020-02-26 17:06:30,233 [salt.state       :1813][INFO    ][7039] Executing state maasng.sshkey_present for [opnfv]
2020-02-26 17:06:30,261 [salt.loaded.ext.module.maasng:1903][INFO    ][7039] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2020-02-26 17:06:30,261 [salt.state       :300 ][INFO    ][7039] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2020-02-26 17:06:30,262 [salt.state       :1951][INFO    ][7039] Completed state [opnfv] at time 17:06:30.262171 duration_in_ms=29.049
2020-02-26 17:06:30,263 [salt.state       :1780][INFO    ][7039] Running state [maas.process_tags] at time 17:06:30.263087
2020-02-26 17:06:30,263 [salt.state       :1813][INFO    ][7039] Executing state module.run for [maas.process_tags]
2020-02-26 17:06:30,264 [salt.utils.decorators:613 ][WARNING ][7039] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-26 17:06:30,296 [salt.loaded.ext.module.maas:92  ][INFO    ][7039] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"]|//capability[@id="cp15_barrier"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G kpti=off
2020-02-26 17:06:30,330 [salt.state       :300 ][INFO    ][7039] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2020-02-26 17:06:30,330 [salt.state       :1951][INFO    ][7039] Completed state [maas.process_tags] at time 17:06:30.330560 duration_in_ms=67.474
2020-02-26 17:06:30,334 [salt.minion      :1711][INFO    ][7039] Returning information for job: 20200226170611259463
2020-02-26 17:06:31,378 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command state.apply with jid 20200226170631366057
2020-02-26 17:06:31,407 [salt.minion      :1432][INFO    ][7174] Starting a new job with PID 7174
2020-02-26 17:06:37,658 [salt.state       :915 ][INFO    ][7174] Loading fresh modules for state activity
2020-02-26 17:06:37,769 [salt.state       :1780][INFO    ][7174] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:06:37.769377
2020-02-26 17:06:37,769 [salt.state       :1813][INFO    ][7174] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-26 17:06:37,771 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7174] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-26 17:06:40,026 [salt.state       :300 ][INFO    ][7174] {'pid': 7181, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-26 17:06:40,028 [salt.state       :1951][INFO    ][7174] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:06:40.027900 duration_in_ms=2258.523
2020-02-26 17:06:40,031 [salt.state       :1780][INFO    ][7174] Running state [maas.process_machines] at time 17:06:40.031454
2020-02-26 17:06:40,032 [salt.state       :1813][INFO    ][7174] Executing state module.run for [maas.process_machines]
2020-02-26 17:06:40,034 [salt.utils.decorators:613 ][WARNING ][7174] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-26 17:06:40,683 [salt.loaded.ext.module.maas:412 ][WARNING ][7174] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-26 17:06:40,684 [salt.loaded.ext.module.maas:92  ][INFO    ][7174] machine hostname=gtw01 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=nkp4yk architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-26 17:06:41,503 [salt.loaded.ext.module.maas:412 ][WARNING ][7174] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-26 17:06:41,503 [salt.loaded.ext.module.maas:92  ][INFO    ][7174] 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=hc4tnx architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-26 17:06:42,208 [salt.loaded.ext.module.maas:412 ][WARNING ][7174] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-26 17:06:42,210 [salt.loaded.ext.module.maas:92  ][INFO    ][7174] machine hostname=odl01 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=636f3n architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-26 17:06:42,916 [salt.loaded.ext.module.maas:412 ][WARNING ][7174] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-26 17:06:42,917 [salt.loaded.ext.module.maas:92  ][INFO    ][7174] 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=qp8qxx architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-26 17:06:43,643 [salt.loaded.ext.module.maas:412 ][WARNING ][7174] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-26 17:06:43,644 [salt.loaded.ext.module.maas:92  ][INFO    ][7174] machine hostname=ctl01 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=bdm6g3 architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-26 17:06:44,348 [salt.state       :300 ][INFO    ][7174] {'ret': {'updated': ['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2020-02-26 17:06:44,349 [salt.state       :1951][INFO    ][7174] Completed state [maas.process_machines] at time 17:06:44.348976 duration_in_ms=4317.521
2020-02-26 17:06:44,355 [salt.minion      :1711][INFO    ][7174] Returning information for job: 20200226170631366057
2020-02-26 17:07:18,874 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command state.apply with jid 20200226170718861415
2020-02-26 17:07:18,898 [salt.minion      :1432][INFO    ][7220] Starting a new job with PID 7220
2020-02-26 17:07:25,092 [salt.state       :915 ][INFO    ][7220] Loading fresh modules for state activity
2020-02-26 17:07:25,203 [salt.state       :1780][INFO    ][7220] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:07:25.203624
2020-02-26 17:07:25,205 [salt.state       :1813][INFO    ][7220] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-26 17:07:25,209 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7220] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-26 17:07:27,469 [salt.state       :300 ][INFO    ][7220] {'pid': 7234, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-26 17:07:27,470 [salt.state       :1951][INFO    ][7220] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:07:27.470345 duration_in_ms=2266.723
2020-02-26 17:07:27,474 [salt.state       :1780][INFO    ][7220] Running state [maas.wait_for_machine_status] at time 17:07:27.473878
2020-02-26 17:07:27,474 [salt.state       :1813][INFO    ][7220] Executing state module.run for [maas.wait_for_machine_status]
2020-02-26 17:07:27,475 [salt.utils.decorators:613 ][WARNING ][7220] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-26 17:07:30,678 [salt.state       :300 ][INFO    ][7220] {'ret': True}
2020-02-26 17:07:30,679 [salt.state       :1951][INFO    ][7220] Completed state [maas.wait_for_machine_status] at time 17:07:30.679278 duration_in_ms=3205.4
2020-02-26 17:07:30,683 [salt.minion      :1711][INFO    ][7220] Returning information for job: 20200226170718861415
2020-02-26 17:07:31,636 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command state.apply with jid 20200226170731622683
2020-02-26 17:07:31,663 [salt.minion      :1432][INFO    ][7253] Starting a new job with PID 7253
2020-02-26 17:07:32,569 [salt.state       :915 ][INFO    ][7253] Loading fresh modules for state activity
2020-02-26 17:07:32,716 [salt.state       :1780][INFO    ][7253] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:07:32.716138
2020-02-26 17:07:32,716 [salt.state       :1813][INFO    ][7253] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-26 17:07:32,718 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7253] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-26 17:07:35,001 [salt.state       :300 ][INFO    ][7253] {'pid': 7260, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-26 17:07:35,002 [salt.state       :1951][INFO    ][7253] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:07:35.002097 duration_in_ms=2285.958
2020-02-26 17:07:35,004 [salt.state       :1780][INFO    ][7253] Running state [maas_machines_storage_cmp002_lvm] at time 17:07:35.004191
2020-02-26 17:07:35,004 [salt.state       :1813][INFO    ][7253] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2020-02-26 17:07:36,234 [salt.loaded.ext.module.maasng:610 ][INFO    ][7253] hc4tnx
2020-02-26 17:07:36,234 [salt.loaded.ext.module.maasng:626 ][INFO    ][7253] sda
2020-02-26 17:07:36,830 [salt.loaded.ext.module.maasng:361 ][INFO    ][7253] hc4tnx
2020-02-26 17:07:36,918 [salt.loaded.ext.module.maasng:367 ][INFO    ][7253] [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'00db2cb0-c5de-4412-a6e4-e64c907376f1', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/1/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hc4tnx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2401b90a-45a5-4833-b018-9600e410176b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 8, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'hc4tnx', 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'id': 1, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/13/', u'uuid': u'b040a329-3281-4800-9f1f-dd2d4ba6a42b', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'hc4tnx', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'ceaeda25-8446-436f-a319-e0dec7a2f51e', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 13, u'size': 800097042432}]
2020-02-26 17:07:36,919 [salt.loaded.ext.module.maasng:632 ][INFO    ][7253] vgroot
2020-02-26 17:07:36,919 [salt.loaded.ext.module.maasng:635 ][INFO    ][7253] lvroot
2020-02-26 17:07:36,920 [salt.loaded.ext.module.maasng:639 ][INFO    ][7253] 107374182400
2020-02-26 17:07:37,586 [salt.loaded.ext.module.maasng:645 ][INFO    ][7253] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': None, u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'e75f3d36-0de7-4256-b15a-568a0b2133d1', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/1/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hc4tnx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0b55a6b3-6b14-4dbb-a019-c5d084273839', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 10, u'device_id': 1}], u'filesystem': None, u'uuid': None, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hc4tnx', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/15/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'd97a4ecb-a800-471f-aa22-4f5ffe34a644', u'fstype': u'ext4'}, u'uuid': u'7baef7fb-aad0-4a2f-8a2b-72684e330623', u'id': 15, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hc4tnx', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/hc4tnx/', u'hardware_info': {u'mainboard_firmware_version': u'I31', u'mainboard_product': u'Unknown', u'system_vendor': u'HP', u'cpu_model': u'Intel(R) Xeon(R) CPU E5-2680 v2', u'mainboard_firmware_date': u'12/20/2013', u'mainboard_vendor': u'Unknown', u'system_serial': u'USE410T2Y0', u'system_version': u'Unknown', u'system_product': u'ProLiant BL460c Gen8 (735151-B21)'}, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'hc4tnx', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 15, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'd97a4ecb-a800-471f-aa22-4f5ffe34a644', u'fstype': u'ext4'}, u'uuid': u'7baef7fb-aad0-4a2f-8a2b-72684e330623', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/15/', u'system_id': u'hc4tnx', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'serial': None, u'partitions': []}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 1, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/1/', u'system_id': u'hc4tnx', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'size': 800109715456, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'e75f3d36-0de7-4256-b15a-568a0b2133d1', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/1/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hc4tnx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0b55a6b3-6b14-4dbb-a019-c5d084273839', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 10, u'device_id': 1}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'hc4tnx', u'id': 10}], u'special_filesystems': [], u'current_commissioning_result_id': 19, u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 65, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'id': 5, u'discovered': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'hc4tnx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/interfaces/5/'}, u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 65, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'id': 5, u'discovered': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'hc4tnx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/interfaces/5/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'id': 11, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'hc4tnx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/interfaces/11/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 66, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'id': 12, u'discovered': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'hc4tnx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/interfaces/12/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'id': 13, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'hc4tnx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/interfaces/13/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 67, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'fabric': u'fabric-0'}, u'effective_mtu': 1500, u'id': 14, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'hc4tnx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/interfaces/14/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 68, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'fabric': u'fabric-0'}, u'effective_mtu': 1500, u'id': 15, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'hc4tnx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/interfaces/15/'}], u'current_testing_result_id': 20, u'cpu_test_status': -1, u'pool': {u'id': 0, u'resource_uri': u'/MAAS/api/2.0/resourcepool/0/', u'name': u'default', u'description': u'Default pool'}, u'bcaches': [], u'locked': False, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'commissioning_status_name': u'Passed', u'boot_disk': {u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 1, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/1/', u'system_id': u'hc4tnx', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'size': 800109715456, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'e75f3d36-0de7-4256-b15a-568a0b2133d1', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4tnx/blockdevices/1/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hc4tnx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0b55a6b3-6b14-4dbb-a019-c5d084273839', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 10, u'device_id': 1}]}, u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2020-02-26 17:07:37,589 [salt.state       :300 ][INFO    ][7253] {'new': {'storage_layout': 'lvm'}}
2020-02-26 17:07:37,589 [salt.state       :1951][INFO    ][7253] Completed state [maas_machines_storage_cmp002_lvm] at time 17:07:37.589793 duration_in_ms=2585.6
2020-02-26 17:07:37,590 [salt.state       :1780][INFO    ][7253] Running state [maas_machines_storage_cmp001_lvm] at time 17:07:37.590600
2020-02-26 17:07:37,591 [salt.state       :1813][INFO    ][7253] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2020-02-26 17:07:38,797 [salt.loaded.ext.module.maasng:610 ][INFO    ][7253] qp8qxx
2020-02-26 17:07:38,798 [salt.loaded.ext.module.maasng:626 ][INFO    ][7253] sda
2020-02-26 17:07:39,410 [salt.loaded.ext.module.maasng:361 ][INFO    ][7253] qp8qxx
2020-02-26 17:07:39,486 [salt.loaded.ext.module.maasng:367 ][INFO    ][7253] [{u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'd1d14b27-f3a6-46d9-8a2f-5db13b79290b', u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/2/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qp8qxx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'6b9b7480-f677-48d8-a8d0-76596f7fcf8d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 9, u'device_id': 2}], u'filesystem': None, u'uuid': None, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qp8qxx', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/14/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'dafba3f7-e141-4724-bb63-8a012657dcab', u'fstype': u'ext4'}, u'uuid': u'1e24af48-5d6d-4e73-a722-c5d23bb79870', u'id': 14, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qp8qxx', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'serial': None, u'size': 800097042432}]
2020-02-26 17:07:39,487 [salt.loaded.ext.module.maasng:632 ][INFO    ][7253] vgroot
2020-02-26 17:07:39,487 [salt.loaded.ext.module.maasng:635 ][INFO    ][7253] lvroot
2020-02-26 17:07:39,487 [salt.loaded.ext.module.maasng:639 ][INFO    ][7253] 107374182400
2020-02-26 17:07:40,207 [salt.loaded.ext.module.maasng:645 ][INFO    ][7253] {u'hwe_kernel': None, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 72, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'id': 7, u'discovered': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'qp8qxx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/interfaces/7/'}, u'min_hwe_kernel': u'ga-18.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'cpu_speed': 2800, u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/qp8qxx/', u'hardware_info': {u'mainboard_firmware_version': u'I31', u'mainboard_product': u'Unknown', u'system_vendor': u'HP', u'cpu_model': u'Intel(R) Xeon(R) CPU E5-2680 v2', u'mainboard_firmware_date': u'06/01/2015', u'mainboard_vendor': u'Unknown', u'system_serial': u'USE410T2XY', u'system_version': u'Unknown', u'system_product': u'ProLiant BL460c Gen8 (735151-B21)'}, u'hostname': u'cmp001', u'storage': 800109.715456, u'node_type': 0, u'owner_data': {}, u'system_id': u'qp8qxx', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'disable_ipv4': False, u'blockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/2/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'id': 2, u'name': u'sda', u'system_id': u'qp8qxx', 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'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'6303428f-9882-4a50-9896-684b2fdf9eca', u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/2/partition/11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qp8qxx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'fca4129d-295a-4ab7-bd93-0c9f859abf82', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 11, u'size': 800101236736}]}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/16/', u'available_size': 0, u'uuid': u'3348561f-4c75-4921-8de8-2063ad850141', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'id': 16, u'name': u'vgroot-lvroot', u'system_id': u'qp8qxx', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'21a14d3d-0bc2-46a6-b15c-7a4b7e1ed142', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'id': 2, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/2/', u'system_id': u'qp8qxx', 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'firmware_version': u'5.22', u'size': 800109715456, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'6303428f-9882-4a50-9896-684b2fdf9eca', u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/2/partition/11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qp8qxx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'fca4129d-295a-4ab7-bd93-0c9f859abf82', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 11, u'size': 800101236736}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'qp8qxx', u'id': 11}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 72, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'id': 7, u'discovered': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'qp8qxx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/interfaces/7/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'id': 16, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'qp8qxx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/interfaces/16/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 73, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'hm7gpr', 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'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'id': 17, u'discovered': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'qp8qxx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/interfaces/17/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'id': 18, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'qp8qxx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/interfaces/18/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 74, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'effective_mtu': 1500, u'id': 19, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'qp8qxx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/interfaces/19/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 75, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'effective_mtu': 1500, u'id': 20, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'firmware_version': u'bc 7.8.24', u'system_id': u'qp8qxx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/interfaces/20/'}], u'current_testing_result_id': 22, u'cpu_test_status': -1, u'pool': {u'id': 0, u'resource_uri': u'/MAAS/api/2.0/resourcepool/0/', u'name': u'default', u'description': u'Default pool'}, u'bcaches': [], u'locked': False, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': u'3348561f-4c75-4921-8de8-2063ad850141', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'id': 16, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/16/', u'system_id': u'qp8qxx', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'21a14d3d-0bc2-46a6-b15c-7a4b7e1ed142', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'size': 107374182400, u'type': u'virtual', u'model': None, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 21, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'id': 2, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/2/', u'system_id': u'qp8qxx', 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'firmware_version': u'5.22', u'size': 800109715456, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'6303428f-9882-4a50-9896-684b2fdf9eca', u'resource_uri': u'/MAAS/api/2.0/nodes/qp8qxx/blockdevices/2/partition/11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qp8qxx', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'fca4129d-295a-4ab7-bd93-0c9f859abf82', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 11, u'size': 800101236736}]}}
2020-02-26 17:07:40,209 [salt.state       :300 ][INFO    ][7253] {'new': {'storage_layout': 'lvm'}}
2020-02-26 17:07:40,209 [salt.state       :1951][INFO    ][7253] Completed state [maas_machines_storage_cmp001_lvm] at time 17:07:40.209711 duration_in_ms=2619.111
2020-02-26 17:07:40,214 [salt.minion      :1711][INFO    ][7253] Returning information for job: 20200226170731622683
2020-02-26 17:07:41,175 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command state.apply with jid 20200226170741164826
2020-02-26 17:07:41,192 [salt.minion      :1432][INFO    ][7273] Starting a new job with PID 7273
2020-02-26 17:07:47,378 [salt.state       :915 ][INFO    ][7273] Loading fresh modules for state activity
2020-02-26 17:07:47,500 [salt.state       :1780][INFO    ][7273] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:07:47.500649
2020-02-26 17:07:47,501 [salt.state       :1813][INFO    ][7273] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-26 17:07:47,502 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7273] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-26 17:07:49,736 [salt.state       :300 ][INFO    ][7273] {'pid': 7281, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-26 17:07:49,737 [salt.state       :1951][INFO    ][7273] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:07:49.737411 duration_in_ms=2236.764
2020-02-26 17:07:49,740 [salt.state       :1780][INFO    ][7273] Running state [maas.deploy_machines] at time 17:07:49.740124
2020-02-26 17:07:49,741 [salt.state       :1813][INFO    ][7273] Executing state module.run for [maas.deploy_machines]
2020-02-26 17:07:49,742 [salt.utils.decorators:613 ][WARNING ][7273] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-26 17:07:50,411 [salt.loaded.ext.module.maas:684 ][INFO    ][7273] deploymachines hwe_kernel=ga-18.04 system_id=hc4tnx distro_series=bionic
2020-02-26 17:07:52,382 [salt.loaded.ext.module.maas:684 ][INFO    ][7273] deploymachines hwe_kernel=ga-18.04 system_id=qp8qxx distro_series=bionic
2020-02-26 17:07:54,322 [salt.state       :300 ][INFO    ][7273] {'ret': {'updated': ['gtw01', 'odl01', 'ctl01'], 'errors': {}, 'success': ['cmp002', 'cmp001']}}
2020-02-26 17:07:54,323 [salt.state       :1951][INFO    ][7273] Completed state [maas.deploy_machines] at time 17:07:54.323009 duration_in_ms=4582.884
2020-02-26 17:07:54,330 [salt.minion      :1711][INFO    ][7273] Returning information for job: 20200226170741164826
2020-02-26 17:07:55,305 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command state.apply with jid 20200226170755294327
2020-02-26 17:07:55,331 [salt.minion      :1432][INFO    ][7306] Starting a new job with PID 7306
2020-02-26 17:07:56,261 [salt.state       :915 ][INFO    ][7306] Loading fresh modules for state activity
2020-02-26 17:07:56,369 [salt.state       :1780][INFO    ][7306] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:07:56.369048
2020-02-26 17:07:56,369 [salt.state       :1813][INFO    ][7306] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-26 17:07:56,371 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7306] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-26 17:07:58,658 [salt.state       :300 ][INFO    ][7306] {'pid': 7313, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-26 17:07:58,659 [salt.state       :1951][INFO    ][7306] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:07:58.659443 duration_in_ms=2290.396
2020-02-26 17:07:58,663 [salt.state       :1780][INFO    ][7306] Running state [maas.wait_for_machine_status] at time 17:07:58.663227
2020-02-26 17:07:58,663 [salt.state       :1813][INFO    ][7306] Executing state module.run for [maas.wait_for_machine_status]
2020-02-26 17:07:58,664 [salt.utils.decorators:613 ][WARNING ][7306] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-26 17:08:01,927 [salt.loaded.ext.module.maas:1024][INFO    ][7306] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2246.75181389s left)
2020-02-26 17:08:10,377 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226170810334117
2020-02-26 17:08:10,401 [salt.minion      :1432][INFO    ][7351] Starting a new job with PID 7351
2020-02-26 17:08:10,433 [salt.minion      :1711][INFO    ][7351] Returning information for job: 20200226170810334117
2020-02-26 17:08:35,047 [salt.loaded.ext.module.maas:1024][INFO    ][7306] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2213.63152695s left)
2020-02-26 17:08:40,458 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226170840447482
2020-02-26 17:08:40,478 [salt.minion      :1432][INFO    ][7369] Starting a new job with PID 7369
2020-02-26 17:08:40,505 [salt.minion      :1711][INFO    ][7369] Returning information for job: 20200226170840447482
2020-02-26 17:09:08,326 [salt.loaded.ext.module.maas:1024][INFO    ][7306] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2180.35291791s left)
2020-02-26 17:09:10,523 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226170910515947
2020-02-26 17:09:10,542 [salt.minion      :1432][INFO    ][7419] Starting a new job with PID 7419
2020-02-26 17:09:10,570 [salt.minion      :1711][INFO    ][7419] Returning information for job: 20200226170910515947
2020-02-26 17:09:40,583 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226170940575072
2020-02-26 17:09:40,604 [salt.minion      :1432][INFO    ][7441] Starting a new job with PID 7441
2020-02-26 17:09:40,647 [salt.minion      :1711][INFO    ][7441] Returning information for job: 20200226170940575072
2020-02-26 17:09:41,602 [salt.loaded.ext.module.maas:1024][INFO    ][7306] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2147.07693386s left)
2020-02-26 17:10:10,672 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226171010660081
2020-02-26 17:10:10,699 [salt.minion      :1432][INFO    ][7474] Starting a new job with PID 7474
2020-02-26 17:10:10,727 [salt.minion      :1711][INFO    ][7474] Returning information for job: 20200226171010660081
2020-02-26 17:10:14,710 [salt.loaded.ext.module.maas:1024][INFO    ][7306] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2113.96939588s left)
2020-02-26 17:10:40,756 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226171040746448
2020-02-26 17:10:40,782 [salt.minion      :1432][INFO    ][7505] Starting a new job with PID 7505
2020-02-26 17:10:40,807 [salt.minion      :1711][INFO    ][7505] Returning information for job: 20200226171040746448
2020-02-26 17:10:48,139 [salt.loaded.ext.module.maas:1024][INFO    ][7306] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2080.54038692s left)
2020-02-26 17:11:10,831 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226171110820028
2020-02-26 17:11:10,857 [salt.minion      :1432][INFO    ][7568] Starting a new job with PID 7568
2020-02-26 17:11:10,887 [salt.minion      :1711][INFO    ][7568] Returning information for job: 20200226171110820028
2020-02-26 17:11:21,628 [salt.loaded.ext.module.maas:1024][INFO    ][7306] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2047.050565s left)
2020-02-26 17:11:40,939 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226171140927543
2020-02-26 17:11:40,967 [salt.minion      :1432][INFO    ][7618] Starting a new job with PID 7618
2020-02-26 17:11:40,994 [salt.minion      :1711][INFO    ][7618] Returning information for job: 20200226171140927543
2020-02-26 17:11:55,003 [salt.loaded.ext.module.maas:1024][INFO    ][7306] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2013.67565799s left)
2020-02-26 17:12:11,035 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226171211024176
2020-02-26 17:12:11,063 [salt.minion      :1432][INFO    ][7652] Starting a new job with PID 7652
2020-02-26 17:12:11,086 [salt.minion      :1711][INFO    ][7652] Returning information for job: 20200226171211024176
2020-02-26 17:12:28,337 [salt.loaded.ext.module.maas:1024][INFO    ][7306] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1980.34160185s left)
2020-02-26 17:12:41,132 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226171241118759
2020-02-26 17:12:41,158 [salt.minion      :1432][INFO    ][7671] Starting a new job with PID 7671
2020-02-26 17:12:41,183 [salt.minion      :1711][INFO    ][7671] Returning information for job: 20200226171241118759
2020-02-26 17:13:01,958 [salt.loaded.ext.module.maas:1024][INFO    ][7306] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1946.7214098s left)
2020-02-26 17:13:11,239 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226171311229219
2020-02-26 17:13:11,263 [salt.minion      :1432][INFO    ][7709] Starting a new job with PID 7709
2020-02-26 17:13:11,289 [salt.minion      :1711][INFO    ][7709] Returning information for job: 20200226171311229219
2020-02-26 17:13:35,483 [salt.loaded.ext.module.maas:1024][INFO    ][7306] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1913.19572997s left)
2020-02-26 17:13:41,331 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226171341320201
2020-02-26 17:13:41,362 [salt.minion      :1432][INFO    ][7730] Starting a new job with PID 7730
2020-02-26 17:13:41,389 [salt.minion      :1711][INFO    ][7730] Returning information for job: 20200226171341320201
2020-02-26 17:14:08,886 [salt.loaded.ext.module.maas:1024][INFO    ][7306] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1879.79272485s left)
2020-02-26 17:14:11,448 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226171411434627
2020-02-26 17:14:11,481 [salt.minion      :1432][INFO    ][7765] Starting a new job with PID 7765
2020-02-26 17:14:11,506 [salt.minion      :1711][INFO    ][7765] Returning information for job: 20200226171411434627
2020-02-26 17:14:41,573 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226171441562704
2020-02-26 17:14:41,591 [salt.minion      :1432][INFO    ][7785] Starting a new job with PID 7785
2020-02-26 17:14:41,621 [salt.minion      :1711][INFO    ][7785] Returning information for job: 20200226171441562704
2020-02-26 17:14:42,550 [salt.loaded.ext.module.maas:1024][INFO    ][7306] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1846.12861586s left)
2020-02-26 17:15:11,674 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226171511663767
2020-02-26 17:15:11,697 [salt.minion      :1432][INFO    ][7818] Starting a new job with PID 7818
2020-02-26 17:15:11,722 [salt.minion      :1711][INFO    ][7818] Returning information for job: 20200226171511663767
2020-02-26 17:15:15,981 [salt.loaded.ext.module.maas:1024][INFO    ][7306] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1812.69809198s left)
2020-02-26 17:15:41,781 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226171541771021
2020-02-26 17:15:41,806 [salt.minion      :1432][INFO    ][7839] Starting a new job with PID 7839
2020-02-26 17:15:41,834 [salt.minion      :1711][INFO    ][7839] Returning information for job: 20200226171541771021
2020-02-26 17:15:49,270 [salt.loaded.ext.module.maas:1024][INFO    ][7306] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1779.40914702s left)
2020-02-26 17:16:11,906 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226171611895900
2020-02-26 17:16:11,928 [salt.minion      :1432][INFO    ][7873] Starting a new job with PID 7873
2020-02-26 17:16:11,954 [salt.minion      :1711][INFO    ][7873] Returning information for job: 20200226171611895900
2020-02-26 17:16:22,799 [salt.loaded.ext.module.maas:1024][INFO    ][7306] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1745.8797009s left)
2020-02-26 17:16:42,036 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226171642023214
2020-02-26 17:16:42,058 [salt.minion      :1432][INFO    ][7895] Starting a new job with PID 7895
2020-02-26 17:16:42,083 [salt.minion      :1711][INFO    ][7895] Returning information for job: 20200226171642023214
2020-02-26 17:16:56,429 [salt.loaded.ext.module.maas:1024][INFO    ][7306] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1712.24952602s left)
2020-02-26 17:17:12,177 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226171712163756
2020-02-26 17:17:12,207 [salt.minion      :1432][INFO    ][7936] Starting a new job with PID 7936
2020-02-26 17:17:12,233 [salt.minion      :1711][INFO    ][7936] Returning information for job: 20200226171712163756
2020-02-26 17:17:29,966 [salt.loaded.ext.module.maas:1024][INFO    ][7306] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1678.71295691s left)
2020-02-26 17:17:42,323 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226171742312614
2020-02-26 17:17:42,352 [salt.minion      :1432][INFO    ][7967] Starting a new job with PID 7967
2020-02-26 17:17:42,377 [salt.minion      :1711][INFO    ][7967] Returning information for job: 20200226171742312614
2020-02-26 17:18:03,557 [salt.loaded.ext.module.maas:1024][INFO    ][7306] Waiting status:Deployed for machines:['cmp001']
sleep for:30s Timeout:2250s (1645.12214589s left)
2020-02-26 17:18:12,475 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226171812465638
2020-02-26 17:18:12,498 [salt.minion      :1432][INFO    ][8006] Starting a new job with PID 8006
2020-02-26 17:18:12,522 [salt.minion      :1711][INFO    ][8006] Returning information for job: 20200226171812465638
2020-02-26 17:18:36,974 [salt.loaded.ext.module.maas:1024][INFO    ][7306] Waiting status:Deployed for machines:['cmp001']
sleep for:30s Timeout:2250s (1611.70501494s left)
2020-02-26 17:18:42,623 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command saltutil.find_job with jid 20200226171842615476
2020-02-26 17:18:42,647 [salt.minion      :1432][INFO    ][8032] Starting a new job with PID 8032
2020-02-26 17:18:42,678 [salt.minion      :1711][INFO    ][8032] Returning information for job: 20200226171842615476
2020-02-26 17:19:10,586 [salt.state       :300 ][INFO    ][7306] {'ret': True}
2020-02-26 17:19:10,587 [salt.state       :1951][INFO    ][7306] Completed state [maas.wait_for_machine_status] at time 17:19:10.587158 duration_in_ms=671923.929
2020-02-26 17:19:10,591 [salt.minion      :1711][INFO    ][7306] Returning information for job: 20200226170755294327
2020-02-26 17:19:50,651 [salt.utils.schedule:1377][INFO    ][3362] Running scheduled job: __mine_interval
2020-02-26 18:08:32,202 [salt.minion      :1308][INFO    ][3362] User sudo_ubuntu Executing command cp.push_dir with jid 20200226180832191411
2020-02-26 18:08:32,230 [salt.minion      :1432][INFO    ][10359] Starting a new job with PID 10359
