2020-02-20 11:01:35,589 [salt.utils.decorators:613 ][WARNING ][1512] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-20 11:01:36,319 [salt.utils.decorators:613 ][WARNING ][1512] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-20 11:01:45,431 [salt.loaded.int.states.file:2298][WARNING ][1689] 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-20 11:01:52,766 [salt.state       :2022][WARNING ][1897] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-02-20 11:01:53,474 [salt.utils.decorators:613 ][WARNING ][1897] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-20 11:02:10,227 [salt.utils.decorators:613 ][WARNING ][1897] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-20 11:02:36,979 [salt.utils.decorators:613 ][WARNING ][1897] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-20 11:02:37,597 [salt.utils.decorators:613 ][WARNING ][1897] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-20 11:02:40,251 [salt.loaded.ext.module.maasng:1008][WARNING ][1897] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2020-02-20 11:02:40,252 [salt.loaded.ext.module.maasng:1011][WARNING ][1897] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2020-02-20 11:02:40,737 [salt.loaded.ext.module.maasng:1235][WARNING ][1897] Ignoring parameter vlan:0
2020-02-20 11:02:41,442 [salt.utils.decorators:613 ][WARNING ][1897] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-20 11:02:51,388 [salt.utils.decorators:613 ][WARNING ][3373] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-20 11:02:51,442 [salt.loaded.ext.module.maas:412 ][WARNING ][3373] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-20 11:02:52,292 [salt.loaded.ext.module.maas:412 ][WARNING ][3373] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-20 11:02:53,169 [salt.loaded.ext.module.maas:412 ][WARNING ][3373] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-20 11:02:53,972 [salt.loaded.ext.module.maas:412 ][WARNING ][3373] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-20 11:02:59,758 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3674] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2020-02-20 11:02:59,804 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3674] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2020-02-20 11:02:59,861 [salt.utils.parsers:1051][WARNING ][154] Minion received a SIGTERM. Exiting.
2020-02-20 11:03:00,924 [salt.cli.daemons :293 ][INFO    ][3733] Setting up the Salt Minion "mas01.mcp-fdio-noha.local"
2020-02-20 11:03:01,125 [salt.cli.daemons :82  ][INFO    ][3733] Starting up the Salt Minion
2020-02-20 11:03:01,125 [salt.utils.event :1017][INFO    ][3733] Starting pull socket on /var/run/salt/minion/minion_event_38d774b16c_pull.ipc
2020-02-20 11:03:02,419 [salt.minion      :976 ][INFO    ][3733] Creating minion process manager
2020-02-20 11:03:04,412 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3733] Executing command ['date', '+%z'] in directory '/root'
2020-02-20 11:03:04,439 [salt.utils.schedule:568 ][INFO    ][3733] Updating job settings for scheduled job: __mine_interval
2020-02-20 11:03:04,446 [salt.minion      :1108][INFO    ][3733] Added mine.update to scheduler
2020-02-20 11:03:04,451 [salt.minion      :1975][INFO    ][3733] Minion is starting as user 'root'
2020-02-20 11:03:04,471 [salt.minion      :2336][INFO    ][3733] Minion is ready to receive requests!
2020-02-20 11:03:26,344 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command state.apply with jid 20200220110326327572
2020-02-20 11:03:26,371 [salt.minion      :1432][INFO    ][3827] Starting a new job with PID 3827
2020-02-20 11:03:32,593 [salt.state       :915 ][INFO    ][3827] Loading fresh modules for state activity
2020-02-20 11:03:32,656 [salt.fileclient  :1219][INFO    ][3827] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2020-02-20 11:03:32,708 [salt.state       :1780][INFO    ][3827] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:03:32.708900
2020-02-20 11:03:32,709 [salt.state       :1813][INFO    ][3827] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-20 11:03:32,710 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3827] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-20 11:03:34,621 [salt.state       :300 ][INFO    ][3827] {'pid': 3834, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-20 11:03:34,622 [salt.state       :1951][INFO    ][3827] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:03:34.622669 duration_in_ms=1913.77
2020-02-20 11:03:34,626 [salt.state       :1780][INFO    ][3827] Running state [maas.wait_for_machine_status] at time 11:03:34.626249
2020-02-20 11:03:34,627 [salt.state       :1813][INFO    ][3827] Executing state module.run for [maas.wait_for_machine_status]
2020-02-20 11:03:34,627 [salt.utils.decorators:613 ][WARNING ][3827] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-20 11:03:35,355 [salt.loaded.ext.module.maas:1024][INFO    ][3827] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.28408098s left)
2020-02-20 11:03:41,453 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220110341438046
2020-02-20 11:03:41,471 [salt.minion      :1432][INFO    ][3844] Starting a new job with PID 3844
2020-02-20 11:03:41,493 [salt.minion      :1711][INFO    ][3844] Returning information for job: 20200220110341438046
2020-02-20 11:04:06,016 [salt.loaded.ext.module.maas:1024][INFO    ][3827] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.62278891s left)
2020-02-20 11:04:11,510 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220110411497962
2020-02-20 11:04:11,534 [salt.minion      :1432][INFO    ][3877] Starting a new job with PID 3877
2020-02-20 11:04:11,553 [salt.minion      :1711][INFO    ][3877] Returning information for job: 20200220110411497962
2020-02-20 11:04:36,702 [salt.loaded.ext.module.maas:1024][INFO    ][3827] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1437.9366169s left)
2020-02-20 11:04:41,579 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220110441561285
2020-02-20 11:04:41,603 [salt.minion      :1432][INFO    ][3896] Starting a new job with PID 3896
2020-02-20 11:04:41,625 [salt.minion      :1711][INFO    ][3896] Returning information for job: 20200220110441561285
2020-02-20 11:05:07,362 [salt.loaded.ext.module.maas:1024][INFO    ][3827] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1407.276932s left)
2020-02-20 11:05:11,651 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220110511637371
2020-02-20 11:05:11,679 [salt.minion      :1432][INFO    ][3928] Starting a new job with PID 3928
2020-02-20 11:05:11,699 [salt.minion      :1711][INFO    ][3928] Returning information for job: 20200220110511637371
2020-02-20 11:05:38,266 [salt.loaded.ext.module.maas:1024][INFO    ][3827] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1376.37333393s left)
2020-02-20 11:05:41,739 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220110541723403
2020-02-20 11:05:41,766 [salt.minion      :1432][INFO    ][3960] Starting a new job with PID 3960
2020-02-20 11:05:41,792 [salt.minion      :1711][INFO    ][3960] Returning information for job: 20200220110541723403
2020-02-20 11:06:09,187 [salt.loaded.ext.module.maas:1024][INFO    ][3827] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1345.45172501s left)
2020-02-20 11:06:11,811 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220110611801677
2020-02-20 11:06:11,831 [salt.minion      :1432][INFO    ][4026] Starting a new job with PID 4026
2020-02-20 11:06:11,855 [salt.minion      :1711][INFO    ][4026] Returning information for job: 20200220110611801677
2020-02-20 11:06:40,143 [salt.loaded.ext.module.maas:1024][INFO    ][3827] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1314.49635291s left)
2020-02-20 11:06:41,913 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220110641896430
2020-02-20 11:06:41,939 [salt.minion      :1432][INFO    ][4058] Starting a new job with PID 4058
2020-02-20 11:06:41,963 [salt.minion      :1711][INFO    ][4058] Returning information for job: 20200220110641896430
2020-02-20 11:07:11,266 [salt.loaded.ext.module.maas:1024][INFO    ][3827] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1283.37321901s left)
2020-02-20 11:07:12,003 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220110711991492
2020-02-20 11:07:12,020 [salt.minion      :1432][INFO    ][4121] Starting a new job with PID 4121
2020-02-20 11:07:12,038 [salt.minion      :1711][INFO    ][4121] Returning information for job: 20200220110711991492
2020-02-20 11:07:42,087 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220110742074793
2020-02-20 11:07:42,103 [salt.minion      :1432][INFO    ][4165] Starting a new job with PID 4165
2020-02-20 11:07:42,123 [salt.minion      :1711][INFO    ][4165] Returning information for job: 20200220110742074793
2020-02-20 11:07:42,776 [salt.loaded.ext.module.maas:1024][INFO    ][3827] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1251.86344886s left)
2020-02-20 11:08:12,178 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220110812167041
2020-02-20 11:08:12,205 [salt.minion      :1432][INFO    ][4213] Starting a new job with PID 4213
2020-02-20 11:08:12,224 [salt.minion      :1711][INFO    ][4213] Returning information for job: 20200220110812167041
2020-02-20 11:08:14,510 [salt.loaded.ext.module.maas:1024][INFO    ][3827] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1220.128829s left)
2020-02-20 11:08:42,305 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220110842296266
2020-02-20 11:08:42,331 [salt.minion      :1432][INFO    ][4265] Starting a new job with PID 4265
2020-02-20 11:08:42,352 [salt.minion      :1711][INFO    ][4265] Returning information for job: 20200220110842296266
2020-02-20 11:08:46,290 [salt.loaded.ext.module.maas:1024][INFO    ][3827] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1188.3495779s left)
2020-02-20 11:09:12,410 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220110912397821
2020-02-20 11:09:12,436 [salt.minion      :1432][INFO    ][4300] Starting a new job with PID 4300
2020-02-20 11:09:12,456 [salt.minion      :1711][INFO    ][4300] Returning information for job: 20200220110912397821
2020-02-20 11:09:18,251 [salt.loaded.ext.module.maas:1024][INFO    ][3827] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1156.38850784s left)
2020-02-20 11:09:42,515 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220110942504649
2020-02-20 11:09:42,538 [salt.minion      :1432][INFO    ][4365] Starting a new job with PID 4365
2020-02-20 11:09:42,559 [salt.minion      :1711][INFO    ][4365] Returning information for job: 20200220110942504649
2020-02-20 11:09:50,474 [salt.loaded.ext.module.maas:1024][INFO    ][3827] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1124.16516995s left)
2020-02-20 11:10:12,618 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220111012605403
2020-02-20 11:10:12,645 [salt.minion      :1432][INFO    ][4397] Starting a new job with PID 4397
2020-02-20 11:10:12,666 [salt.minion      :1711][INFO    ][4397] Returning information for job: 20200220111012605403
2020-02-20 11:10:22,744 [salt.state       :300 ][INFO    ][3827] {'ret': True}
2020-02-20 11:10:22,744 [salt.state       :1951][INFO    ][3827] Completed state [maas.wait_for_machine_status] at time 11:10:22.744568 duration_in_ms=408118.319
2020-02-20 11:10:22,748 [salt.minion      :1711][INFO    ][3827] Returning information for job: 20200220110326327572
2020-02-20 11:10:23,573 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command state.apply with jid 20200220111023562039
2020-02-20 11:10:23,597 [salt.minion      :1432][INFO    ][4417] Starting a new job with PID 4417
2020-02-20 11:10:29,789 [salt.state       :915 ][INFO    ][4417] Loading fresh modules for state activity
2020-02-20 11:10:29,850 [salt.fileclient  :1219][INFO    ][4417] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2020-02-20 11:10:29,949 [salt.state       :1780][INFO    ][4417] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:10:29.949000
2020-02-20 11:10:29,949 [salt.state       :1813][INFO    ][4417] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-20 11:10:29,951 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4417] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-20 11:10:31,863 [salt.state       :300 ][INFO    ][4417] {'pid': 4430, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-20 11:10:31,863 [salt.state       :1951][INFO    ][4417] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:10:31.863680 duration_in_ms=1914.678
2020-02-20 11:10:31,866 [salt.state       :1780][INFO    ][4417] Running state [maas_machines_storage_cmp002_lvm] at time 11:10:31.866187
2020-02-20 11:10:31,866 [salt.state       :1813][INFO    ][4417] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2020-02-20 11:10:33,015 [salt.loaded.ext.module.maasng:610 ][INFO    ][4417] dkbhtc
2020-02-20 11:10:33,015 [salt.loaded.ext.module.maasng:626 ][INFO    ][4417] sda
2020-02-20 11:10:33,655 [salt.loaded.ext.module.maasng:361 ][INFO    ][4417] dkbhtc
2020-02-20 11:10:33,742 [salt.loaded.ext.module.maasng:367 ][INFO    ][4417] [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'e202132f-a37d-41e8-b2ec-15fb69d74a6e', u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dkbhtc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e975b784-657f-4f1a-9942-fb8ec1be0e62', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'dkbhtc', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/3/', u'uuid': u'17c7006e-8af7-4a33-9304-9938684027b4', 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'70e24a9c-4db6-40d2-af00-52d2a376a6bc', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dkbhtc', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 3, u'size': 800097042432}]
2020-02-20 11:10:33,743 [salt.loaded.ext.module.maasng:632 ][INFO    ][4417] vgroot
2020-02-20 11:10:33,743 [salt.loaded.ext.module.maasng:635 ][INFO    ][4417] lvroot
2020-02-20 11:10:33,743 [salt.loaded.ext.module.maasng:639 ][INFO    ][4417] 107374182400
2020-02-20 11:10:34,376 [salt.loaded.ext.module.maasng:645 ][INFO    ][4417] {u'node_type_name': u'Machine', 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'swap_size': None, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': None, 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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'dkbhtc', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/interfaces/5/'}, u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/dkbhtc/', 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'dkbhtc', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/9/', u'uuid': u'af07dce0-4e4a-4eb4-8313-23f96645536a', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'fcc7eb10-0e5a-4de1-8c34-b8520d3b38b8', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'dkbhtc', 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'block_size': 4096, u'used_size': 107374182400, u'id': 9, u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'4ca532e9-2991-4305-a0a3-20d9a5c75317', u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/1/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dkbhtc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a73d65e3-5748-4556-9ce4-0f0f303883a0', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 5, u'size': 800101236736}], u'filesystem': None, u'name': u'sda', u'system_id': u'dkbhtc', 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'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/9/', u'uuid': u'af07dce0-4e4a-4eb4-8313-23f96645536a', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'fcc7eb10-0e5a-4de1-8c34-b8520d3b38b8', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'dkbhtc', 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'id': 9, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'dkbhtc', u'id': 5}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'4ca532e9-2991-4305-a0a3-20d9a5c75317', u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/1/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dkbhtc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a73d65e3-5748-4556-9ce4-0f0f303883a0', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 5, u'size': 800101236736}], u'filesystem': None, u'name': u'sda', u'system_id': u'dkbhtc', 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'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, 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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'dkbhtc', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/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'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'dkbhtc', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/interfaces/10/'}, {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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'dkbhtc', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/interfaces/11/'}, {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'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'dkbhtc', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/interfaces/12/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'dkbhtc', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/interfaces/13/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 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'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'dkbhtc', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/interfaces/14/'}], 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'architecture': u'amd64/generic', u'storage_test_status': 2, u'locked': False, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'4ca532e9-2991-4305-a0a3-20d9a5c75317', u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/1/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dkbhtc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a73d65e3-5748-4556-9ce4-0f0f303883a0', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 5, u'size': 800101236736}], u'filesystem': None, u'name': u'sda', u'system_id': u'dkbhtc', 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'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], 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'current_commissioning_result_id': 4, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2020-02-20 11:10:34,378 [salt.state       :300 ][INFO    ][4417] {'new': {'storage_layout': 'lvm'}}
2020-02-20 11:10:34,379 [salt.state       :1951][INFO    ][4417] Completed state [maas_machines_storage_cmp002_lvm] at time 11:10:34.379023 duration_in_ms=2512.835
2020-02-20 11:10:34,379 [salt.state       :1780][INFO    ][4417] Running state [maas_machines_storage_cmp001_lvm] at time 11:10:34.379403
2020-02-20 11:10:34,379 [salt.state       :1813][INFO    ][4417] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2020-02-20 11:10:35,459 [salt.loaded.ext.module.maasng:610 ][INFO    ][4417] sstn8r
2020-02-20 11:10:35,459 [salt.loaded.ext.module.maasng:626 ][INFO    ][4417] sda
2020-02-20 11:10:36,046 [salt.loaded.ext.module.maasng:361 ][INFO    ][4417] sstn8r
2020-02-20 11:10:36,127 [salt.loaded.ext.module.maasng:367 ][INFO    ][4417] [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'5f38733f-a21d-4098-9478-c147fcfd6639', u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sstn8r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'5402265d-3173-4031-913e-b010a5fa4c4c', 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'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'sstn8r', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/4/', u'uuid': u'a0ffe4ca-0b96-4249-a61b-d89d62f33ca1', 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'fb9df237-ae3f-4730-a099-98c52c18cef9', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sstn8r', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'size': 800097042432}]
2020-02-20 11:10:36,127 [salt.loaded.ext.module.maasng:632 ][INFO    ][4417] vgroot
2020-02-20 11:10:36,127 [salt.loaded.ext.module.maasng:635 ][INFO    ][4417] lvroot
2020-02-20 11:10:36,127 [salt.loaded.ext.module.maasng:639 ][INFO    ][4417] 107374182400
2020-02-20 11:10:36,778 [salt.loaded.ext.module.maasng:645 ][INFO    ][4417] {u'node_type_name': u'Machine', 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'swap_size': None, u'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': None, u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'cpu_speed': 2800, u'owner': None, u'pod': None, 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/sstn8r/', u'current_commissioning_result_id': 6, 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'testing_status': 2, u'system_id': u'sstn8r', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'uuid': u'e4f8b9bf-fa59-48b8-8e51-e9519f44f4c6', 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'834f80e2-6756-4fc9-9fa7-4faf8dfb5336', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/10/', u'system_id': u'sstn8r', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 10, u'name': u'vgroot-lvroot'}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'ffa5fd70-1ed3-47e9-8b41-312366fcc848', u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sstn8r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'1fff4d95-a224-4fcf-a410-9a06dcce24c4', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'sstn8r', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/10/', u'uuid': u'e4f8b9bf-fa59-48b8-8e51-e9519f44f4c6', 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'834f80e2-6756-4fc9-9fa7-4faf8dfb5336', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sstn8r', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 10, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'ffa5fd70-1ed3-47e9-8b41-312366fcc848', u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sstn8r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'1fff4d95-a224-4fcf-a410-9a06dcce24c4', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/2/', u'system_id': u'sstn8r', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'firmware_version': u'5.22', u'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'sstn8r', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'pxe_admin'}, 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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.39', u'id': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 6, 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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'sstn8r', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/interfaces/6/'}, u'current_testing_result_id': 7, 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'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, 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'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'pxe_admin'}, 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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.39', u'id': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 6, 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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'sstn8r', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/interfaces/6/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': None, u'vendor': u'HP', u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 15, u'discovered': None, u'system_id': u'sstn8r', u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/interfaces/15/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'pxe_admin'}, 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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 16, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.43'}], u'system_id': u'sstn8r', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/interfaces/16/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': None, u'vendor': u'HP', u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 17, u'discovered': None, u'system_id': u'sstn8r', u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/interfaces/17/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', 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'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 18, u'discovered': None, u'system_id': u'sstn8r', u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/interfaces/18/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', 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'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 29, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 19, u'discovered': None, u'system_id': u'sstn8r', u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/interfaces/19/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'ffa5fd70-1ed3-47e9-8b41-312366fcc848', u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sstn8r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'1fff4d95-a224-4fcf-a410-9a06dcce24c4', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/2/', u'system_id': u'sstn8r', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'firmware_version': u'5.22', u'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}}
2020-02-20 11:10:36,779 [salt.state       :300 ][INFO    ][4417] {'new': {'storage_layout': 'lvm'}}
2020-02-20 11:10:36,780 [salt.state       :1951][INFO    ][4417] Completed state [maas_machines_storage_cmp001_lvm] at time 11:10:36.779962 duration_in_ms=2400.559
2020-02-20 11:10:36,786 [salt.minion      :1711][INFO    ][4417] Returning information for job: 20200220111023562039
2020-02-20 11:10:37,616 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command state.apply with jid 20200220111037605153
2020-02-20 11:10:37,637 [salt.minion      :1432][INFO    ][4441] Starting a new job with PID 4441
2020-02-20 11:10:38,560 [salt.state       :915 ][INFO    ][4441] Loading fresh modules for state activity
2020-02-20 11:10:38,614 [salt.fileclient  :1219][INFO    ][4441] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2020-02-20 11:10:38,664 [salt.state       :1780][INFO    ][4441] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:10:38.664233
2020-02-20 11:10:38,664 [salt.state       :1813][INFO    ][4441] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-20 11:10:38,666 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4441] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-20 11:10:40,731 [salt.state       :300 ][INFO    ][4441] {'pid': 4449, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-20 11:10:40,731 [salt.state       :1951][INFO    ][4441] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:10:40.731760 duration_in_ms=2067.528
2020-02-20 11:10:40,735 [salt.state       :1780][INFO    ][4441] Running state [maas.deploy_machines] at time 11:10:40.735071
2020-02-20 11:10:40,735 [salt.state       :1813][INFO    ][4441] Executing state module.run for [maas.deploy_machines]
2020-02-20 11:10:40,736 [salt.utils.decorators:613 ][WARNING ][4441] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-20 11:10:41,310 [salt.loaded.ext.module.maas:684 ][INFO    ][4441] deploymachines hwe_kernel=ga-18.04 system_id=t76e6s distro_series=bionic
2020-02-20 11:10:43,206 [salt.loaded.ext.module.maas:684 ][INFO    ][4441] deploymachines hwe_kernel=ga-18.04 system_id=dkbhtc distro_series=bionic
2020-02-20 11:10:45,201 [salt.loaded.ext.module.maas:684 ][INFO    ][4441] deploymachines hwe_kernel=ga-18.04 system_id=sstn8r distro_series=bionic
2020-02-20 11:10:47,091 [salt.loaded.ext.module.maas:684 ][INFO    ][4441] deploymachines hwe_kernel=ga-18.04 system_id=hgwgdh distro_series=bionic
2020-02-20 11:10:49,159 [salt.state       :300 ][INFO    ][4441] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2020-02-20 11:10:49,159 [salt.state       :1951][INFO    ][4441] Completed state [maas.deploy_machines] at time 11:10:49.159774 duration_in_ms=8424.703
2020-02-20 11:10:49,163 [salt.minion      :1711][INFO    ][4441] Returning information for job: 20200220111037605153
2020-02-20 11:10:49,959 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command state.apply with jid 20200220111049948958
2020-02-20 11:10:49,975 [salt.minion      :1432][INFO    ][4487] Starting a new job with PID 4487
2020-02-20 11:10:56,243 [salt.state       :915 ][INFO    ][4487] Loading fresh modules for state activity
2020-02-20 11:10:56,299 [salt.fileclient  :1219][INFO    ][4487] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2020-02-20 11:10:56,353 [salt.state       :1780][INFO    ][4487] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:10:56.353887
2020-02-20 11:10:56,354 [salt.state       :1813][INFO    ][4487] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-20 11:10:56,355 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4487] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-20 11:10:58,297 [salt.state       :300 ][INFO    ][4487] {'pid': 4514, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-20 11:10:58,298 [salt.state       :1951][INFO    ][4487] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:10:58.298696 duration_in_ms=1944.809
2020-02-20 11:10:58,302 [salt.state       :1780][INFO    ][4487] Running state [maas.wait_for_machine_status] at time 11:10:58.302557
2020-02-20 11:10:58,303 [salt.state       :1813][INFO    ][4487] Executing state module.run for [maas.wait_for_machine_status]
2020-02-20 11:10:58,303 [salt.utils.decorators:613 ][WARNING ][4487] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-20 11:11:00,615 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2247.70067406s left)
2020-02-20 11:11:04,997 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220111104983028
2020-02-20 11:11:05,019 [salt.minion      :1432][INFO    ][4531] Starting a new job with PID 4531
2020-02-20 11:11:05,043 [salt.minion      :1711][INFO    ][4531] Returning information for job: 20200220111104983028
2020-02-20 11:11:32,955 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2215.36001205s left)
2020-02-20 11:11:35,061 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220111135048932
2020-02-20 11:11:35,092 [salt.minion      :1432][INFO    ][4551] Starting a new job with PID 4551
2020-02-20 11:11:35,112 [salt.minion      :1711][INFO    ][4551] Returning information for job: 20200220111135048932
2020-02-20 11:12:05,130 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220111205118259
2020-02-20 11:12:05,153 [salt.minion      :1432][INFO    ][4596] Starting a new job with PID 4596
2020-02-20 11:12:05,171 [salt.minion      :1711][INFO    ][4596] Returning information for job: 20200220111205118259
2020-02-20 11:12:05,361 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2182.9545691s left)
2020-02-20 11:12:35,179 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220111235167215
2020-02-20 11:12:35,204 [salt.minion      :1432][INFO    ][4621] Starting a new job with PID 4621
2020-02-20 11:12:35,223 [salt.minion      :1711][INFO    ][4621] Returning information for job: 20200220111235167215
2020-02-20 11:12:37,663 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2150.65222907s left)
2020-02-20 11:13:05,250 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220111305239388
2020-02-20 11:13:05,278 [salt.minion      :1432][INFO    ][4657] Starting a new job with PID 4657
2020-02-20 11:13:05,299 [salt.minion      :1711][INFO    ][4657] Returning information for job: 20200220111305239388
2020-02-20 11:13:10,022 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2118.29390502s left)
2020-02-20 11:13:35,294 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220111335281085
2020-02-20 11:13:35,321 [salt.minion      :1432][INFO    ][4685] Starting a new job with PID 4685
2020-02-20 11:13:35,339 [salt.minion      :1711][INFO    ][4685] Returning information for job: 20200220111335281085
2020-02-20 11:13:42,530 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2085.78579521s left)
2020-02-20 11:14:05,371 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220111405347338
2020-02-20 11:14:05,396 [salt.minion      :1432][INFO    ][4752] Starting a new job with PID 4752
2020-02-20 11:14:05,418 [salt.minion      :1711][INFO    ][4752] Returning information for job: 20200220111405347338
2020-02-20 11:14:14,983 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2053.33231807s left)
2020-02-20 11:14:35,453 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220111435438569
2020-02-20 11:14:35,476 [salt.minion      :1432][INFO    ][4799] Starting a new job with PID 4799
2020-02-20 11:14:35,497 [salt.minion      :1711][INFO    ][4799] Returning information for job: 20200220111435438569
2020-02-20 11:14:46,130 [salt.loaded.ext.module.maas:993 ][INFO    ][4487] Machine dkbhtc mark broken
2020-02-20 11:15:05,514 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220111505501492
2020-02-20 11:15:05,537 [salt.minion      :1432][INFO    ][4850] Starting a new job with PID 4850
2020-02-20 11:15:05,555 [salt.minion      :1711][INFO    ][4850] Returning information for job: 20200220111505501492
2020-02-20 11:15:16,801 [salt.loaded.ext.module.maas:997 ][INFO    ][4487] Machine dkbhtc mark fixed
2020-02-20 11:15:17,869 [salt.loaded.ext.module.maas:684 ][INFO    ][4487] deploymachines hwe_kernel=ga-18.04 system_id=dkbhtc distro_series=bionic
2020-02-20 11:15:20,273 [salt.loaded.ext.module.maas:993 ][INFO    ][4487] Machine sstn8r mark broken
2020-02-20 11:15:35,590 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220111535583433
2020-02-20 11:15:35,613 [salt.minion      :1432][INFO    ][4900] Starting a new job with PID 4900
2020-02-20 11:15:35,637 [salt.minion      :1711][INFO    ][4900] Returning information for job: 20200220111535583433
2020-02-20 11:15:50,852 [salt.loaded.ext.module.maas:997 ][INFO    ][4487] Machine sstn8r mark fixed
2020-02-20 11:15:51,964 [salt.loaded.ext.module.maas:684 ][INFO    ][4487] deploymachines hwe_kernel=ga-18.04 system_id=sstn8r distro_series=bionic
2020-02-20 11:15:54,739 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1953.57625103s left)
2020-02-20 11:16:05,703 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220111605686349
2020-02-20 11:16:05,731 [salt.minion      :1432][INFO    ][4979] Starting a new job with PID 4979
2020-02-20 11:16:05,764 [salt.minion      :1711][INFO    ][4979] Returning information for job: 20200220111605686349
2020-02-20 11:16:27,146 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1921.16971803s left)
2020-02-20 11:16:35,829 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220111635815832
2020-02-20 11:16:35,851 [salt.minion      :1432][INFO    ][4998] Starting a new job with PID 4998
2020-02-20 11:16:35,873 [salt.minion      :1711][INFO    ][4998] Returning information for job: 20200220111635815832
2020-02-20 11:16:59,855 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1888.46076012s left)
2020-02-20 11:17:05,921 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220111705910794
2020-02-20 11:17:05,946 [salt.minion      :1432][INFO    ][5039] Starting a new job with PID 5039
2020-02-20 11:17:05,971 [salt.minion      :1711][INFO    ][5039] Returning information for job: 20200220111705910794
2020-02-20 11:17:32,164 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1856.1512711s left)
2020-02-20 11:17:36,014 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220111736004917
2020-02-20 11:17:36,035 [salt.minion      :1432][INFO    ][5062] Starting a new job with PID 5062
2020-02-20 11:17:36,068 [salt.minion      :1711][INFO    ][5062] Returning information for job: 20200220111736004917
2020-02-20 11:18:04,341 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1823.97472405s left)
2020-02-20 11:18:06,125 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220111806111842
2020-02-20 11:18:06,151 [salt.minion      :1432][INFO    ][5103] Starting a new job with PID 5103
2020-02-20 11:18:06,175 [salt.minion      :1711][INFO    ][5103] Returning information for job: 20200220111806111842
2020-02-20 11:18:36,275 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220111836267324
2020-02-20 11:18:36,299 [salt.minion      :1432][INFO    ][5152] Starting a new job with PID 5152
2020-02-20 11:18:36,329 [salt.minion      :1711][INFO    ][5152] Returning information for job: 20200220111836267324
2020-02-20 11:18:36,935 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1791.38075709s left)
2020-02-20 11:19:06,386 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220111906370472
2020-02-20 11:19:06,413 [salt.minion      :1432][INFO    ][5198] Starting a new job with PID 5198
2020-02-20 11:19:06,435 [salt.minion      :1711][INFO    ][5198] Returning information for job: 20200220111906370472
2020-02-20 11:19:08,165 [salt.loaded.ext.module.maas:993 ][INFO    ][4487] Machine dkbhtc mark broken
2020-02-20 11:19:36,535 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220111936519857
2020-02-20 11:19:36,559 [salt.minion      :1432][INFO    ][5255] Starting a new job with PID 5255
2020-02-20 11:19:36,585 [salt.minion      :1711][INFO    ][5255] Returning information for job: 20200220111936519857
2020-02-20 11:19:38,860 [salt.loaded.ext.module.maas:997 ][INFO    ][4487] Machine dkbhtc mark fixed
2020-02-20 11:19:39,397 [salt.loaded.ext.module.maas:1001][INFO    ][4487] Machine dkbhtc fio test
2020-02-20 11:19:41,858 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1726.45762205s left)
2020-02-20 11:20:06,680 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220112006668278
2020-02-20 11:20:06,701 [salt.minion      :1432][INFO    ][5296] Starting a new job with PID 5296
2020-02-20 11:20:06,722 [salt.minion      :1711][INFO    ][5296] Returning information for job: 20200220112006668278
2020-02-20 11:20:13,505 [salt.loaded.ext.module.maas:993 ][INFO    ][4487] Machine sstn8r mark broken
2020-02-20 11:20:36,823 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220112036810785
2020-02-20 11:20:36,852 [salt.minion      :1432][INFO    ][5325] Starting a new job with PID 5325
2020-02-20 11:20:36,877 [salt.minion      :1711][INFO    ][5325] Returning information for job: 20200220112036810785
2020-02-20 11:20:44,153 [salt.loaded.ext.module.maas:997 ][INFO    ][4487] Machine sstn8r mark fixed
2020-02-20 11:20:44,727 [salt.loaded.ext.module.maas:1001][INFO    ][4487] Machine sstn8r fio test
2020-02-20 11:20:46,406 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1661.9089992s left)
2020-02-20 11:21:06,974 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220112106956199
2020-02-20 11:21:06,999 [salt.minion      :1432][INFO    ][5366] Starting a new job with PID 5366
2020-02-20 11:21:07,025 [salt.minion      :1711][INFO    ][5366] Returning information for job: 20200220112106956199
2020-02-20 11:21:18,496 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1629.81967616s left)
2020-02-20 11:21:37,126 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220112137111876
2020-02-20 11:21:37,156 [salt.minion      :1432][INFO    ][5387] Starting a new job with PID 5387
2020-02-20 11:21:37,179 [salt.minion      :1711][INFO    ][5387] Returning information for job: 20200220112137111876
2020-02-20 11:21:50,471 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1597.844347s left)
2020-02-20 11:22:07,274 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220112207261012
2020-02-20 11:22:07,302 [salt.minion      :1432][INFO    ][5434] Starting a new job with PID 5434
2020-02-20 11:22:07,325 [salt.minion      :1711][INFO    ][5434] Returning information for job: 20200220112207261012
2020-02-20 11:22:22,379 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1565.93654203s left)
2020-02-20 11:22:37,407 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220112237394010
2020-02-20 11:22:37,436 [salt.minion      :1432][INFO    ][5465] Starting a new job with PID 5465
2020-02-20 11:22:37,461 [salt.minion      :1711][INFO    ][5465] Returning information for job: 20200220112237394010
2020-02-20 11:22:54,563 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1533.75292802s left)
2020-02-20 11:23:07,598 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220112307582609
2020-02-20 11:23:07,622 [salt.minion      :1432][INFO    ][5511] Starting a new job with PID 5511
2020-02-20 11:23:07,643 [salt.minion      :1711][INFO    ][5511] Returning information for job: 20200220112307582609
2020-02-20 11:23:26,572 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1501.74371314s left)
2020-02-20 11:23:37,783 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220112337766496
2020-02-20 11:23:37,810 [salt.minion      :1432][INFO    ][5532] Starting a new job with PID 5532
2020-02-20 11:23:37,834 [salt.minion      :1711][INFO    ][5532] Returning information for job: 20200220112337766496
2020-02-20 11:23:58,725 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1469.590765s left)
2020-02-20 11:24:07,943 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220112407929175
2020-02-20 11:24:07,964 [salt.minion      :1432][INFO    ][5598] Starting a new job with PID 5598
2020-02-20 11:24:07,986 [salt.minion      :1711][INFO    ][5598] Returning information for job: 20200220112407929175
2020-02-20 11:24:30,794 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1437.52199316s left)
2020-02-20 11:24:38,030 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220112438017640
2020-02-20 11:24:38,057 [salt.minion      :1432][INFO    ][5659] Starting a new job with PID 5659
2020-02-20 11:24:38,079 [salt.minion      :1711][INFO    ][5659] Returning information for job: 20200220112438017640
2020-02-20 11:25:02,866 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1405.44966221s left)
2020-02-20 11:25:08,239 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220112508225081
2020-02-20 11:25:08,266 [salt.minion      :1432][INFO    ][5702] Starting a new job with PID 5702
2020-02-20 11:25:08,287 [salt.minion      :1711][INFO    ][5702] Returning information for job: 20200220112508225081
2020-02-20 11:25:35,023 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1373.29260111s left)
2020-02-20 11:25:38,423 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220112538411048
2020-02-20 11:25:38,455 [salt.minion      :1432][INFO    ][5722] Starting a new job with PID 5722
2020-02-20 11:25:38,475 [salt.minion      :1711][INFO    ][5722] Returning information for job: 20200220112538411048
2020-02-20 11:26:07,346 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1340.96911716s left)
2020-02-20 11:26:08,647 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220112608637429
2020-02-20 11:26:08,675 [salt.minion      :1432][INFO    ][5781] Starting a new job with PID 5781
2020-02-20 11:26:08,698 [salt.minion      :1711][INFO    ][5781] Returning information for job: 20200220112608637429
2020-02-20 11:26:38,859 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220112638847992
2020-02-20 11:26:38,875 [salt.minion      :1432][INFO    ][5800] Starting a new job with PID 5800
2020-02-20 11:26:38,897 [salt.minion      :1711][INFO    ][5800] Returning information for job: 20200220112638847992
2020-02-20 11:26:39,617 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1308.69816113s left)
2020-02-20 11:27:09,074 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220112709065721
2020-02-20 11:27:09,100 [salt.minion      :1432][INFO    ][5837] Starting a new job with PID 5837
2020-02-20 11:27:09,126 [salt.minion      :1711][INFO    ][5837] Returning information for job: 20200220112709065721
2020-02-20 11:27:11,930 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1276.3854692s left)
2020-02-20 11:27:39,295 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220112739285178
2020-02-20 11:27:39,318 [salt.minion      :1432][INFO    ][5857] Starting a new job with PID 5857
2020-02-20 11:27:39,339 [salt.minion      :1711][INFO    ][5857] Returning information for job: 20200220112739285178
2020-02-20 11:27:44,247 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1244.06842518s left)
2020-02-20 11:28:09,354 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220112809342595
2020-02-20 11:28:09,377 [salt.minion      :1432][INFO    ][5889] Starting a new job with PID 5889
2020-02-20 11:28:09,398 [salt.minion      :1711][INFO    ][5889] Returning information for job: 20200220112809342595
2020-02-20 11:28:16,718 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1211.59721899s left)
2020-02-20 11:28:39,419 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220112839405431
2020-02-20 11:28:39,440 [salt.minion      :1432][INFO    ][5909] Starting a new job with PID 5909
2020-02-20 11:28:39,471 [salt.minion      :1711][INFO    ][5909] Returning information for job: 20200220112839405431
2020-02-20 11:28:49,357 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1178.95888019s left)
2020-02-20 11:29:09,482 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220112909467865
2020-02-20 11:29:09,502 [salt.minion      :1432][INFO    ][5949] Starting a new job with PID 5949
2020-02-20 11:29:09,525 [salt.minion      :1711][INFO    ][5949] Returning information for job: 20200220112909467865
2020-02-20 11:29:21,743 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1146.57245016s left)
2020-02-20 11:29:39,534 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220112939518849
2020-02-20 11:29:39,561 [salt.minion      :1432][INFO    ][5970] Starting a new job with PID 5970
2020-02-20 11:29:39,585 [salt.minion      :1711][INFO    ][5970] Returning information for job: 20200220112939518849
2020-02-20 11:29:54,231 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1114.08426118s left)
2020-02-20 11:30:09,627 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220113009615139
2020-02-20 11:30:09,651 [salt.minion      :1432][INFO    ][6004] Starting a new job with PID 6004
2020-02-20 11:30:09,671 [salt.minion      :1711][INFO    ][6004] Returning information for job: 20200220113009615139
2020-02-20 11:30:26,510 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1081.805094s left)
2020-02-20 11:30:39,707 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220113039697245
2020-02-20 11:30:39,727 [salt.minion      :1432][INFO    ][6025] Starting a new job with PID 6025
2020-02-20 11:30:39,747 [salt.minion      :1711][INFO    ][6025] Returning information for job: 20200220113039697245
2020-02-20 11:30:58,834 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1049.4811132s left)
2020-02-20 11:31:09,801 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220113109785150
2020-02-20 11:31:09,833 [salt.minion      :1432][INFO    ][6059] Starting a new job with PID 6059
2020-02-20 11:31:09,851 [salt.minion      :1711][INFO    ][6059] Returning information for job: 20200220113109785150
2020-02-20 11:31:31,174 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1017.14124703s left)
2020-02-20 11:31:39,919 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220113139905085
2020-02-20 11:31:39,942 [salt.minion      :1432][INFO    ][6081] Starting a new job with PID 6081
2020-02-20 11:31:39,966 [salt.minion      :1711][INFO    ][6081] Returning information for job: 20200220113139905085
2020-02-20 11:32:03,446 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (984.869915009s left)
2020-02-20 11:32:10,031 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220113210020986
2020-02-20 11:32:10,053 [salt.minion      :1432][INFO    ][6131] Starting a new job with PID 6131
2020-02-20 11:32:10,077 [salt.minion      :1711][INFO    ][6131] Returning information for job: 20200220113210020986
2020-02-20 11:32:35,847 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (952.468392134s left)
2020-02-20 11:32:40,162 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220113240153312
2020-02-20 11:32:40,179 [salt.minion      :1432][INFO    ][6153] Starting a new job with PID 6153
2020-02-20 11:32:40,198 [salt.minion      :1711][INFO    ][6153] Returning information for job: 20200220113240153312
2020-02-20 11:33:08,058 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (920.257548094s left)
2020-02-20 11:33:10,323 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220113310306740
2020-02-20 11:33:10,350 [salt.minion      :1432][INFO    ][6190] Starting a new job with PID 6190
2020-02-20 11:33:10,372 [salt.minion      :1711][INFO    ][6190] Returning information for job: 20200220113310306740
2020-02-20 11:33:40,293 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (888.022900105s left)
2020-02-20 11:33:40,491 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220113340474782
2020-02-20 11:33:40,516 [salt.minion      :1432][INFO    ][6210] Starting a new job with PID 6210
2020-02-20 11:33:40,541 [salt.minion      :1711][INFO    ][6210] Returning information for job: 20200220113340474782
2020-02-20 11:34:10,689 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220113410677977
2020-02-20 11:34:10,709 [salt.minion      :1432][INFO    ][6247] Starting a new job with PID 6247
2020-02-20 11:34:10,730 [salt.minion      :1711][INFO    ][6247] Returning information for job: 20200220113410677977
2020-02-20 11:34:12,581 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (855.734736204s left)
2020-02-20 11:34:40,887 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220113440874972
2020-02-20 11:34:40,912 [salt.minion      :1432][INFO    ][6272] Starting a new job with PID 6272
2020-02-20 11:34:40,942 [salt.minion      :1711][INFO    ][6272] Returning information for job: 20200220113440874972
2020-02-20 11:34:44,619 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (823.696524143s left)
2020-02-20 11:35:11,083 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220113511072023
2020-02-20 11:35:11,111 [salt.minion      :1432][INFO    ][6308] Starting a new job with PID 6308
2020-02-20 11:35:11,129 [salt.minion      :1711][INFO    ][6308] Returning information for job: 20200220113511072023
2020-02-20 11:35:16,914 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (791.401774168s left)
2020-02-20 11:35:41,309 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220113541297675
2020-02-20 11:35:41,337 [salt.minion      :1432][INFO    ][6330] Starting a new job with PID 6330
2020-02-20 11:35:41,360 [salt.minion      :1711][INFO    ][6330] Returning information for job: 20200220113541297675
2020-02-20 11:35:49,104 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (759.211976051s left)
2020-02-20 11:36:11,334 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220113611318344
2020-02-20 11:36:11,357 [salt.minion      :1432][INFO    ][6365] Starting a new job with PID 6365
2020-02-20 11:36:11,379 [salt.minion      :1711][INFO    ][6365] Returning information for job: 20200220113611318344
2020-02-20 11:36:21,342 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (726.973470211s left)
2020-02-20 11:36:41,396 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220113641380146
2020-02-20 11:36:41,422 [salt.minion      :1432][INFO    ][6386] Starting a new job with PID 6386
2020-02-20 11:36:41,443 [salt.minion      :1711][INFO    ][6386] Returning information for job: 20200220113641380146
2020-02-20 11:36:53,462 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (694.853576183s left)
2020-02-20 11:37:11,428 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220113711412767
2020-02-20 11:37:11,455 [salt.minion      :1432][INFO    ][6419] Starting a new job with PID 6419
2020-02-20 11:37:11,475 [salt.minion      :1711][INFO    ][6419] Returning information for job: 20200220113711412767
2020-02-20 11:37:25,591 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (662.72422719s left)
2020-02-20 11:37:41,507 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220113741497219
2020-02-20 11:37:41,535 [salt.minion      :1432][INFO    ][6439] Starting a new job with PID 6439
2020-02-20 11:37:41,560 [salt.minion      :1711][INFO    ][6439] Returning information for job: 20200220113741497219
2020-02-20 11:37:57,699 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (630.616171122s left)
2020-02-20 11:38:11,586 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220113811575585
2020-02-20 11:38:11,606 [salt.minion      :1432][INFO    ][6475] Starting a new job with PID 6475
2020-02-20 11:38:11,632 [salt.minion      :1711][INFO    ][6475] Returning information for job: 20200220113811575585
2020-02-20 11:38:29,854 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (598.461802006s left)
2020-02-20 11:38:41,681 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220113841669256
2020-02-20 11:38:41,708 [salt.minion      :1432][INFO    ][6494] Starting a new job with PID 6494
2020-02-20 11:38:41,729 [salt.minion      :1711][INFO    ][6494] Returning information for job: 20200220113841669256
2020-02-20 11:39:01,948 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (566.367983103s left)
2020-02-20 11:39:11,823 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220113911814929
2020-02-20 11:39:11,851 [salt.minion      :1432][INFO    ][6526] Starting a new job with PID 6526
2020-02-20 11:39:11,872 [salt.minion      :1711][INFO    ][6526] Returning information for job: 20200220113911814929
2020-02-20 11:39:34,135 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (534.180685043s left)
2020-02-20 11:39:42,002 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220113941986441
2020-02-20 11:39:42,026 [salt.minion      :1432][INFO    ][6548] Starting a new job with PID 6548
2020-02-20 11:39:42,047 [salt.minion      :1711][INFO    ][6548] Returning information for job: 20200220113941986441
2020-02-20 11:40:06,386 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (501.930009127s left)
2020-02-20 11:40:12,185 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220114012169637
2020-02-20 11:40:12,210 [salt.minion      :1432][INFO    ][6581] Starting a new job with PID 6581
2020-02-20 11:40:12,232 [salt.minion      :1711][INFO    ][6581] Returning information for job: 20200220114012169637
2020-02-20 11:40:38,517 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (469.798804998s left)
2020-02-20 11:40:42,305 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220114042295011
2020-02-20 11:40:42,332 [salt.minion      :1432][INFO    ][6603] Starting a new job with PID 6603
2020-02-20 11:40:42,351 [salt.minion      :1711][INFO    ][6603] Returning information for job: 20200220114042295011
2020-02-20 11:41:10,839 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (437.47680521s left)
2020-02-20 11:41:12,475 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220114112461881
2020-02-20 11:41:12,497 [salt.minion      :1432][INFO    ][6639] Starting a new job with PID 6639
2020-02-20 11:41:12,530 [salt.minion      :1711][INFO    ][6639] Returning information for job: 20200220114112461881
2020-02-20 11:41:42,698 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220114142683067
2020-02-20 11:41:42,719 [salt.minion      :1432][INFO    ][6660] Starting a new job with PID 6660
2020-02-20 11:41:42,746 [salt.minion      :1711][INFO    ][6660] Returning information for job: 20200220114142683067
2020-02-20 11:41:42,987 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (405.328983068s left)
2020-02-20 11:42:12,743 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220114212729623
2020-02-20 11:42:12,772 [salt.minion      :1432][INFO    ][6709] Starting a new job with PID 6709
2020-02-20 11:42:12,801 [salt.minion      :1711][INFO    ][6709] Returning information for job: 20200220114212729623
2020-02-20 11:42:15,115 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (373.200519085s left)
2020-02-20 11:42:42,944 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220114242932971
2020-02-20 11:42:42,969 [salt.minion      :1432][INFO    ][6731] Starting a new job with PID 6731
2020-02-20 11:42:43,001 [salt.minion      :1711][INFO    ][6731] Returning information for job: 20200220114242932971
2020-02-20 11:42:47,167 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (341.148102999s left)
2020-02-20 11:43:13,006 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220114312995698
2020-02-20 11:43:13,030 [salt.minion      :1432][INFO    ][6765] Starting a new job with PID 6765
2020-02-20 11:43:13,054 [salt.minion      :1711][INFO    ][6765] Returning information for job: 20200220114312995698
2020-02-20 11:43:19,545 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (308.770219088s left)
2020-02-20 11:43:43,207 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220114343194463
2020-02-20 11:43:43,232 [salt.minion      :1432][INFO    ][6785] Starting a new job with PID 6785
2020-02-20 11:43:43,258 [salt.minion      :1711][INFO    ][6785] Returning information for job: 20200220114343194463
2020-02-20 11:43:51,797 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (276.518845081s left)
2020-02-20 11:44:13,282 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220114413270742
2020-02-20 11:44:13,311 [salt.minion      :1432][INFO    ][6819] Starting a new job with PID 6819
2020-02-20 11:44:13,338 [salt.minion      :1711][INFO    ][6819] Returning information for job: 20200220114413270742
2020-02-20 11:44:23,835 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (244.480190039s left)
2020-02-20 11:44:43,353 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220114443340236
2020-02-20 11:44:43,378 [salt.minion      :1432][INFO    ][6838] Starting a new job with PID 6838
2020-02-20 11:44:43,407 [salt.minion      :1711][INFO    ][6838] Returning information for job: 20200220114443340236
2020-02-20 11:44:56,107 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (212.208624125s left)
2020-02-20 11:45:13,467 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220114513454557
2020-02-20 11:45:13,487 [salt.minion      :1432][INFO    ][6871] Starting a new job with PID 6871
2020-02-20 11:45:13,515 [salt.minion      :1711][INFO    ][6871] Returning information for job: 20200220114513454557
2020-02-20 11:45:28,160 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (180.155042171s left)
2020-02-20 11:45:43,566 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220114543558194
2020-02-20 11:45:43,590 [salt.minion      :1432][INFO    ][6891] Starting a new job with PID 6891
2020-02-20 11:45:43,616 [salt.minion      :1711][INFO    ][6891] Returning information for job: 20200220114543558194
2020-02-20 11:46:00,363 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (147.952248096s left)
2020-02-20 11:46:13,684 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220114613674979
2020-02-20 11:46:13,709 [salt.minion      :1432][INFO    ][6925] Starting a new job with PID 6925
2020-02-20 11:46:13,733 [salt.minion      :1711][INFO    ][6925] Returning information for job: 20200220114613674979
2020-02-20 11:46:32,458 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (115.857722044s left)
2020-02-20 11:46:43,853 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220114643843675
2020-02-20 11:46:43,880 [salt.minion      :1432][INFO    ][6947] Starting a new job with PID 6947
2020-02-20 11:46:43,907 [salt.minion      :1711][INFO    ][6947] Returning information for job: 20200220114643843675
2020-02-20 11:47:04,694 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (83.6212561131s left)
2020-02-20 11:47:14,004 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220114713989503
2020-02-20 11:47:14,027 [salt.minion      :1432][INFO    ][6983] Starting a new job with PID 6983
2020-02-20 11:47:14,057 [salt.minion      :1711][INFO    ][6983] Returning information for job: 20200220114713989503
2020-02-20 11:47:37,022 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (51.2939901352s left)
2020-02-20 11:47:44,179 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220114744169405
2020-02-20 11:47:44,200 [salt.minion      :1432][INFO    ][7005] Starting a new job with PID 7005
2020-02-20 11:47:44,227 [salt.minion      :1711][INFO    ][7005] Returning information for job: 20200220114744169405
2020-02-20 11:48:09,131 [salt.loaded.ext.module.maas:1024][INFO    ][4487] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (19.1848371029s left)
2020-02-20 11:48:14,374 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220114814361117
2020-02-20 11:48:14,403 [salt.minion      :1432][INFO    ][7038] Starting a new job with PID 7038
2020-02-20 11:48:14,429 [salt.minion      :1711][INFO    ][7038] Returning information for job: 20200220114814361117
2020-02-20 11:48:41,281 [salt.state       :302 ][ERROR   ][4487] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['cmp002', 'cmp001']not in Deployed state
2020-02-20 11:48:41,282 [salt.state       :1951][INFO    ][4487] Completed state [maas.wait_for_machine_status] at time 11:48:41.282064 duration_in_ms=2262979.503
2020-02-20 11:48:41,288 [salt.minion      :1711][INFO    ][4487] Returning information for job: 20200220111049948958
2020-02-20 11:48:52,589 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command pillar.get with jid 20200220114852577104
2020-02-20 11:48:52,614 [salt.minion      :1432][INFO    ][7067] Starting a new job with PID 7067
2020-02-20 11:48:52,623 [salt.minion      :1711][INFO    ][7067] Returning information for job: 20200220114852577104
2020-02-20 11:48:53,579 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command service.status with jid 20200220114853566702
2020-02-20 11:48:53,608 [salt.minion      :1432][INFO    ][7072] Starting a new job with PID 7072
2020-02-20 11:48:54,404 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][7072] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2020-02-20 11:48:54,441 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][7072] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2020-02-20 11:48:54,462 [salt.minion      :1711][INFO    ][7072] Returning information for job: 20200220114853566702
2020-02-20 11:48:55,474 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command state.apply with jid 20200220114855467761
2020-02-20 11:48:55,492 [salt.minion      :1432][INFO    ][7097] Starting a new job with PID 7097
2020-02-20 11:49:01,748 [salt.state       :915 ][INFO    ][7097] Loading fresh modules for state activity
2020-02-20 11:49:02,380 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7097] Executing command 'salt-minion --version' in directory '/root'
2020-02-20 11:49:02,779 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7097] Executing command 'salt-minion --version' in directory '/root'
2020-02-20 11:49:03,886 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7097] Executing command 'salt-minion --version' in directory '/root'
2020-02-20 11:49:04,290 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7097] Executing command 'salt-minion --version' in directory '/root'
2020-02-20 11:49:06,674 [salt.state       :1780][INFO    ][7097] Running state [salt-minion] at time 11:49:06.674110
2020-02-20 11:49:06,674 [salt.state       :1813][INFO    ][7097] Executing state pkg.installed for [salt-minion]
2020-02-20 11:49:06,675 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7097] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-20 11:49:06,798 [salt.state       :300 ][INFO    ][7097] All specified packages are already installed
2020-02-20 11:49:06,799 [salt.state       :1951][INFO    ][7097] Completed state [salt-minion] at time 11:49:06.798965 duration_in_ms=124.855
2020-02-20 11:49:06,799 [salt.state       :1780][INFO    ][7097] Running state [salt_minion_dependency_packages] at time 11:49:06.799319
2020-02-20 11:49:06,799 [salt.state       :1813][INFO    ][7097] Executing state pkg.installed for [salt_minion_dependency_packages]
2020-02-20 11:49:06,810 [salt.state       :300 ][INFO    ][7097] All specified packages are already installed
2020-02-20 11:49:06,810 [salt.state       :1951][INFO    ][7097] Completed state [salt_minion_dependency_packages] at time 11:49:06.810461 duration_in_ms=11.142
2020-02-20 11:49:06,814 [salt.state       :1780][INFO    ][7097] Running state [/etc/salt/minion.d/minion.conf] at time 11:49:06.814361
2020-02-20 11:49:06,814 [salt.state       :1813][INFO    ][7097] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2020-02-20 11:49:07,105 [salt.state       :300 ][INFO    ][7097] File /etc/salt/minion.d/minion.conf is in the correct state
2020-02-20 11:49:07,105 [salt.state       :1951][INFO    ][7097] Completed state [/etc/salt/minion.d/minion.conf] at time 11:49:07.105895 duration_in_ms=291.534
2020-02-20 11:49:07,110 [salt.state       :1780][INFO    ][7097] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 11:49:07.109933
2020-02-20 11:49:07,110 [salt.state       :1813][INFO    ][7097] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2020-02-20 11:49:07,125 [salt.state       :300 ][INFO    ][7097] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2020-02-20 11:49:07,126 [salt.state       :1951][INFO    ][7097] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 11:49:07.126405 duration_in_ms=16.471
2020-02-20 11:49:07,127 [salt.state       :1780][INFO    ][7097] Running state [salt-minion] at time 11:49:07.127793
2020-02-20 11:49:07,128 [salt.state       :1813][INFO    ][7097] Executing state service.running for [salt-minion]
2020-02-20 11:49:07,130 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7097] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2020-02-20 11:49:07,180 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7097] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2020-02-20 11:49:07,204 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7097] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2020-02-20 11:49:07,230 [salt.state       :300 ][INFO    ][7097] The service salt-minion is already running
2020-02-20 11:49:07,231 [salt.state       :1951][INFO    ][7097] Completed state [salt-minion] at time 11:49:07.231246 duration_in_ms=103.452
2020-02-20 11:49:07,234 [salt.state       :1780][INFO    ][7097] Running state [/etc/salt/grains.d] at time 11:49:07.234036
2020-02-20 11:49:07,234 [salt.state       :1813][INFO    ][7097] Executing state file.directory for [/etc/salt/grains.d]
2020-02-20 11:49:07,238 [salt.state       :300 ][INFO    ][7097] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2020-02-20 11:49:07,238 [salt.state       :1951][INFO    ][7097] Completed state [/etc/salt/grains.d] at time 11:49:07.238779 duration_in_ms=4.744
2020-02-20 11:49:07,239 [salt.state       :1780][INFO    ][7097] Running state [/etc/salt/grains] at time 11:49:07.239660
2020-02-20 11:49:07,240 [salt.state       :1813][INFO    ][7097] Executing state file.managed for [/etc/salt/grains]
2020-02-20 11:49:07,240 [salt.state       :300 ][INFO    ][7097] File /etc/salt/grains exists with proper permissions. No changes made.
2020-02-20 11:49:07,241 [salt.state       :1951][INFO    ][7097] Completed state [/etc/salt/grains] at time 11:49:07.241128 duration_in_ms=1.468
2020-02-20 11:49:07,241 [salt.state       :1780][INFO    ][7097] Running state [/etc/salt/grains.d/placeholder] at time 11:49:07.241755
2020-02-20 11:49:07,242 [salt.state       :1813][INFO    ][7097] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2020-02-20 11:49:07,242 [salt.state       :300 ][INFO    ][7097] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2020-02-20 11:49:07,243 [salt.state       :1951][INFO    ][7097] Completed state [/etc/salt/grains.d/placeholder] at time 11:49:07.243187 duration_in_ms=1.433
2020-02-20 11:49:07,243 [salt.state       :1780][INFO    ][7097] Running state [/etc/salt/grains.d/sphinx] at time 11:49:07.243834
2020-02-20 11:49:07,244 [salt.state       :1813][INFO    ][7097] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2020-02-20 11:49:07,246 [salt.state       :300 ][INFO    ][7097] File /etc/salt/grains.d/sphinx is in the correct state
2020-02-20 11:49:07,246 [salt.state       :1951][INFO    ][7097] Completed state [/etc/salt/grains.d/sphinx] at time 11:49:07.246584 duration_in_ms=2.75
2020-02-20 11:49:07,251 [salt.state       :1780][INFO    ][7097] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 11:49:07.251182
2020-02-20 11:49:07,251 [salt.state       :1813][INFO    ][7097] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2020-02-20 11:49:07,251 [salt.state       :300 ][INFO    ][7097] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2020-02-20 11:49:07,252 [salt.state       :1951][INFO    ][7097] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 11:49:07.252025 duration_in_ms=0.843
2020-02-20 11:49:07,252 [salt.state       :1780][INFO    ][7097] Running state [/etc/salt/grains.d/dns_records] at time 11:49:07.252672
2020-02-20 11:49:07,252 [salt.state       :1813][INFO    ][7097] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2020-02-20 11:49:07,253 [salt.state       :300 ][INFO    ][7097] File /etc/salt/grains.d/dns_records is in the correct state
2020-02-20 11:49:07,254 [salt.state       :1951][INFO    ][7097] Completed state [/etc/salt/grains.d/dns_records] at time 11:49:07.254172 duration_in_ms=1.5
2020-02-20 11:49:07,255 [salt.state       :1780][INFO    ][7097] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 11:49:07.255077
2020-02-20 11:49:07,255 [salt.state       :1813][INFO    ][7097] 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-20 11:49:07,255 [salt.state       :300 ][INFO    ][7097] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2020-02-20 11:49:07,255 [salt.state       :1951][INFO    ][7097] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 11:49:07.255892 duration_in_ms=0.815
2020-02-20 11:49:07,256 [salt.state       :1780][INFO    ][7097] Running state [/etc/salt/grains.d/salt] at time 11:49:07.256400
2020-02-20 11:49:07,256 [salt.state       :1813][INFO    ][7097] Executing state file.managed for [/etc/salt/grains.d/salt]
2020-02-20 11:49:07,257 [salt.state       :300 ][INFO    ][7097] File /etc/salt/grains.d/salt is in the correct state
2020-02-20 11:49:07,257 [salt.state       :1951][INFO    ][7097] Completed state [/etc/salt/grains.d/salt] at time 11:49:07.257908 duration_in_ms=1.508
2020-02-20 11:49:07,258 [salt.state       :1780][INFO    ][7097] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 11:49:07.258797
2020-02-20 11:49:07,259 [salt.state       :1813][INFO    ][7097] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2020-02-20 11:49:07,259 [salt.state       :300 ][INFO    ][7097] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2020-02-20 11:49:07,259 [salt.state       :1951][INFO    ][7097] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 11:49:07.259664 duration_in_ms=0.867
2020-02-20 11:49:07,262 [salt.state       :1780][INFO    ][7097] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 11:49:07.262517
2020-02-20 11:49:07,262 [salt.state       :1813][INFO    ][7097] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2020-02-20 11:49:07,263 [salt.state       :300 ][INFO    ][7097] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2020-02-20 11:49:07,263 [salt.state       :1951][INFO    ][7097] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 11:49:07.263393 duration_in_ms=0.877
2020-02-20 11:49:07,264 [salt.state       :1780][INFO    ][7097] Running state [mine.update] at time 11:49:07.264082
2020-02-20 11:49:07,265 [salt.state       :1813][INFO    ][7097] Executing state module.wait for [mine.update]
2020-02-20 11:49:07,265 [salt.state       :300 ][INFO    ][7097] No changes made for mine.update
2020-02-20 11:49:07,265 [salt.state       :1951][INFO    ][7097] Completed state [mine.update] at time 11:49:07.265790 duration_in_ms=1.707
2020-02-20 11:49:07,266 [salt.state       :1780][INFO    ][7097] Running state [ca-certificates] at time 11:49:07.266095
2020-02-20 11:49:07,266 [salt.state       :1813][INFO    ][7097] Executing state pkg.installed for [ca-certificates]
2020-02-20 11:49:07,277 [salt.state       :300 ][INFO    ][7097] All specified packages are already installed
2020-02-20 11:49:07,278 [salt.state       :1951][INFO    ][7097] Completed state [ca-certificates] at time 11:49:07.277978 duration_in_ms=11.883
2020-02-20 11:49:07,279 [salt.state       :1780][INFO    ][7097] Running state [update-ca-certificates] at time 11:49:07.278933
2020-02-20 11:49:07,279 [salt.state       :1813][INFO    ][7097] Executing state cmd.wait for [update-ca-certificates]
2020-02-20 11:49:07,279 [salt.state       :300 ][INFO    ][7097] No changes made for update-ca-certificates
2020-02-20 11:49:07,279 [salt.state       :1951][INFO    ][7097] Completed state [update-ca-certificates] at time 11:49:07.279790 duration_in_ms=0.857
2020-02-20 11:49:07,280 [salt.state       :1780][INFO    ][7097] Running state [iptables] at time 11:49:07.280098
2020-02-20 11:49:07,280 [salt.state       :1813][INFO    ][7097] Executing state pkg.installed for [iptables]
2020-02-20 11:49:07,290 [salt.state       :300 ][INFO    ][7097] All specified packages are already installed
2020-02-20 11:49:07,290 [salt.state       :1951][INFO    ][7097] Completed state [iptables] at time 11:49:07.290610 duration_in_ms=10.512
2020-02-20 11:49:07,290 [salt.state       :1780][INFO    ][7097] Running state [iptables-persistent] at time 11:49:07.290879
2020-02-20 11:49:07,291 [salt.state       :1813][INFO    ][7097] Executing state pkg.installed for [iptables-persistent]
2020-02-20 11:49:07,300 [salt.state       :300 ][INFO    ][7097] All specified packages are already installed
2020-02-20 11:49:07,300 [salt.state       :1951][INFO    ][7097] Completed state [iptables-persistent] at time 11:49:07.300911 duration_in_ms=10.032
2020-02-20 11:49:07,301 [salt.state       :1780][INFO    ][7097] Running state [iptables_modules_v4_load] at time 11:49:07.301890
2020-02-20 11:49:07,302 [salt.state       :1813][INFO    ][7097] Executing state kmod.present for [iptables_modules_v4_load]
2020-02-20 11:49:07,302 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7097] Executing command 'lsmod' in directory '/root'
2020-02-20 11:49:07,326 [salt.state       :300 ][INFO    ][7097] Kernel modules iptable_filter, ip_tables are already present
2020-02-20 11:49:07,326 [salt.state       :1951][INFO    ][7097] Completed state [iptables_modules_v4_load] at time 11:49:07.326796 duration_in_ms=24.905
2020-02-20 11:49:07,329 [salt.state       :1780][INFO    ][7097] Running state [/etc/iptables/rules.v4] at time 11:49:07.328148
2020-02-20 11:49:07,329 [salt.state       :1813][INFO    ][7097] Executing state file.managed for [/etc/iptables/rules.v4]
2020-02-20 11:49:07,443 [salt.state       :300 ][INFO    ][7097] File /etc/iptables/rules.v4 is in the correct state
2020-02-20 11:49:07,443 [salt.state       :1951][INFO    ][7097] Completed state [/etc/iptables/rules.v4] at time 11:49:07.443344 duration_in_ms=115.197
2020-02-20 11:49:07,445 [salt.state       :1780][INFO    ][7097] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 11:49:07.445616
2020-02-20 11:49:07,445 [salt.state       :1813][INFO    ][7097] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2020-02-20 11:49:07,446 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7097] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2020-02-20 11:49:07,467 [salt.state       :300 ][INFO    ][7097] onlyif execution failed
2020-02-20 11:49:07,467 [salt.state       :1951][INFO    ][7097] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 11:49:07.467779 duration_in_ms=22.162
2020-02-20 11:49:07,471 [salt.state       :1780][INFO    ][7097] Running state [netfilter-persistent] at time 11:49:07.470961
2020-02-20 11:49:07,471 [salt.state       :1813][INFO    ][7097] Executing state service.running for [netfilter-persistent]
2020-02-20 11:49:07,473 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7097] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2020-02-20 11:49:07,499 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7097] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2020-02-20 11:49:07,527 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7097] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2020-02-20 11:49:07,556 [salt.state       :300 ][INFO    ][7097] The service netfilter-persistent is already running
2020-02-20 11:49:07,557 [salt.state       :1951][INFO    ][7097] Completed state [netfilter-persistent] at time 11:49:07.556987 duration_in_ms=86.026
2020-02-20 11:49:07,558 [salt.state       :1780][INFO    ][7097] Running state [iptables_extra.remove_stale_tables] at time 11:49:07.558537
2020-02-20 11:49:07,559 [salt.state       :1813][INFO    ][7097] Executing state module.wait for [iptables_extra.remove_stale_tables]
2020-02-20 11:49:07,559 [salt.state       :300 ][INFO    ][7097] No changes made for iptables_extra.remove_stale_tables
2020-02-20 11:49:07,560 [salt.state       :1951][INFO    ][7097] Completed state [iptables_extra.remove_stale_tables] at time 11:49:07.560078 duration_in_ms=1.542
2020-02-20 11:49:07,562 [salt.state       :1780][INFO    ][7097] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 11:49:07.562552
2020-02-20 11:49:07,562 [salt.state       :1813][INFO    ][7097] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2020-02-20 11:49:07,563 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7097] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2020-02-20 11:49:07,582 [salt.state       :300 ][INFO    ][7097] onlyif execution failed
2020-02-20 11:49:07,583 [salt.state       :1951][INFO    ][7097] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 11:49:07.583198 duration_in_ms=20.647
2020-02-20 11:49:07,586 [salt.state       :1780][INFO    ][7097] Running state [/etc/iptables/rules.v6] at time 11:49:07.586819
2020-02-20 11:49:07,587 [salt.state       :1813][INFO    ][7097] Executing state file.absent for [/etc/iptables/rules.v6]
2020-02-20 11:49:07,588 [salt.state       :300 ][INFO    ][7097] File /etc/iptables/rules.v6 is not present
2020-02-20 11:49:07,588 [salt.state       :1951][INFO    ][7097] Completed state [/etc/iptables/rules.v6] at time 11:49:07.588685 duration_in_ms=1.866
2020-02-20 11:49:07,589 [salt.state       :1780][INFO    ][7097] Running state [iptables_extra.flush_all] at time 11:49:07.589760
2020-02-20 11:49:07,590 [salt.state       :1813][INFO    ][7097] Executing state module.wait for [iptables_extra.flush_all]
2020-02-20 11:49:07,590 [salt.state       :300 ][INFO    ][7097] No changes made for iptables_extra.flush_all
2020-02-20 11:49:07,591 [salt.state       :1951][INFO    ][7097] Completed state [iptables_extra.flush_all] at time 11:49:07.590937 duration_in_ms=1.178
2020-02-20 11:49:07,595 [salt.minion      :1711][INFO    ][7097] Returning information for job: 20200220114855467761
2020-02-20 11:49:08,575 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command state.apply with jid 20200220114908563303
2020-02-20 11:49:08,600 [salt.minion      :1432][INFO    ][7179] Starting a new job with PID 7179
2020-02-20 11:49:09,566 [salt.state       :915 ][INFO    ][7179] Loading fresh modules for state activity
2020-02-20 11:49:10,875 [salt.state       :1780][INFO    ][7179] Running state [maas-rack-controller] at time 11:49:10.875074
2020-02-20 11:49:10,875 [salt.state       :1813][INFO    ][7179] Executing state pkg.installed for [maas-rack-controller]
2020-02-20 11:49:10,876 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7179] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-20 11:49:11,001 [salt.state       :300 ][INFO    ][7179] All specified packages are already installed
2020-02-20 11:49:11,001 [salt.state       :1951][INFO    ][7179] Completed state [maas-rack-controller] at time 11:49:11.001764 duration_in_ms=126.691
2020-02-20 11:49:11,002 [salt.state       :1780][INFO    ][7179] Running state [ipmitool] at time 11:49:11.002147
2020-02-20 11:49:11,002 [salt.state       :1813][INFO    ][7179] Executing state pkg.installed for [ipmitool]
2020-02-20 11:49:11,013 [salt.state       :300 ][INFO    ][7179] All specified packages are already installed
2020-02-20 11:49:11,013 [salt.state       :1951][INFO    ][7179] Completed state [ipmitool] at time 11:49:11.013230 duration_in_ms=11.083
2020-02-20 11:49:11,018 [salt.state       :1780][INFO    ][7179] Running state [/etc/maas/rackd.conf] at time 11:49:11.018570
2020-02-20 11:49:11,018 [salt.state       :1813][INFO    ][7179] Executing state file.line for [/etc/maas/rackd.conf]
2020-02-20 11:49:11,019 [salt.state       :300 ][INFO    ][7179] No changes needed to be made
2020-02-20 11:49:11,020 [salt.state       :1951][INFO    ][7179] Completed state [/etc/maas/rackd.conf] at time 11:49:11.020076 duration_in_ms=1.507
2020-02-20 11:49:11,020 [salt.state       :1780][INFO    ][7179] Running state [/etc/maas/rackd.conf] at time 11:49:11.020364
2020-02-20 11:49:11,020 [salt.state       :1813][INFO    ][7179] Executing state file.managed for [/etc/maas/rackd.conf]
2020-02-20 11:49:11,020 [salt.loaded.int.states.file:2298][WARNING ][7179] 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-20 11:49:11,021 [salt.state       :300 ][INFO    ][7179] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2020-02-20 11:49:11,021 [salt.state       :1951][INFO    ][7179] Completed state [/etc/maas/rackd.conf] at time 11:49:11.021770 duration_in_ms=1.406
2020-02-20 11:49:11,022 [salt.state       :1780][INFO    ][7179] Running state [maas-rackd] at time 11:49:11.022927
2020-02-20 11:49:11,023 [salt.state       :1813][INFO    ][7179] Executing state service.running for [maas-rackd]
2020-02-20 11:49:11,024 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7179] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2020-02-20 11:49:11,064 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7179] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2020-02-20 11:49:11,091 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7179] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2020-02-20 11:49:11,116 [salt.state       :300 ][INFO    ][7179] The service maas-rackd is already running
2020-02-20 11:49:11,117 [salt.state       :1951][INFO    ][7179] Completed state [maas-rackd] at time 11:49:11.117370 duration_in_ms=94.442
2020-02-20 11:49:11,120 [salt.minion      :1711][INFO    ][7179] Returning information for job: 20200220114908563303
2020-02-20 11:49:12,102 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command state.apply with jid 20200220114912090304
2020-02-20 11:49:12,130 [salt.minion      :1432][INFO    ][7202] Starting a new job with PID 7202
2020-02-20 11:49:13,078 [salt.state       :915 ][INFO    ][7202] Loading fresh modules for state activity
2020-02-20 11:49:14,531 [salt.state       :1780][INFO    ][7202] Running state [maas-region-controller] at time 11:49:14.531303
2020-02-20 11:49:14,531 [salt.state       :1813][INFO    ][7202] Executing state pkg.installed for [maas-region-controller]
2020-02-20 11:49:14,532 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7202] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-20 11:49:14,644 [salt.state       :300 ][INFO    ][7202] All specified packages are already installed
2020-02-20 11:49:14,644 [salt.state       :1951][INFO    ][7202] Completed state [maas-region-controller] at time 11:49:14.644743 duration_in_ms=113.441
2020-02-20 11:49:14,645 [salt.state       :1780][INFO    ][7202] Running state [python-oauth] at time 11:49:14.645135
2020-02-20 11:49:14,645 [salt.state       :1813][INFO    ][7202] Executing state pkg.installed for [python-oauth]
2020-02-20 11:49:14,657 [salt.state       :300 ][INFO    ][7202] All specified packages are already installed
2020-02-20 11:49:14,657 [salt.state       :1951][INFO    ][7202] Completed state [python-oauth] at time 11:49:14.657401 duration_in_ms=12.266
2020-02-20 11:49:14,661 [salt.state       :1780][INFO    ][7202] Running state [/etc/maas/regiond.conf] at time 11:49:14.661086
2020-02-20 11:49:14,661 [salt.state       :1813][INFO    ][7202] Executing state file.replace for [/etc/maas/regiond.conf]
2020-02-20 11:49:14,668 [salt.state       :300 ][INFO    ][7202] No changes needed to be made
2020-02-20 11:49:14,668 [salt.state       :1951][INFO    ][7202] Completed state [/etc/maas/regiond.conf] at time 11:49:14.668280 duration_in_ms=7.194
2020-02-20 11:49:14,668 [salt.state       :1780][INFO    ][7202] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 11:49:14.668793
2020-02-20 11:49:14,669 [salt.state       :1813][INFO    ][7202] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2020-02-20 11:49:14,732 [salt.state       :300 ][INFO    ][7202] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2020-02-20 11:49:14,733 [salt.state       :1951][INFO    ][7202] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 11:49:14.733124 duration_in_ms=64.33
2020-02-20 11:49:14,733 [salt.state       :1780][INFO    ][7202] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:49:14.733683
2020-02-20 11:49:14,734 [salt.state       :1813][INFO    ][7202] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-02-20 11:49:14,739 [salt.state       :300 ][INFO    ][7202] No changes needed to be made
2020-02-20 11:49:14,739 [salt.state       :1951][INFO    ][7202] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:49:14.739390 duration_in_ms=5.707
2020-02-20 11:49:14,739 [salt.state       :1780][INFO    ][7202] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:49:14.739896
2020-02-20 11:49:14,740 [salt.state       :1813][INFO    ][7202] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-02-20 11:49:14,744 [salt.state       :300 ][INFO    ][7202] No changes needed to be made
2020-02-20 11:49:14,745 [salt.state       :1951][INFO    ][7202] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:49:14.744980 duration_in_ms=5.084
2020-02-20 11:49:14,745 [salt.state       :1780][INFO    ][7202] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 11:49:14.745485
2020-02-20 11:49:14,745 [salt.state       :1813][INFO    ][7202] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2020-02-20 11:49:14,775 [salt.state       :300 ][INFO    ][7202] No changes needed to be made
2020-02-20 11:49:14,775 [salt.state       :1951][INFO    ][7202] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 11:49:14.775680 duration_in_ms=30.195
2020-02-20 11:49:14,776 [salt.state       :1780][INFO    ][7202] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 11:49:14.776494
2020-02-20 11:49:14,776 [salt.state       :1813][INFO    ][7202] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2020-02-20 11:49:14,795 [salt.state       :300 ][INFO    ][7202] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2020-02-20 11:49:14,799 [salt.state       :1951][INFO    ][7202] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 11:49:14.796142 duration_in_ms=19.648
2020-02-20 11:49:14,800 [salt.state       :1780][INFO    ][7202] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 11:49:14.800029
2020-02-20 11:49:14,801 [salt.state       :1813][INFO    ][7202] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2020-02-20 11:49:14,863 [salt.state       :300 ][INFO    ][7202] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2020-02-20 11:49:14,864 [salt.state       :1951][INFO    ][7202] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 11:49:14.864129 duration_in_ms=64.102
2020-02-20 11:49:14,865 [salt.state       :1780][INFO    ][7202] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 11:49:14.865198
2020-02-20 11:49:14,865 [salt.state       :1813][INFO    ][7202] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2020-02-20 11:49:14,926 [salt.state       :300 ][INFO    ][7202] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2020-02-20 11:49:14,927 [salt.state       :1951][INFO    ][7202] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 11:49:14.927237 duration_in_ms=62.04
2020-02-20 11:49:14,927 [salt.state       :1780][INFO    ][7202] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 11:49:14.927886
2020-02-20 11:49:14,929 [salt.state       :1813][INFO    ][7202] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2020-02-20 11:49:14,998 [salt.state       :300 ][INFO    ][7202] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2020-02-20 11:49:14,998 [salt.state       :1951][INFO    ][7202] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 11:49:14.998834 duration_in_ms=70.948
2020-02-20 11:49:14,999 [salt.state       :1780][INFO    ][7202] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 11:49:14.999451
2020-02-20 11:49:14,999 [salt.state       :1813][INFO    ][7202] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic]
2020-02-20 11:49:15,059 [salt.state       :300 ][INFO    ][7202] File /etc/maas/preseeds/curtin_userdata_amd64_generic_bionic is in the correct state
2020-02-20 11:49:15,060 [salt.state       :1951][INFO    ][7202] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 11:49:15.059989 duration_in_ms=60.537
2020-02-20 11:49:15,061 [salt.state       :1780][INFO    ][7202] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 11:49:15.061482
2020-02-20 11:49:15,061 [salt.state       :1813][INFO    ][7202] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic]
2020-02-20 11:49:15,130 [salt.state       :300 ][INFO    ][7202] File /etc/maas/preseeds/curtin_userdata_arm64_generic_bionic is in the correct state
2020-02-20 11:49:15,131 [salt.state       :1951][INFO    ][7202] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 11:49:15.131148 duration_in_ms=69.665
2020-02-20 11:49:15,131 [salt.state       :1780][INFO    ][7202] Running state [/root/.pgpass] at time 11:49:15.131519
2020-02-20 11:49:15,131 [salt.state       :1813][INFO    ][7202] Executing state file.managed for [/root/.pgpass]
2020-02-20 11:49:15,179 [salt.state       :300 ][INFO    ][7202] File /root/.pgpass is in the correct state
2020-02-20 11:49:15,179 [salt.state       :1951][INFO    ][7202] Completed state [/root/.pgpass] at time 11:49:15.179529 duration_in_ms=48.01
2020-02-20 11:49:15,194 [salt.state       :1780][INFO    ][7202] Running state [maas-region migrate --noinput] at time 11:49:15.194862
2020-02-20 11:49:15,195 [salt.state       :1813][INFO    ][7202] Executing state cmd.run for [maas-region migrate --noinput]
2020-02-20 11:49:15,197 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7202] Executing command 'maas-region migrate --noinput' in directory '/root'
2020-02-20 11:49:18,218 [salt.state       :300 ][INFO    ][7202] {'pid': 7233, '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-20 11:49:18,219 [salt.state       :1951][INFO    ][7202] Completed state [maas-region migrate --noinput] at time 11:49:18.218854 duration_in_ms=3024.0
2020-02-20 11:49:18,219 [salt.state       :2022][WARNING ][7202] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-02-20 11:49:18,223 [salt.state       :1780][INFO    ][7202] Running state [maas-regiond] at time 11:49:18.223350
2020-02-20 11:49:18,224 [salt.state       :1813][INFO    ][7202] Executing state service.running for [maas-regiond]
2020-02-20 11:49:18,226 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7202] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2020-02-20 11:49:18,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7202] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2020-02-20 11:49:18,314 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7202] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2020-02-20 11:49:18,342 [salt.state       :300 ][INFO    ][7202] The service maas-regiond is already running
2020-02-20 11:49:18,343 [salt.state       :1951][INFO    ][7202] Completed state [maas-regiond] at time 11:49:18.343205 duration_in_ms=119.854
2020-02-20 11:49:18,346 [salt.state       :1780][INFO    ][7202] Running state [bind9] at time 11:49:18.346613
2020-02-20 11:49:18,347 [salt.state       :1813][INFO    ][7202] Executing state service.running for [bind9]
2020-02-20 11:49:18,351 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7202] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2020-02-20 11:49:18,379 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7202] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2020-02-20 11:49:18,407 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7202] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2020-02-20 11:49:18,435 [salt.state       :300 ][INFO    ][7202] The service bind9 is already running
2020-02-20 11:49:18,436 [salt.state       :1951][INFO    ][7202] Completed state [bind9] at time 11:49:18.435895 duration_in_ms=89.282
2020-02-20 11:49:18,439 [salt.state       :1780][INFO    ][7202] Running state [maasng.wait_for_http_code] at time 11:49:18.439193
2020-02-20 11:49:18,439 [salt.state       :1813][INFO    ][7202] Executing state module.run for [maasng.wait_for_http_code]
2020-02-20 11:49:18,440 [salt.utils.decorators:613 ][WARNING ][7202] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-20 11:49:18,451 [salt.state       :300 ][INFO    ][7202] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2020-02-20 11:49:18,451 [salt.state       :1951][INFO    ][7202] Completed state [maasng.wait_for_http_code] at time 11:49:18.451641 duration_in_ms=12.449
2020-02-20 11:49:18,453 [salt.state       :1780][INFO    ][7202] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 11:49:18.453076
2020-02-20 11:49:18,453 [salt.state       :1813][INFO    ][7202] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2020-02-20 11:49:18,453 [salt.state       :300 ][INFO    ][7202] /var/lib/maas/.setup_admin exists
2020-02-20 11:49:18,454 [salt.state       :1951][INFO    ][7202] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 11:49:18.454051 duration_in_ms=0.975
2020-02-20 11:49:18,454 [salt.state       :1780][INFO    ][7202] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:49:18.454748
2020-02-20 11:49:18,455 [salt.state       :1813][INFO    ][7202] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-20 11:49:18,455 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7202] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-20 11:49:20,781 [salt.state       :300 ][INFO    ][7202] {'pid': 7253, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-20 11:49:20,782 [salt.state       :1951][INFO    ][7202] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:49:20.782247 duration_in_ms=2327.498
2020-02-20 11:49:20,793 [salt.state       :1780][INFO    ][7202] Running state [maas_region_boot_source_resources_mirror] at time 11:49:20.793465
2020-02-20 11:49:20,794 [salt.state       :1813][INFO    ][7202] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2020-02-20 11:49:20,862 [salt.state       :300 ][INFO    ][7202] {'changes': {}}
2020-02-20 11:49:20,863 [salt.state       :1951][INFO    ][7202] Completed state [maas_region_boot_source_resources_mirror] at time 11:49:20.862914 duration_in_ms=69.45
2020-02-20 11:49:20,864 [salt.state       :1780][INFO    ][7202] Running state [maasng.boot_resources_import] at time 11:49:20.864100
2020-02-20 11:49:20,866 [salt.state       :1813][INFO    ][7202] Executing state module.run for [maasng.boot_resources_import]
2020-02-20 11:49:20,866 [salt.utils.decorators:613 ][WARNING ][7202] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-20 11:49:22,153 [salt.loaded.ext.module.maasng:1600][INFO    ][7202] Waiting boot-resources import done
sleep for:5s Left:899.0/900s
2020-02-20 11:49:27,149 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220114927135405
2020-02-20 11:49:27,178 [salt.minion      :1432][INFO    ][7282] Starting a new job with PID 7282
2020-02-20 11:49:27,204 [salt.minion      :1711][INFO    ][7282] Returning information for job: 20200220114927135405
2020-02-20 11:49:27,220 [salt.state       :300 ][INFO    ][7202] {'ret': True}
2020-02-20 11:49:27,221 [salt.state       :1951][INFO    ][7202] Completed state [maasng.boot_resources_import] at time 11:49:27.221528 duration_in_ms=6357.428
2020-02-20 11:49:27,222 [salt.state       :1780][INFO    ][7202] Running state [maas_region_boot_sources_selection_bionic] at time 11:49:27.222822
2020-02-20 11:49:27,223 [salt.state       :1813][INFO    ][7202] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_bionic]
2020-02-20 11:49:27,338 [salt.state       :300 ][INFO    ][7202] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2020-02-20 11:49:27,339 [salt.state       :1951][INFO    ][7202] Completed state [maas_region_boot_sources_selection_bionic] at time 11:49:27.339063 duration_in_ms=116.24
2020-02-20 11:49:27,340 [salt.state       :1780][INFO    ][7202] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 11:49:27.340866
2020-02-20 11:49:27,341 [salt.state       :1813][INFO    ][7202] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2020-02-20 11:49:27,341 [salt.utils.decorators:613 ][WARNING ][7202] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-20 11:49:27,342 [salt.loaded.ext.module.maasng:1771][INFO    ][7202] boot-sources sync initiated for ALL Rack's
2020-02-20 11:49:28,135 [salt.state       :300 ][INFO    ][7202] {'ret': True}
2020-02-20 11:49:28,135 [salt.state       :1951][INFO    ][7202] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 11:49:28.135623 duration_in_ms=794.754
2020-02-20 11:49:28,138 [salt.state       :1780][INFO    ][7202] Running state [maas.process_maas_config] at time 11:49:28.138708
2020-02-20 11:49:28,139 [salt.state       :1813][INFO    ][7202] Executing state module.run for [maas.process_maas_config]
2020-02-20 11:49:28,140 [salt.utils.decorators:613 ][WARNING ][7202] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-20 11:49:28,141 [salt.loaded.ext.module.maas:92  ][INFO    ][7202] maasconfig name=enable_http_proxy value=True
2020-02-20 11:49:28,181 [salt.loaded.ext.module.maas:92  ][INFO    ][7202] maasconfig name=upstream_dns value=8.8.8.8
2020-02-20 11:49:28,229 [salt.loaded.ext.module.maas:92  ][INFO    ][7202] maasconfig name=commissioning_distro_series value=bionic
2020-02-20 11:49:28,285 [salt.loaded.ext.module.maas:92  ][INFO    ][7202] maasconfig name=default_osystem value=ubuntu
2020-02-20 11:49:28,335 [salt.loaded.ext.module.maas:92  ][INFO    ][7202] maasconfig name=active_discovery_interval value=600
2020-02-20 11:49:28,399 [salt.loaded.ext.module.maas:92  ][INFO    ][7202] maasconfig name=dnssec_validation value=no
2020-02-20 11:49:28,463 [salt.loaded.ext.module.maas:92  ][INFO    ][7202] maasconfig name=kernel_opts value=spectre_v2=off nopti kpti=off nospec_store_bypass_disable noibrs noibpb
2020-02-20 11:49:28,500 [salt.loaded.ext.module.maas:92  ][INFO    ][7202] maasconfig name=network_discovery value=enabled
2020-02-20 11:49:28,593 [salt.loaded.ext.module.maas:92  ][INFO    ][7202] maasconfig name=enable_third_party_drivers value=True
2020-02-20 11:49:29,598 [salt.loaded.ext.module.maas:92  ][INFO    ][7202] maasconfig name=default_storage_layout value=lvm
2020-02-20 11:49:29,645 [salt.loaded.ext.module.maas:92  ][INFO    ][7202] maasconfig name=maas_name value=mas01
2020-02-20 11:49:29,679 [salt.loaded.ext.module.maas:92  ][INFO    ][7202] maasconfig name=disk_erase_with_secure_erase value=False
2020-02-20 11:49:29,917 [salt.loaded.ext.module.maas:92  ][INFO    ][7202] maasconfig name=default_distro_series value=bionic
2020-02-20 11:49:29,960 [salt.loaded.ext.module.maas:92  ][INFO    ][7202] maasconfig name=ntp_external_only value=True
2020-02-20 11:49:29,991 [salt.loaded.ext.module.maas:92  ][INFO    ][7202] maasconfig name=default_min_hwe_kernel value=ga-18.04
2020-02-20 11:49:30,042 [salt.state       :300 ][INFO    ][7202] {'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-20 11:49:30,042 [salt.state       :1951][INFO    ][7202] Completed state [maas.process_maas_config] at time 11:49:30.042425 duration_in_ms=1903.717
2020-02-20 11:49:30,043 [salt.state       :1780][INFO    ][7202] Running state [pxe_admin] at time 11:49:30.043024
2020-02-20 11:49:30,043 [salt.state       :1813][INFO    ][7202] Executing state maasng.fabric_present for [pxe_admin]
2020-02-20 11:49:30,077 [salt.loaded.ext.module.maasng:945 ][INFO    ][7202] [{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/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, 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/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'pxe_admin', u'id': 2}]
2020-02-20 11:49:30,124 [salt.loaded.ext.module.maasng:1008][WARNING ][7202] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2020-02-20 11:49:30,125 [salt.loaded.ext.module.maasng:1011][WARNING ][7202] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2020-02-20 11:49:30,161 [salt.state       :300 ][INFO    ][7202] {'new': 'Fabric  pxe_admin created', 'result': True}
2020-02-20 11:49:30,161 [salt.state       :1951][INFO    ][7202] Completed state [pxe_admin] at time 11:49:30.161820 duration_in_ms=118.795
2020-02-20 11:49:30,162 [salt.state       :1780][INFO    ][7202] Running state [vlan 0] at time 11:49:30.162159
2020-02-20 11:49:30,162 [salt.state       :1813][INFO    ][7202] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-02-20 11:49:30,196 [salt.loaded.ext.module.maasng:945 ][INFO    ][7202] [{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/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': None, 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'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {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': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'pxe_admin'}], u'class_type': None, u'name': u'pxe_admin', u'id': 2}]
2020-02-20 11:49:30,454 [salt.loaded.ext.module.maasng:945 ][INFO    ][7202] [{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/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': None, 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'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {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': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'pxe_admin'}], u'class_type': None, u'name': u'pxe_admin', u'id': 2}]
2020-02-20 11:49:30,633 [salt.loaded.ext.module.maasng:945 ][INFO    ][7202] [{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/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': None, 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'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {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': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'pxe_admin'}], u'class_type': None, u'name': u'pxe_admin', u'id': 2}]
2020-02-20 11:49:30,711 [salt.state       :300 ][INFO    ][7202] {'new': 'Vlan untagged was updated'}
2020-02-20 11:49:30,712 [salt.state       :1951][INFO    ][7202] Completed state [vlan 0] at time 11:49:30.712083 duration_in_ms=549.923
2020-02-20 11:49:30,714 [salt.state       :1780][INFO    ][7202] Running state [192.168.11.0/24] at time 11:49:30.714221
2020-02-20 11:49:30,714 [salt.state       :1813][INFO    ][7202] Executing state maasng.subnet_present for [192.168.11.0/24]
2020-02-20 11:49:30,883 [salt.loaded.ext.module.maasng:945 ][INFO    ][7202] [{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/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': None, 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'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {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': u'w8mwd3', 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'pxe_admin'}], u'class_type': None, u'name': u'pxe_admin', u'id': 2}]
2020-02-20 11:49:30,883 [salt.loaded.ext.module.maasng:1235][WARNING ][7202] Ignoring parameter vlan:0
2020-02-20 11:49:30,927 [salt.state       :300 ][INFO    ][7202] Subnet 192.168.11.0/24 has been updated for pxe_admin
2020-02-20 11:49:30,927 [salt.state       :1951][INFO    ][7202] Completed state [192.168.11.0/24] at time 11:49:30.927477 duration_in_ms=213.255
2020-02-20 11:49:30,930 [salt.state       :1780][INFO    ][7202] Running state [maas_create_iprange_1] at time 11:49:30.930050
2020-02-20 11:49:30,930 [salt.state       :1813][INFO    ][7202] Executing state maasng.iprange_present for [maas_create_iprange_1]
2020-02-20 11:49:30,978 [salt.state       :300 ][INFO    ][7202] Iprange maas_create_iprange_1 already exist.
2020-02-20 11:49:30,979 [salt.state       :1951][INFO    ][7202] Completed state [maas_create_iprange_1] at time 11:49:30.979068 duration_in_ms=49.018
2020-02-20 11:49:30,979 [salt.state       :1780][INFO    ][7202] Running state [vlan 0] at time 11:49:30.979525
2020-02-20 11:49:30,979 [salt.state       :1813][INFO    ][7202] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-02-20 11:49:31,021 [salt.loaded.ext.module.maasng:945 ][INFO    ][7202] [{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/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': None, 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'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {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': u'w8mwd3', 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'pxe_admin'}], u'class_type': None, u'name': u'pxe_admin', u'id': 2}]
2020-02-20 11:49:31,105 [salt.loaded.ext.module.maasng:945 ][INFO    ][7202] [{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/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': None, 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'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {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': u'w8mwd3', 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'pxe_admin'}], u'class_type': None, u'name': u'pxe_admin', u'id': 2}]
2020-02-20 11:49:31,313 [salt.loaded.ext.module.maasng:945 ][INFO    ][7202] [{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/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, 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/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'pxe_admin', u'id': 2}]
2020-02-20 11:49:31,388 [salt.state       :300 ][INFO    ][7202] {'new': 'Vlan untagged was updated'}
2020-02-20 11:49:31,388 [salt.state       :1951][INFO    ][7202] Completed state [vlan 0] at time 11:49:31.388691 duration_in_ms=409.165
2020-02-20 11:49:31,389 [salt.state       :1780][INFO    ][7202] Running state [opnfv] at time 11:49:31.389767
2020-02-20 11:49:31,390 [salt.state       :1813][INFO    ][7202] Executing state maasng.sshkey_present for [opnfv]
2020-02-20 11:49:31,432 [salt.loaded.ext.module.maasng:1903][INFO    ][7202] [{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-20 11:49:31,433 [salt.state       :300 ][INFO    ][7202] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2020-02-20 11:49:31,433 [salt.state       :1951][INFO    ][7202] Completed state [opnfv] at time 11:49:31.433517 duration_in_ms=43.75
2020-02-20 11:49:31,434 [salt.state       :1780][INFO    ][7202] Running state [maas.process_tags] at time 11:49:31.434436
2020-02-20 11:49:31,434 [salt.state       :1813][INFO    ][7202] Executing state module.run for [maas.process_tags]
2020-02-20 11:49:31,435 [salt.utils.decorators:613 ][WARNING ][7202] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-20 11:49:31,462 [salt.loaded.ext.module.maas:92  ][INFO    ][7202] 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-20 11:49:31,499 [salt.state       :300 ][INFO    ][7202] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2020-02-20 11:49:31,499 [salt.state       :1951][INFO    ][7202] Completed state [maas.process_tags] at time 11:49:31.499760 duration_in_ms=65.325
2020-02-20 11:49:31,503 [salt.minion      :1711][INFO    ][7202] Returning information for job: 20200220114912090304
2020-02-20 11:49:32,557 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command state.apply with jid 20200220114932546695
2020-02-20 11:49:32,579 [salt.minion      :1432][INFO    ][7329] Starting a new job with PID 7329
2020-02-20 11:49:38,950 [salt.state       :915 ][INFO    ][7329] Loading fresh modules for state activity
2020-02-20 11:49:39,058 [salt.state       :1780][INFO    ][7329] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:49:39.058634
2020-02-20 11:49:39,059 [salt.state       :1813][INFO    ][7329] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-20 11:49:39,061 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7329] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-20 11:49:41,356 [salt.state       :300 ][INFO    ][7329] {'pid': 7337, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-20 11:49:41,357 [salt.state       :1951][INFO    ][7329] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:49:41.357312 duration_in_ms=2298.68
2020-02-20 11:49:41,360 [salt.state       :1780][INFO    ][7329] Running state [maas.process_machines] at time 11:49:41.360061
2020-02-20 11:49:41,360 [salt.state       :1813][INFO    ][7329] Executing state module.run for [maas.process_machines]
2020-02-20 11:49:41,361 [salt.utils.decorators:613 ][WARNING ][7329] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-20 11:49:41,881 [salt.loaded.ext.module.maas:412 ][WARNING ][7329] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-20 11:49:41,882 [salt.loaded.ext.module.maas:92  ][INFO    ][7329] 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=t76e6s architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-20 11:49:42,628 [salt.loaded.ext.module.maas:412 ][WARNING ][7329] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-20 11:49:42,628 [salt.loaded.ext.module.maas:92  ][INFO    ][7329] 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=dkbhtc architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-20 11:49:43,331 [salt.loaded.ext.module.maas:412 ][WARNING ][7329] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-20 11:49:43,333 [salt.loaded.ext.module.maas:92  ][INFO    ][7329] 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=sstn8r architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-20 11:49:44,026 [salt.loaded.ext.module.maas:412 ][WARNING ][7329] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-20 11:49:44,027 [salt.loaded.ext.module.maas:92  ][INFO    ][7329] 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=hgwgdh architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-20 11:49:44,758 [salt.state       :300 ][INFO    ][7329] {'ret': {'updated': ['gtw01', 'cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2020-02-20 11:49:44,759 [salt.state       :1951][INFO    ][7329] Completed state [maas.process_machines] at time 11:49:44.759129 duration_in_ms=3399.067
2020-02-20 11:49:44,763 [salt.minion      :1711][INFO    ][7329] Returning information for job: 20200220114932546695
2020-02-20 11:50:18,550 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command state.apply with jid 20200220115018534912
2020-02-20 11:50:18,574 [salt.minion      :1432][INFO    ][7382] Starting a new job with PID 7382
2020-02-20 11:50:24,826 [salt.state       :915 ][INFO    ][7382] Loading fresh modules for state activity
2020-02-20 11:50:24,945 [salt.state       :1780][INFO    ][7382] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:50:24.945613
2020-02-20 11:50:24,946 [salt.state       :1813][INFO    ][7382] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-20 11:50:24,947 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7382] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-20 11:50:27,245 [salt.state       :300 ][INFO    ][7382] {'pid': 7396, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-20 11:50:27,246 [salt.state       :1951][INFO    ][7382] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:50:27.246296 duration_in_ms=2300.683
2020-02-20 11:50:27,250 [salt.state       :1780][INFO    ][7382] Running state [maas.wait_for_machine_status] at time 11:50:27.250581
2020-02-20 11:50:27,251 [salt.state       :1813][INFO    ][7382] Executing state module.run for [maas.wait_for_machine_status]
2020-02-20 11:50:27,252 [salt.utils.decorators:613 ][WARNING ][7382] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-20 11:50:29,426 [salt.state       :300 ][INFO    ][7382] {'ret': True}
2020-02-20 11:50:29,427 [salt.state       :1951][INFO    ][7382] Completed state [maas.wait_for_machine_status] at time 11:50:29.427269 duration_in_ms=2176.688
2020-02-20 11:50:29,431 [salt.minion      :1711][INFO    ][7382] Returning information for job: 20200220115018534912
2020-02-20 11:50:30,370 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command state.apply with jid 20200220115030358128
2020-02-20 11:50:30,398 [salt.minion      :1432][INFO    ][7407] Starting a new job with PID 7407
2020-02-20 11:50:31,275 [salt.state       :915 ][INFO    ][7407] Loading fresh modules for state activity
2020-02-20 11:50:31,451 [salt.state       :1780][INFO    ][7407] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:50:31.451329
2020-02-20 11:50:31,451 [salt.state       :1813][INFO    ][7407] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-20 11:50:31,455 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7407] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-20 11:50:33,758 [salt.state       :300 ][INFO    ][7407] {'pid': 7414, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-20 11:50:33,759 [salt.state       :1951][INFO    ][7407] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:50:33.759420 duration_in_ms=2308.098
2020-02-20 11:50:33,762 [salt.state       :1780][INFO    ][7407] Running state [maas_machines_storage_cmp002_lvm] at time 11:50:33.762551
2020-02-20 11:50:33,763 [salt.state       :1813][INFO    ][7407] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2020-02-20 11:50:34,841 [salt.loaded.ext.module.maasng:610 ][INFO    ][7407] dkbhtc
2020-02-20 11:50:34,842 [salt.loaded.ext.module.maasng:626 ][INFO    ][7407] sda
2020-02-20 11:50:35,374 [salt.loaded.ext.module.maasng:361 ][INFO    ][7407] dkbhtc
2020-02-20 11:50:35,484 [salt.loaded.ext.module.maasng:367 ][INFO    ][7407] [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'b3389851-4610-4509-8f25-1965b9aea026', u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dkbhtc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'8a018571-814d-4d79-bb3b-cc89f18d2ffc', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'dkbhtc', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/11/', u'uuid': u'7219fe15-b791-4ede-8f8c-257f23bf1f73', 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'247b4be8-9c9b-4b80-a4a8-d017e5869b8e', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dkbhtc', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 11, u'size': 800097042432}]
2020-02-20 11:50:35,486 [salt.loaded.ext.module.maasng:632 ][INFO    ][7407] vgroot
2020-02-20 11:50:35,486 [salt.loaded.ext.module.maasng:635 ][INFO    ][7407] lvroot
2020-02-20 11:50:35,487 [salt.loaded.ext.module.maasng:639 ][INFO    ][7407] 107374182400
2020-02-20 11:50:36,101 [salt.loaded.ext.module.maasng:645 ][INFO    ][7407] {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'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': None, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/1/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'dkbhtc', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'da600ac0-f419-4d79-984a-a1fa33c19f75', u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/1/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dkbhtc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0c6c5345-f95a-4fe2-81f3-665b391db764', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 9, u'size': 800101236736}]}, u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/1/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'da600ac0-f419-4d79-984a-a1fa33c19f75', u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/1/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dkbhtc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0c6c5345-f95a-4fe2-81f3-665b391db764', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 9, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'dkbhtc', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/13/', u'available_size': 0, u'uuid': u'd075957d-ef21-4dd4-a4ea-1f4af8f027ee', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dkbhtc', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'34945a27-c71c-49d9-a400-33d2d77de650', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 13, 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/dkbhtc/', 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'dkbhtc', 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'ip_addresses': [], u'min_hwe_kernel': u'ga-18.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'dkbhtc', u'id': 9}], u'special_filesystems': [], u'current_commissioning_result_id': 16, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'id': 52, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'pxe_admin'}, u'enabled': True, u'parents': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'id': 5, u'system_id': u'dkbhtc', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/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'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'effective_mtu': 1500, u'id': 10, u'system_id': u'dkbhtc', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/interfaces/10/'}, {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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'id': 53, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'pxe_admin'}, u'enabled': True, u'parents': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'effective_mtu': 1500, u'id': 11, u'system_id': u'dkbhtc', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/interfaces/11/'}, {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'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'effective_mtu': 1500, u'id': 12, u'system_id': u'dkbhtc', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/interfaces/12/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 54, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'effective_mtu': 1500, u'id': 13, u'system_id': u'dkbhtc', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/interfaces/13/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 55, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'effective_mtu': 1500, u'id': 14, u'system_id': u'dkbhtc', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/interfaces/14/'}], u'current_testing_result_id': 17, 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'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/1/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'dkbhtc', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'da600ac0-f419-4d79-984a-a1fa33c19f75', u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/1/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dkbhtc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0c6c5345-f95a-4fe2-81f3-665b391db764', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 9, u'size': 800101236736}]}], u'netboot': True, u'osystem': u'', u'status_action': u'', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/blockdevices/13/', u'available_size': 0, u'uuid': u'd075957d-ef21-4dd4-a4ea-1f4af8f027ee', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dkbhtc', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'34945a27-c71c-49d9-a400-33d2d77de650', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 13, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', 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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'id': 52, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'pxe_admin'}, u'enabled': True, u'parents': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'id': 5, u'system_id': u'dkbhtc', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dkbhtc/interfaces/5/'}, 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-20 11:50:36,105 [salt.state       :300 ][INFO    ][7407] {'new': {'storage_layout': 'lvm'}}
2020-02-20 11:50:36,105 [salt.state       :1951][INFO    ][7407] Completed state [maas_machines_storage_cmp002_lvm] at time 11:50:36.105674 duration_in_ms=2343.12
2020-02-20 11:50:36,106 [salt.state       :1780][INFO    ][7407] Running state [maas_machines_storage_cmp001_lvm] at time 11:50:36.106547
2020-02-20 11:50:36,107 [salt.state       :1813][INFO    ][7407] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2020-02-20 11:50:37,297 [salt.loaded.ext.module.maasng:610 ][INFO    ][7407] sstn8r
2020-02-20 11:50:37,298 [salt.loaded.ext.module.maasng:626 ][INFO    ][7407] sda
2020-02-20 11:50:37,791 [salt.loaded.ext.module.maasng:361 ][INFO    ][7407] sstn8r
2020-02-20 11:50:37,879 [salt.loaded.ext.module.maasng:367 ][INFO    ][7407] [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/2/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'298d36d0-8072-43dd-8092-9ebb962d8648', u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/2/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sstn8r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd8e50153-bd47-4973-86fa-32dbdbc00c99', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 8, u'device_id': 2}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'sstn8r', 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'used_size': 800106479616, u'id': 2, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/12/', u'available_size': 0, u'uuid': u'ea3735a2-5907-4f87-8b99-1db8c91d7d0b', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sstn8r', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'a9942ced-c30f-4961-ac8a-238a55c74ada', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 12, u'size': 800097042432}]
2020-02-20 11:50:37,880 [salt.loaded.ext.module.maasng:632 ][INFO    ][7407] vgroot
2020-02-20 11:50:37,881 [salt.loaded.ext.module.maasng:635 ][INFO    ][7407] lvroot
2020-02-20 11:50:37,881 [salt.loaded.ext.module.maasng:639 ][INFO    ][7407] 107374182400
2020-02-20 11:50:38,494 [salt.loaded.ext.module.maasng:645 ][INFO    ][7407] {u'node_type_name': u'Machine', 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'swap_size': None, 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'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'cpu_speed': 2800, u'owner': None, u'pod': None, 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/sstn8r/', u'current_commissioning_result_id': 18, 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'testing_status': 2, u'system_id': u'sstn8r', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'uuid': u'e8ce4a99-5193-4e62-92c3-f1aeda1b1359', 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'80c804e8-9d00-4a89-bb4e-442051430a41', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/14/', u'system_id': u'sstn8r', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 14, u'name': u'vgroot-lvroot'}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'6d0e99e9-c5fb-4c07-ab6a-e2db9285a557', u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/2/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sstn8r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'5f05d649-450f-4e61-ae6f-be727e56731d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 10, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'sstn8r', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/14/', u'uuid': u'e8ce4a99-5193-4e62-92c3-f1aeda1b1359', 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'80c804e8-9d00-4a89-bb4e-442051430a41', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sstn8r', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 14, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'6d0e99e9-c5fb-4c07-ab6a-e2db9285a557', u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/2/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sstn8r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'5f05d649-450f-4e61-ae6f-be727e56731d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 10, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/2/', u'system_id': u'sstn8r', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'firmware_version': u'5.22', u'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'sstn8r', u'id': 10}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'pxe_admin'}, 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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'id': 59, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 6, u'discovered': [], u'system_id': u'sstn8r', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/interfaces/6/'}, u'current_testing_result_id': 19, 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'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, 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'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'pxe_admin'}, 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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'id': 59, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 6, u'discovered': [], u'system_id': u'sstn8r', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/interfaces/6/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': None, u'vendor': u'HP', u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 15, u'discovered': None, u'system_id': u'sstn8r', u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/interfaces/15/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'pxe_admin'}, 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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8mwd3', 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'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': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'id': 60, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 16, u'discovered': [], u'system_id': u'sstn8r', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/interfaces/16/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': None, u'vendor': u'HP', u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 17, u'discovered': None, u'system_id': u'sstn8r', u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/interfaces/17/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', 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'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 61, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 18, u'discovered': None, u'system_id': u'sstn8r', u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/interfaces/18/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', 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'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 62, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 19, u'discovered': None, u'system_id': u'sstn8r', u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/interfaces/19/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'6d0e99e9-c5fb-4c07-ab6a-e2db9285a557', u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/2/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sstn8r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'5f05d649-450f-4e61-ae6f-be727e56731d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 10, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/sstn8r/blockdevices/2/', u'system_id': u'sstn8r', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'firmware_version': u'5.22', u'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}}
2020-02-20 11:50:38,497 [salt.state       :300 ][INFO    ][7407] {'new': {'storage_layout': 'lvm'}}
2020-02-20 11:50:38,498 [salt.state       :1951][INFO    ][7407] Completed state [maas_machines_storage_cmp001_lvm] at time 11:50:38.498029 duration_in_ms=2391.482
2020-02-20 11:50:38,501 [salt.minion      :1711][INFO    ][7407] Returning information for job: 20200220115030358128
2020-02-20 11:50:39,511 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command state.apply with jid 20200220115039495713
2020-02-20 11:50:39,537 [salt.minion      :1432][INFO    ][7428] Starting a new job with PID 7428
2020-02-20 11:50:40,453 [salt.state       :915 ][INFO    ][7428] Loading fresh modules for state activity
2020-02-20 11:50:40,562 [salt.state       :1780][INFO    ][7428] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:50:40.562686
2020-02-20 11:50:40,563 [salt.state       :1813][INFO    ][7428] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-20 11:50:40,564 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7428] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-20 11:50:42,752 [salt.state       :300 ][INFO    ][7428] {'pid': 7435, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-20 11:50:42,754 [salt.state       :1951][INFO    ][7428] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:50:42.753843 duration_in_ms=2191.156
2020-02-20 11:50:42,757 [salt.state       :1780][INFO    ][7428] Running state [maas.deploy_machines] at time 11:50:42.757377
2020-02-20 11:50:42,757 [salt.state       :1813][INFO    ][7428] Executing state module.run for [maas.deploy_machines]
2020-02-20 11:50:42,758 [salt.utils.decorators:613 ][WARNING ][7428] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-20 11:50:43,273 [salt.loaded.ext.module.maas:684 ][INFO    ][7428] deploymachines hwe_kernel=ga-18.04 system_id=dkbhtc distro_series=bionic
2020-02-20 11:50:45,270 [salt.loaded.ext.module.maas:684 ][INFO    ][7428] deploymachines hwe_kernel=ga-18.04 system_id=sstn8r distro_series=bionic
2020-02-20 11:50:47,160 [salt.state       :300 ][INFO    ][7428] {'ret': {'updated': ['gtw01', 'ctl01'], 'errors': {}, 'success': ['cmp002', 'cmp001']}}
2020-02-20 11:50:47,163 [salt.state       :1951][INFO    ][7428] Completed state [maas.deploy_machines] at time 11:50:47.163183 duration_in_ms=4405.806
2020-02-20 11:50:47,166 [salt.minion      :1711][INFO    ][7428] Returning information for job: 20200220115039495713
2020-02-20 11:50:48,146 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command state.apply with jid 20200220115048129688
2020-02-20 11:50:48,168 [salt.minion      :1432][INFO    ][7460] Starting a new job with PID 7460
2020-02-20 11:50:54,714 [salt.state       :915 ][INFO    ][7460] Loading fresh modules for state activity
2020-02-20 11:50:54,823 [salt.state       :1780][INFO    ][7460] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:50:54.823838
2020-02-20 11:50:54,824 [salt.state       :1813][INFO    ][7460] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-20 11:50:54,826 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7460] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-20 11:50:57,136 [salt.state       :300 ][INFO    ][7460] {'pid': 7471, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-20 11:50:57,137 [salt.state       :1951][INFO    ][7460] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:50:57.137309 duration_in_ms=2313.47
2020-02-20 11:50:57,141 [salt.state       :1780][INFO    ][7460] Running state [maas.wait_for_machine_status] at time 11:50:57.141611
2020-02-20 11:50:57,142 [salt.state       :1813][INFO    ][7460] Executing state module.run for [maas.wait_for_machine_status]
2020-02-20 11:50:57,143 [salt.utils.decorators:613 ][WARNING ][7460] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-20 11:50:59,183 [salt.loaded.ext.module.maas:1024][INFO    ][7460] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2247.977211s left)
2020-02-20 11:51:03,239 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220115103230031
2020-02-20 11:51:03,260 [salt.minion      :1432][INFO    ][7501] Starting a new job with PID 7501
2020-02-20 11:51:03,293 [salt.minion      :1711][INFO    ][7501] Returning information for job: 20200220115103230031
2020-02-20 11:51:31,311 [salt.loaded.ext.module.maas:1024][INFO    ][7460] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2215.84840703s left)
2020-02-20 11:51:33,478 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220115133424117
2020-02-20 11:51:33,503 [salt.minion      :1432][INFO    ][7524] Starting a new job with PID 7524
2020-02-20 11:51:33,537 [salt.minion      :1711][INFO    ][7524] Returning information for job: 20200220115133424117
2020-02-20 11:52:03,391 [salt.loaded.ext.module.maas:1024][INFO    ][7460] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2183.76839304s left)
2020-02-20 11:52:03,555 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220115203542404
2020-02-20 11:52:03,586 [salt.minion      :1432][INFO    ][7571] Starting a new job with PID 7571
2020-02-20 11:52:03,616 [salt.minion      :1711][INFO    ][7571] Returning information for job: 20200220115203542404
2020-02-20 11:52:33,638 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220115233622537
2020-02-20 11:52:33,665 [salt.minion      :1432][INFO    ][7595] Starting a new job with PID 7595
2020-02-20 11:52:33,692 [salt.minion      :1711][INFO    ][7595] Returning information for job: 20200220115233622537
2020-02-20 11:52:35,610 [salt.loaded.ext.module.maas:1024][INFO    ][7460] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2151.54983592s left)
2020-02-20 11:53:03,723 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220115303711179
2020-02-20 11:53:03,751 [salt.minion      :1432][INFO    ][7629] Starting a new job with PID 7629
2020-02-20 11:53:03,789 [salt.minion      :1711][INFO    ][7629] Returning information for job: 20200220115303711179
2020-02-20 11:53:07,688 [salt.loaded.ext.module.maas:1024][INFO    ][7460] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2119.47144103s left)
2020-02-20 11:53:33,816 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220115333801710
2020-02-20 11:53:33,848 [salt.minion      :1432][INFO    ][7659] Starting a new job with PID 7659
2020-02-20 11:53:33,878 [salt.minion      :1711][INFO    ][7659] Returning information for job: 20200220115333801710
2020-02-20 11:53:39,872 [salt.loaded.ext.module.maas:1024][INFO    ][7460] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2087.28755403s left)
2020-02-20 11:54:03,904 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220115403893842
2020-02-20 11:54:03,930 [salt.minion      :1432][INFO    ][7723] Starting a new job with PID 7723
2020-02-20 11:54:03,962 [salt.minion      :1711][INFO    ][7723] Returning information for job: 20200220115403893842
2020-02-20 11:54:12,245 [salt.loaded.ext.module.maas:1024][INFO    ][7460] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2054.91500902s left)
2020-02-20 11:54:34,013 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220115433989816
2020-02-20 11:54:34,044 [salt.minion      :1432][INFO    ][7769] Starting a new job with PID 7769
2020-02-20 11:54:34,073 [salt.minion      :1711][INFO    ][7769] Returning information for job: 20200220115433989816
2020-02-20 11:54:44,564 [salt.loaded.ext.module.maas:1024][INFO    ][7460] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2022.59557605s left)
2020-02-20 11:55:04,107 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220115504093264
2020-02-20 11:55:04,137 [salt.minion      :1432][INFO    ][7804] Starting a new job with PID 7804
2020-02-20 11:55:04,166 [salt.minion      :1711][INFO    ][7804] Returning information for job: 20200220115504093264
2020-02-20 11:55:17,031 [salt.loaded.ext.module.maas:1024][INFO    ][7460] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1990.12910008s left)
2020-02-20 11:55:34,205 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220115534183591
2020-02-20 11:55:34,230 [salt.minion      :1432][INFO    ][7825] Starting a new job with PID 7825
2020-02-20 11:55:34,256 [salt.minion      :1711][INFO    ][7825] Returning information for job: 20200220115534183591
2020-02-20 11:55:49,611 [salt.loaded.ext.module.maas:1024][INFO    ][7460] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1957.54927087s left)
2020-02-20 11:56:04,317 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220115604301934
2020-02-20 11:56:04,348 [salt.minion      :1432][INFO    ][7859] Starting a new job with PID 7859
2020-02-20 11:56:04,374 [salt.minion      :1711][INFO    ][7859] Returning information for job: 20200220115604301934
2020-02-20 11:56:21,960 [salt.loaded.ext.module.maas:1024][INFO    ][7460] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1925.20030403s left)
2020-02-20 11:56:34,415 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220115634402369
2020-02-20 11:56:34,446 [salt.minion      :1432][INFO    ][7879] Starting a new job with PID 7879
2020-02-20 11:56:34,480 [salt.minion      :1711][INFO    ][7879] Returning information for job: 20200220115634402369
2020-02-20 11:56:54,279 [salt.loaded.ext.module.maas:1024][INFO    ][7460] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1892.88091588s left)
2020-02-20 11:57:04,534 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220115704520121
2020-02-20 11:57:04,563 [salt.minion      :1432][INFO    ][7913] Starting a new job with PID 7913
2020-02-20 11:57:04,590 [salt.minion      :1711][INFO    ][7913] Returning information for job: 20200220115704520121
2020-02-20 11:57:26,767 [salt.loaded.ext.module.maas:1024][INFO    ][7460] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1860.39317298s left)
2020-02-20 11:57:34,637 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220115734626776
2020-02-20 11:57:34,662 [salt.minion      :1432][INFO    ][7933] Starting a new job with PID 7933
2020-02-20 11:57:34,687 [salt.minion      :1711][INFO    ][7933] Returning information for job: 20200220115734626776
2020-02-20 11:57:59,171 [salt.loaded.ext.module.maas:1024][INFO    ][7460] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1827.98882699s left)
2020-02-20 11:58:04,750 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220115804735431
2020-02-20 11:58:04,780 [salt.minion      :1432][INFO    ][7966] Starting a new job with PID 7966
2020-02-20 11:58:04,806 [salt.minion      :1711][INFO    ][7966] Returning information for job: 20200220115804735431
2020-02-20 11:58:31,445 [salt.loaded.ext.module.maas:1024][INFO    ][7460] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1795.71464705s left)
2020-02-20 11:58:34,874 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220115834863534
2020-02-20 11:58:34,902 [salt.minion      :1432][INFO    ][7985] Starting a new job with PID 7985
2020-02-20 11:58:34,933 [salt.minion      :1711][INFO    ][7985] Returning information for job: 20200220115834863534
2020-02-20 11:59:03,682 [salt.loaded.ext.module.maas:1024][INFO    ][7460] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1763.47790003s left)
2020-02-20 11:59:05,002 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220115904987002
2020-02-20 11:59:05,026 [salt.minion      :1432][INFO    ][8018] Starting a new job with PID 8018
2020-02-20 11:59:05,057 [salt.minion      :1711][INFO    ][8018] Returning information for job: 20200220115904987002
2020-02-20 11:59:35,130 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220115935119844
2020-02-20 11:59:35,154 [salt.minion      :1432][INFO    ][8038] Starting a new job with PID 8038
2020-02-20 11:59:35,178 [salt.minion      :1711][INFO    ][8038] Returning information for job: 20200220115935119844
2020-02-20 11:59:36,170 [salt.loaded.ext.module.maas:1024][INFO    ][7460] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1730.98988485s left)
2020-02-20 12:00:05,270 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220120005253571
2020-02-20 12:00:05,296 [salt.minion      :1432][INFO    ][8074] Starting a new job with PID 8074
2020-02-20 12:00:05,326 [salt.minion      :1711][INFO    ][8074] Returning information for job: 20200220120005253571
2020-02-20 12:00:08,525 [salt.loaded.ext.module.maas:1024][INFO    ][7460] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1698.63476491s left)
2020-02-20 12:00:35,415 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220120035401508
2020-02-20 12:00:35,442 [salt.minion      :1432][INFO    ][8098] Starting a new job with PID 8098
2020-02-20 12:00:35,468 [salt.minion      :1711][INFO    ][8098] Returning information for job: 20200220120035401508
2020-02-20 12:00:40,898 [salt.loaded.ext.module.maas:1024][INFO    ][7460] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1666.26157093s left)
2020-02-20 12:01:05,561 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220120105546588
2020-02-20 12:01:05,584 [salt.minion      :1432][INFO    ][8132] Starting a new job with PID 8132
2020-02-20 12:01:05,613 [salt.minion      :1711][INFO    ][8132] Returning information for job: 20200220120105546588
2020-02-20 12:01:13,233 [salt.loaded.ext.module.maas:1024][INFO    ][7460] Waiting status:Deployed for machines:['cmp001']
sleep for:30s Timeout:2250s (1633.92707205s left)
2020-02-20 12:01:35,726 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command saltutil.find_job with jid 20200220120135714461
2020-02-20 12:01:35,747 [salt.minion      :1432][INFO    ][8153] Starting a new job with PID 8153
2020-02-20 12:01:35,775 [salt.minion      :1711][INFO    ][8153] Returning information for job: 20200220120135714461
2020-02-20 12:01:45,507 [salt.state       :300 ][INFO    ][7460] {'ret': True}
2020-02-20 12:01:45,507 [salt.state       :1951][INFO    ][7460] Completed state [maas.wait_for_machine_status] at time 12:01:45.507308 duration_in_ms=648365.697
2020-02-20 12:01:45,510 [salt.minion      :1711][INFO    ][7460] Returning information for job: 20200220115048129688
2020-02-20 12:03:05,475 [salt.utils.schedule:1377][INFO    ][3733] Running scheduled job: __mine_interval
2020-02-20 12:51:25,241 [salt.minion      :1308][INFO    ][3733] User sudo_ubuntu Executing command cp.push_dir with jid 20200220125125229172
2020-02-20 12:51:25,258 [salt.minion      :1432][INFO    ][9874] Starting a new job with PID 9874
