2020-01-31 08:45:02,997 [salt.utils.decorators:613 ][WARNING ][1516] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-31 08:45:03,877 [salt.utils.decorators:613 ][WARNING ][1516] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-31 08:45:13,073 [salt.loaded.int.states.file:2298][WARNING ][1684] 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-01-31 08:45:20,275 [salt.state       :2022][WARNING ][1891] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-01-31 08:45:21,011 [salt.utils.decorators:613 ][WARNING ][1891] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-31 08:45:37,634 [salt.utils.decorators:613 ][WARNING ][1891] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-31 08:46:03,249 [salt.utils.decorators:613 ][WARNING ][1891] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-31 08:46:03,973 [salt.utils.decorators:613 ][WARNING ][1891] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-31 08:46:04,755 [salt.loaded.ext.module.maasng:1008][WARNING ][1891] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2020-01-31 08:46:04,755 [salt.loaded.ext.module.maasng:1011][WARNING ][1891] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2020-01-31 08:46:06,046 [salt.loaded.ext.module.maasng:1235][WARNING ][1891] Ignoring parameter vlan:0
2020-01-31 08:46:17,075 [salt.utils.decorators:613 ][WARNING ][3265] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-31 08:46:17,137 [salt.loaded.ext.module.maas:412 ][WARNING ][3265] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-31 08:46:17,916 [salt.loaded.ext.module.maas:412 ][WARNING ][3265] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-31 08:46:18,682 [salt.loaded.ext.module.maas:412 ][WARNING ][3265] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-31 08:46:19,454 [salt.loaded.ext.module.maas:412 ][WARNING ][3265] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-31 08:46:20,296 [salt.loaded.ext.module.maas:412 ][WARNING ][3265] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-31 08:46:24,228 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3567] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2020-01-31 08:46:24,266 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3567] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2020-01-31 08:46:24,320 [salt.utils.parsers:1051][WARNING ][154] Minion received a SIGTERM. Exiting.
2020-01-31 08:46:25,415 [salt.cli.daemons :293 ][INFO    ][3636] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2020-01-31 08:46:25,617 [salt.cli.daemons :82  ][INFO    ][3636] Starting up the Salt Minion
2020-01-31 08:46:25,618 [salt.utils.event :1017][INFO    ][3636] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2020-01-31 08:46:26,853 [salt.minion      :976 ][INFO    ][3636] Creating minion process manager
2020-01-31 08:46:28,710 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3636] Executing command ['date', '+%z'] in directory '/root'
2020-01-31 08:46:28,736 [salt.utils.schedule:568 ][INFO    ][3636] Updating job settings for scheduled job: __mine_interval
2020-01-31 08:46:28,737 [salt.minion      :1108][INFO    ][3636] Added mine.update to scheduler
2020-01-31 08:46:28,742 [salt.minion      :1975][INFO    ][3636] Minion is starting as user 'root'
2020-01-31 08:46:28,763 [salt.minion      :2336][INFO    ][3636] Minion is ready to receive requests!
2020-01-31 08:46:52,724 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command state.apply with jid 20200131084652714909
2020-01-31 08:46:52,739 [salt.minion      :1432][INFO    ][3738] Starting a new job with PID 3738
2020-01-31 08:46:59,202 [salt.state       :915 ][INFO    ][3738] Loading fresh modules for state activity
2020-01-31 08:46:59,263 [salt.fileclient  :1219][INFO    ][3738] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2020-01-31 08:46:59,310 [salt.state       :1780][INFO    ][3738] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:46:59.310514
2020-01-31 08:46:59,310 [salt.state       :1813][INFO    ][3738] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-31 08:46:59,312 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3738] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-31 08:47:01,294 [salt.state       :300 ][INFO    ][3738] {'pid': 3745, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-31 08:47:01,295 [salt.state       :1951][INFO    ][3738] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:47:01.295253 duration_in_ms=1984.739
2020-01-31 08:47:01,298 [salt.state       :1780][INFO    ][3738] Running state [maas.wait_for_machine_status] at time 08:47:01.298883
2020-01-31 08:47:01,299 [salt.state       :1813][INFO    ][3738] Executing state module.run for [maas.wait_for_machine_status]
2020-01-31 08:47:01,300 [salt.utils.decorators:613 ][WARNING ][3738] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-31 08:47:02,312 [salt.loaded.ext.module.maas:1024][INFO    ][3738] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.99839306s left)
2020-01-31 08:47:07,831 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131084707821842
2020-01-31 08:47:07,851 [salt.minion      :1432][INFO    ][3755] Starting a new job with PID 3755
2020-01-31 08:47:07,871 [salt.minion      :1711][INFO    ][3755] Returning information for job: 20200131084707821842
2020-01-31 08:47:33,307 [salt.loaded.ext.module.maas:1024][INFO    ][3738] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.00336409s left)
2020-01-31 08:47:37,887 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131084737872818
2020-01-31 08:47:37,908 [salt.minion      :1432][INFO    ][3787] Starting a new job with PID 3787
2020-01-31 08:47:37,931 [salt.minion      :1711][INFO    ][3787] Returning information for job: 20200131084737872818
2020-01-31 08:48:04,436 [salt.loaded.ext.module.maas:1024][INFO    ][3738] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.87439299s left)
2020-01-31 08:48:07,988 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131084807977654
2020-01-31 08:48:08,005 [salt.minion      :1432][INFO    ][3806] Starting a new job with PID 3806
2020-01-31 08:48:08,025 [salt.minion      :1711][INFO    ][3806] Returning information for job: 20200131084807977654
2020-01-31 08:48:35,384 [salt.loaded.ext.module.maas:1024][INFO    ][3738] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.92618203s left)
2020-01-31 08:48:38,062 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131084838051749
2020-01-31 08:48:38,078 [salt.minion      :1432][INFO    ][3838] Starting a new job with PID 3838
2020-01-31 08:48:38,100 [salt.minion      :1711][INFO    ][3838] Returning information for job: 20200131084838051749
2020-01-31 08:49:06,596 [salt.loaded.ext.module.maas:1024][INFO    ][3738] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.71408796s left)
2020-01-31 08:49:08,147 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131084908132168
2020-01-31 08:49:08,170 [salt.minion      :1432][INFO    ][3869] Starting a new job with PID 3869
2020-01-31 08:49:08,188 [salt.minion      :1711][INFO    ][3869] Returning information for job: 20200131084908132168
2020-01-31 08:49:37,886 [salt.loaded.ext.module.maas:1024][INFO    ][3738] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.42405796s left)
2020-01-31 08:49:38,230 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131084938219200
2020-01-31 08:49:38,248 [salt.minion      :1432][INFO    ][3934] Starting a new job with PID 3934
2020-01-31 08:49:38,270 [salt.minion      :1711][INFO    ][3934] Returning information for job: 20200131084938219200
2020-01-31 08:50:08,339 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131085008321139
2020-01-31 08:50:08,356 [salt.minion      :1432][INFO    ][3967] Starting a new job with PID 3967
2020-01-31 08:50:08,384 [salt.minion      :1711][INFO    ][3967] Returning information for job: 20200131085008321139
2020-01-31 08:50:09,470 [salt.loaded.ext.module.maas:1024][INFO    ][3738] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1311.84085703s left)
2020-01-31 08:50:38,399 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131085038385320
2020-01-31 08:50:38,424 [salt.minion      :1432][INFO    ][4038] Starting a new job with PID 4038
2020-01-31 08:50:38,447 [salt.minion      :1711][INFO    ][4038] Returning information for job: 20200131085038385320
2020-01-31 08:50:41,090 [salt.loaded.ext.module.maas:1024][INFO    ][3738] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1280.22027493s left)
2020-01-31 08:51:08,495 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131085108482256
2020-01-31 08:51:08,511 [salt.minion      :1432][INFO    ][4097] Starting a new job with PID 4097
2020-01-31 08:51:08,535 [salt.minion      :1711][INFO    ][4097] Returning information for job: 20200131085108482256
2020-01-31 08:51:13,104 [salt.loaded.ext.module.maas:1024][INFO    ][3738] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1248.20635605s left)
2020-01-31 08:51:38,584 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131085138574320
2020-01-31 08:51:38,605 [salt.minion      :1432][INFO    ][4152] Starting a new job with PID 4152
2020-01-31 08:51:38,625 [salt.minion      :1711][INFO    ][4152] Returning information for job: 20200131085138574320
2020-01-31 08:51:45,774 [salt.loaded.ext.module.maas:1024][INFO    ][3738] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1215.53648901s left)
2020-01-31 08:52:08,697 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131085208681023
2020-01-31 08:52:08,716 [salt.minion      :1432][INFO    ][4200] Starting a new job with PID 4200
2020-01-31 08:52:08,739 [salt.minion      :1711][INFO    ][4200] Returning information for job: 20200131085208681023
2020-01-31 08:52:18,298 [salt.loaded.ext.module.maas:1024][INFO    ][3738] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1183.01252198s left)
2020-01-31 08:52:38,800 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131085238790208
2020-01-31 08:52:38,824 [salt.minion      :1432][INFO    ][4237] Starting a new job with PID 4237
2020-01-31 08:52:38,851 [salt.minion      :1711][INFO    ][4237] Returning information for job: 20200131085238790208
2020-01-31 08:52:50,785 [salt.loaded.ext.module.maas:1024][INFO    ][3738] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1150.52592397s left)
2020-01-31 08:53:08,931 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131085308909174
2020-01-31 08:53:08,956 [salt.minion      :1432][INFO    ][4300] Starting a new job with PID 4300
2020-01-31 08:53:08,984 [salt.minion      :1711][INFO    ][4300] Returning information for job: 20200131085308909174
2020-01-31 08:53:24,344 [salt.loaded.ext.module.maas:1024][INFO    ][3738] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1116.96687102s left)
2020-01-31 08:53:39,059 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131085339044156
2020-01-31 08:53:39,079 [salt.minion      :1432][INFO    ][4351] Starting a new job with PID 4351
2020-01-31 08:53:39,100 [salt.minion      :1711][INFO    ][4351] Returning information for job: 20200131085339044156
2020-01-31 08:53:57,960 [salt.state       :300 ][INFO    ][3738] {'ret': True}
2020-01-31 08:53:57,961 [salt.state       :1951][INFO    ][3738] Completed state [maas.wait_for_machine_status] at time 08:53:57.961072 duration_in_ms=416662.188
2020-01-31 08:53:57,966 [salt.minion      :1711][INFO    ][3738] Returning information for job: 20200131084652714909
2020-01-31 08:53:59,128 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command state.apply with jid 20200131085359116409
2020-01-31 08:53:59,151 [salt.minion      :1432][INFO    ][4377] Starting a new job with PID 4377
2020-01-31 08:54:05,586 [salt.state       :915 ][INFO    ][4377] Loading fresh modules for state activity
2020-01-31 08:54:05,663 [salt.fileclient  :1219][INFO    ][4377] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2020-01-31 08:54:05,771 [salt.state       :1780][INFO    ][4377] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:54:05.771124
2020-01-31 08:54:05,771 [salt.state       :1813][INFO    ][4377] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-31 08:54:05,775 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4377] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-31 08:54:07,848 [salt.state       :300 ][INFO    ][4377] {'pid': 4388, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-31 08:54:07,849 [salt.state       :1951][INFO    ][4377] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:54:07.848907 duration_in_ms=2077.777
2020-01-31 08:54:07,853 [salt.state       :1780][INFO    ][4377] Running state [maas_machines_storage_cmp002_lvm] at time 08:54:07.853027
2020-01-31 08:54:07,854 [salt.state       :1813][INFO    ][4377] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2020-01-31 08:54:09,196 [salt.loaded.ext.module.maasng:610 ][INFO    ][4377] r3w6n8
2020-01-31 08:54:09,197 [salt.loaded.ext.module.maasng:626 ][INFO    ][4377] sda
2020-01-31 08:54:09,979 [salt.loaded.ext.module.maasng:361 ][INFO    ][4377] r3w6n8
2020-01-31 08:54:10,059 [salt.loaded.ext.module.maasng:367 ][INFO    ][4377] [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'5b504486-5319-4737-ab73-14b2b6872205', u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r3w6n8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'c96badf9-07ab-44e4-b829-e49d529fb1b2', 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'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'r3w6n8', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/3/', u'uuid': u'65bdce91-fa53-4e02-9743-e4660ae5cd24', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'r3w6n8', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'fb6bd34e-6867-40fa-bedb-90c2cf903030', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 3, u'size': 800097042432}]
2020-01-31 08:54:10,060 [salt.loaded.ext.module.maasng:632 ][INFO    ][4377] vgroot
2020-01-31 08:54:10,060 [salt.loaded.ext.module.maasng:635 ][INFO    ][4377] lvroot
2020-01-31 08:54:10,060 [salt.loaded.ext.module.maasng:639 ][INFO    ][4377] 107374182400
2020-01-31 08:54:10,715 [salt.loaded.ext.module.maasng:645 ][INFO    ][4377] {u'hwe_kernel': None, u'testing_status_name': u'Passed', u'boot_interface': {u'children': [], u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 20, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'system_id': u'r3w6n8', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/interfaces/4/'}, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/1/', u'system_id': u'r3w6n8', 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'partitions': [{u'uuid': u'f46bd760-66c7-4276-bd14-05d86a0b1f81', u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r3w6n8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'91b923a6-bce3-4d16-ae6b-f319d0e9eba9', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'type': u'physical', u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, 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/r3w6n8/', u'current_commissioning_result_id': 2, 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'r3w6n8', 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'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'32cdc1dc-bc0a-4e3e-b3ee-dec749556ad2', u'fstype': u'ext4'}, u'uuid': u'6846fc4f-8440-434b-99d0-a5269b5685ef', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/11/', u'system_id': u'r3w6n8', 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'partitions': [], u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'f46bd760-66c7-4276-bd14-05d86a0b1f81', u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r3w6n8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'91b923a6-bce3-4d16-ae6b-f319d0e9eba9', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'uuid': None, u'id': 1, u'system_id': u'r3w6n8', 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'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/11/', u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'32cdc1dc-bc0a-4e3e-b3ee-dec749556ad2', u'fstype': u'ext4'}, u'uuid': u'6846fc4f-8440-434b-99d0-a5269b5685ef', u'id': 11, u'system_id': u'r3w6n8', 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'type': u'virtual', u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'r3w6n8', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'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'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/1/', u'system_id': u'r3w6n8', 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'partitions': [{u'uuid': u'f46bd760-66c7-4276-bd14-05d86a0b1f81', u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r3w6n8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'91b923a6-bce3-4d16-ae6b-f319d0e9eba9', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'type': u'physical', u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'status_action': u'', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'min_hwe_kernel': u'ga-18.04', u'interface_set': [{u'children': [], u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 20, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'system_id': u'r3w6n8', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/interfaces/4/'}, {u'children': [], 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'system_id': u'r3w6n8', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/interfaces/11/'}, {u'children': [], u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'effective_mtu': 1500, u'system_id': u'r3w6n8', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/interfaces/12/'}, {u'children': [], 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'system_id': u'r3w6n8', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/interfaces/13/'}, {u'children': [], u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'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'system_id': u'r3w6n8', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/interfaces/14/'}, {u'children': [], u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 23, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'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'system_id': u'r3w6n8', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/interfaces/15/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2020-01-31 08:54:10,718 [salt.state       :300 ][INFO    ][4377] {'new': {'storage_layout': 'lvm'}}
2020-01-31 08:54:10,718 [salt.state       :1951][INFO    ][4377] Completed state [maas_machines_storage_cmp002_lvm] at time 08:54:10.718418 duration_in_ms=2865.39
2020-01-31 08:54:10,719 [salt.state       :1780][INFO    ][4377] Running state [maas_machines_storage_cmp001_lvm] at time 08:54:10.719041
2020-01-31 08:54:10,719 [salt.state       :1813][INFO    ][4377] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2020-01-31 08:54:12,082 [salt.loaded.ext.module.maasng:610 ][INFO    ][4377] 64gfhp
2020-01-31 08:54:12,082 [salt.loaded.ext.module.maasng:626 ][INFO    ][4377] sda
2020-01-31 08:54:12,736 [salt.loaded.ext.module.maasng:361 ][INFO    ][4377] 64gfhp
2020-01-31 08:54:12,830 [salt.loaded.ext.module.maasng:367 ][INFO    ][4377] [{u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/2/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'c65bc69d-f0cf-4301-9ff2-47f660b89f5b', u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'64gfhp', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a5122c3d-2db9-4623-8ed9-5ab8c59e8ca7', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'device_id': 2}], u'uuid': None, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'64gfhp', 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'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/4/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'c4257c3a-68ba-483a-9c07-074e0d1e6339', u'id': 4, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'64gfhp', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'4a226cde-65de-4883-a0cf-5b3495951302', 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'model': None, u'size': 800097042432}]
2020-01-31 08:54:12,831 [salt.loaded.ext.module.maasng:632 ][INFO    ][4377] vgroot
2020-01-31 08:54:12,831 [salt.loaded.ext.module.maasng:635 ][INFO    ][4377] lvroot
2020-01-31 08:54:12,832 [salt.loaded.ext.module.maasng:639 ][INFO    ][4377] 107374182400
2020-01-31 08:54:13,487 [salt.loaded.ext.module.maasng:645 ][INFO    ][4377] {u'node_type_name': u'Machine', u'hwe_kernel': None, u'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 29, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'64gfhp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/interfaces/5/'}, u'min_hwe_kernel': u'ga-18.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/2/', u'system_id': u'64gfhp', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'firmware_version': u'5.22', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'fffed08a-a639-44e3-9448-4af3dbab6e84', u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'64gfhp', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'6f7509e6-63ce-42dc-afc6-415931729798', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}]}, u'fqdn': u'cmp001.maas', 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/64gfhp/', u'hardware_info': {u'mainboard_firmware_version': u'I31', u'mainboard_product': u'Unknown', u'system_vendor': u'HP', u'cpu_model': u'Intel(R) Xeon(R) CPU E5-2680 v2', u'mainboard_firmware_date': u'06/01/2015', u'mainboard_vendor': u'Unknown', u'system_serial': u'USE410T2XY', u'system_version': u'Unknown', u'system_product': u'ProLiant BL460c Gen8 (735151-B21)'}, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'64gfhp', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'4bbf1dfe-adcb-4cb0-a9df-6c6faef0bb7f', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/12/', u'system_id': u'64gfhp', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'ab69a9fc-b16e-4d9e-a4f9-cf80184aca6f', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'type': u'virtual', u'id': 12, u'partitions': []}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'fffed08a-a639-44e3-9448-4af3dbab6e84', u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'64gfhp', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'6f7509e6-63ce-42dc-afc6-415931729798', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'64gfhp', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/12/', u'uuid': u'4bbf1dfe-adcb-4cb0-a9df-6c6faef0bb7f', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'64gfhp', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'ab69a9fc-b16e-4d9e-a4f9-cf80184aca6f', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'64gfhp', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 29, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'64gfhp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/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'id': 16, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'64gfhp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/interfaces/16/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 30, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 17, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.44'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'64gfhp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/interfaces/17/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 18, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'64gfhp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/interfaces/18/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 31, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 19, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'64gfhp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/interfaces/19/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 32, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 20, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'64gfhp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/interfaces/20/'}], 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'bcaches': [], u'locked': False, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/2/', u'system_id': u'64gfhp', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'firmware_version': u'5.22', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'fffed08a-a639-44e3-9448-4af3dbab6e84', u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'64gfhp', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'6f7509e6-63ce-42dc-afc6-415931729798', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}]}], u'netboot': True, u'osystem': u'', u'status_action': u'', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'architecture': u'amd64/generic', 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-01-31 08:54:13,490 [salt.state       :300 ][INFO    ][4377] {'new': {'storage_layout': 'lvm'}}
2020-01-31 08:54:13,490 [salt.state       :1951][INFO    ][4377] Completed state [maas_machines_storage_cmp001_lvm] at time 08:54:13.490726 duration_in_ms=2771.682
2020-01-31 08:54:13,497 [salt.minion      :1711][INFO    ][4377] Returning information for job: 20200131085359116409
2020-01-31 08:54:14,291 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command state.apply with jid 20200131085414276722
2020-01-31 08:54:14,316 [salt.minion      :1432][INFO    ][4399] Starting a new job with PID 4399
2020-01-31 08:54:15,361 [salt.state       :915 ][INFO    ][4399] Loading fresh modules for state activity
2020-01-31 08:54:15,415 [salt.fileclient  :1219][INFO    ][4399] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2020-01-31 08:54:15,467 [salt.state       :1780][INFO    ][4399] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:54:15.467092
2020-01-31 08:54:15,467 [salt.state       :1813][INFO    ][4399] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-31 08:54:15,469 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4399] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-31 08:54:17,514 [salt.state       :300 ][INFO    ][4399] {'pid': 4413, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-31 08:54:17,516 [salt.state       :1951][INFO    ][4399] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:54:17.515917 duration_in_ms=2048.824
2020-01-31 08:54:17,519 [salt.state       :1780][INFO    ][4399] Running state [maas.deploy_machines] at time 08:54:17.519791
2020-01-31 08:54:17,520 [salt.state       :1813][INFO    ][4399] Executing state module.run for [maas.deploy_machines]
2020-01-31 08:54:17,521 [salt.utils.decorators:613 ][WARNING ][4399] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-31 08:54:18,215 [salt.loaded.ext.module.maas:684 ][INFO    ][4399] deploymachines hwe_kernel=ga-18.04 system_id=r3w6n8 distro_series=bionic
2020-01-31 08:54:20,254 [salt.loaded.ext.module.maas:684 ][INFO    ][4399] deploymachines hwe_kernel=ga-18.04 system_id=64gfhp distro_series=bionic
2020-01-31 08:54:22,296 [salt.loaded.ext.module.maas:684 ][INFO    ][4399] deploymachines hwe_kernel=ga-18.04 system_id=wx33xe distro_series=bionic
2020-01-31 08:54:24,392 [salt.loaded.ext.module.maas:684 ][INFO    ][4399] deploymachines hwe_kernel=ga-18.04 system_id=qyaery distro_series=bionic
2020-01-31 08:54:26,415 [salt.loaded.ext.module.maas:684 ][INFO    ][4399] deploymachines hwe_kernel=ga-18.04 system_id=ydcs6h distro_series=bionic
2020-01-31 08:54:28,443 [salt.state       :300 ][INFO    ][4399] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2020-01-31 08:54:28,444 [salt.state       :1951][INFO    ][4399] Completed state [maas.deploy_machines] at time 08:54:28.443945 duration_in_ms=10924.153
2020-01-31 08:54:28,451 [salt.minion      :1711][INFO    ][4399] Returning information for job: 20200131085414276722
2020-01-31 08:54:29,264 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command state.apply with jid 20200131085429247696
2020-01-31 08:54:29,292 [salt.minion      :1432][INFO    ][4469] Starting a new job with PID 4469
2020-01-31 08:54:35,624 [salt.state       :915 ][INFO    ][4469] Loading fresh modules for state activity
2020-01-31 08:54:35,683 [salt.fileclient  :1219][INFO    ][4469] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2020-01-31 08:54:35,734 [salt.state       :1780][INFO    ][4469] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:54:35.734574
2020-01-31 08:54:35,734 [salt.state       :1813][INFO    ][4469] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-31 08:54:35,736 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4469] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-31 08:54:37,790 [salt.state       :300 ][INFO    ][4469] {'pid': 4482, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-31 08:54:37,791 [salt.state       :1951][INFO    ][4469] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:54:37.791736 duration_in_ms=2057.161
2020-01-31 08:54:37,795 [salt.state       :1780][INFO    ][4469] Running state [maas.wait_for_machine_status] at time 08:54:37.795772
2020-01-31 08:54:37,796 [salt.state       :1813][INFO    ][4469] Executing state module.run for [maas.wait_for_machine_status]
2020-01-31 08:54:37,796 [salt.utils.decorators:613 ][WARNING ][4469] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-31 08:54:41,170 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.64149594s left)
2020-01-31 08:54:44,359 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131085444348764
2020-01-31 08:54:44,383 [salt.minion      :1432][INFO    ][4491] Starting a new job with PID 4491
2020-01-31 08:54:44,403 [salt.minion      :1711][INFO    ][4491] Returning information for job: 20200131085444348764
2020-01-31 08:55:14,416 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131085514407465
2020-01-31 08:55:14,439 [salt.minion      :1432][INFO    ][4510] Starting a new job with PID 4510
2020-01-31 08:55:14,460 [salt.minion      :1711][INFO    ][4510] Returning information for job: 20200131085514407465
2020-01-31 08:55:14,579 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.232759s left)
2020-01-31 08:55:44,495 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131085544487379
2020-01-31 08:55:44,519 [salt.minion      :1432][INFO    ][4560] Starting a new job with PID 4560
2020-01-31 08:55:44,544 [salt.minion      :1711][INFO    ][4560] Returning information for job: 20200131085544487379
2020-01-31 08:55:48,133 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.67884207s left)
2020-01-31 08:56:14,579 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131085614564222
2020-01-31 08:56:14,608 [salt.minion      :1432][INFO    ][4588] Starting a new job with PID 4588
2020-01-31 08:56:14,629 [salt.minion      :1711][INFO    ][4588] Returning information for job: 20200131085614564222
2020-01-31 08:56:21,706 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.10572004s left)
2020-01-31 08:56:44,680 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131085644665517
2020-01-31 08:56:44,704 [salt.minion      :1432][INFO    ][4625] Starting a new job with PID 4625
2020-01-31 08:56:44,725 [salt.minion      :1711][INFO    ][4625] Returning information for job: 20200131085644665517
2020-01-31 08:56:55,398 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.41487288s left)
2020-01-31 08:57:14,766 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131085714749778
2020-01-31 08:57:14,788 [salt.minion      :1432][INFO    ][4655] Starting a new job with PID 4655
2020-01-31 08:57:14,822 [salt.minion      :1711][INFO    ][4655] Returning information for job: 20200131085714749778
2020-01-31 08:57:29,502 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2078.30952692s left)
2020-01-31 08:57:44,895 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131085744880859
2020-01-31 08:57:44,919 [salt.minion      :1432][INFO    ][4721] Starting a new job with PID 4721
2020-01-31 08:57:44,940 [salt.minion      :1711][INFO    ][4721] Returning information for job: 20200131085744880859
2020-01-31 08:58:03,268 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2044.54368901s left)
2020-01-31 08:58:14,990 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131085814979881
2020-01-31 08:58:15,016 [salt.minion      :1432][INFO    ][4767] Starting a new job with PID 4767
2020-01-31 08:58:15,042 [salt.minion      :1711][INFO    ][4767] Returning information for job: 20200131085814979881
2020-01-31 08:58:34,023 [salt.loaded.ext.module.maas:993 ][INFO    ][4469] Machine r3w6n8 mark broken
2020-01-31 08:58:45,093 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131085845080381
2020-01-31 08:58:45,111 [salt.minion      :1432][INFO    ][4834] Starting a new job with PID 4834
2020-01-31 08:58:45,135 [salt.minion      :1711][INFO    ][4834] Returning information for job: 20200131085845080381
2020-01-31 08:59:04,706 [salt.loaded.ext.module.maas:997 ][INFO    ][4469] Machine r3w6n8 mark fixed
2020-01-31 08:59:05,917 [salt.loaded.ext.module.maas:684 ][INFO    ][4469] deploymachines hwe_kernel=ga-18.04 system_id=r3w6n8 distro_series=bionic
2020-01-31 08:59:08,388 [salt.loaded.ext.module.maas:993 ][INFO    ][4469] Machine 64gfhp mark broken
2020-01-31 08:59:15,159 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131085915147882
2020-01-31 08:59:15,182 [salt.minion      :1432][INFO    ][4881] Starting a new job with PID 4881
2020-01-31 08:59:15,201 [salt.minion      :1711][INFO    ][4881] Returning information for job: 20200131085915147882
2020-01-31 08:59:38,987 [salt.loaded.ext.module.maas:997 ][INFO    ][4469] Machine 64gfhp mark fixed
2020-01-31 08:59:40,241 [salt.loaded.ext.module.maas:684 ][INFO    ][4469] deploymachines hwe_kernel=ga-18.04 system_id=64gfhp distro_series=bionic
2020-01-31 08:59:44,493 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1943.31890488s left)
2020-01-31 08:59:45,248 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131085945236286
2020-01-31 08:59:45,268 [salt.minion      :1432][INFO    ][4977] Starting a new job with PID 4977
2020-01-31 08:59:45,287 [salt.minion      :1711][INFO    ][4977] Returning information for job: 20200131085945236286
2020-01-31 09:00:15,348 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131090015334876
2020-01-31 09:00:15,371 [salt.minion      :1432][INFO    ][5001] Starting a new job with PID 5001
2020-01-31 09:00:15,392 [salt.minion      :1711][INFO    ][5001] Returning information for job: 20200131090015334876
2020-01-31 09:00:18,200 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1909.61205792s left)
2020-01-31 09:00:45,464 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131090045446169
2020-01-31 09:00:45,491 [salt.minion      :1432][INFO    ][5035] Starting a new job with PID 5035
2020-01-31 09:00:45,511 [salt.minion      :1711][INFO    ][5035] Returning information for job: 20200131090045446169
2020-01-31 09:00:51,544 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1876.26775098s left)
2020-01-31 09:01:15,559 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131090115544605
2020-01-31 09:01:15,578 [salt.minion      :1432][INFO    ][5060] Starting a new job with PID 5060
2020-01-31 09:01:15,596 [salt.minion      :1711][INFO    ][5060] Returning information for job: 20200131090115544605
2020-01-31 09:01:25,356 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1842.45604897s left)
2020-01-31 09:01:45,663 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131090145652693
2020-01-31 09:01:45,685 [salt.minion      :1432][INFO    ][5114] Starting a new job with PID 5114
2020-01-31 09:01:45,706 [salt.minion      :1711][INFO    ][5114] Returning information for job: 20200131090145652693
2020-01-31 09:01:59,050 [salt.loaded.ext.module.maas:993 ][INFO    ][4469] Machine ydcs6h mark broken
2020-01-31 09:02:15,748 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131090215734603
2020-01-31 09:02:15,770 [salt.minion      :1432][INFO    ][5151] Starting a new job with PID 5151
2020-01-31 09:02:15,803 [salt.minion      :1711][INFO    ][5151] Returning information for job: 20200131090215734603
2020-01-31 09:02:29,742 [salt.loaded.ext.module.maas:997 ][INFO    ][4469] Machine ydcs6h mark fixed
2020-01-31 09:02:31,003 [salt.loaded.ext.module.maas:684 ][INFO    ][4469] deploymachines hwe_kernel=ga-18.04 system_id=ydcs6h distro_series=bionic
2020-01-31 09:02:33,080 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1774.73134089s left)
2020-01-31 09:02:45,907 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131090245896569
2020-01-31 09:02:45,927 [salt.minion      :1432][INFO    ][5232] Starting a new job with PID 5232
2020-01-31 09:02:45,948 [salt.minion      :1711][INFO    ][5232] Returning information for job: 20200131090245896569
2020-01-31 09:03:03,818 [salt.loaded.ext.module.maas:993 ][INFO    ][4469] Machine r3w6n8 mark broken
2020-01-31 09:03:16,012 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131090316005218
2020-01-31 09:03:16,030 [salt.minion      :1432][INFO    ][5272] Starting a new job with PID 5272
2020-01-31 09:03:16,053 [salt.minion      :1711][INFO    ][5272] Returning information for job: 20200131090316005218
2020-01-31 09:03:34,494 [salt.loaded.ext.module.maas:997 ][INFO    ][4469] Machine r3w6n8 mark fixed
2020-01-31 09:03:34,993 [salt.loaded.ext.module.maas:1001][INFO    ][4469] Machine r3w6n8 fio test
2020-01-31 09:03:37,818 [salt.loaded.ext.module.maas:993 ][INFO    ][4469] Machine wx33xe mark broken
2020-01-31 09:03:46,163 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131090346152087
2020-01-31 09:03:46,191 [salt.minion      :1432][INFO    ][5331] Starting a new job with PID 5331
2020-01-31 09:03:46,212 [salt.minion      :1711][INFO    ][5331] Returning information for job: 20200131090346152087
2020-01-31 09:04:08,435 [salt.loaded.ext.module.maas:997 ][INFO    ][4469] Machine wx33xe mark fixed
2020-01-31 09:04:09,586 [salt.loaded.ext.module.maas:684 ][INFO    ][4469] deploymachines hwe_kernel=ga-18.04 system_id=wx33xe distro_series=bionic
2020-01-31 09:04:12,265 [salt.loaded.ext.module.maas:993 ][INFO    ][4469] Machine qyaery mark broken
2020-01-31 09:04:16,330 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131090416319105
2020-01-31 09:04:16,355 [salt.minion      :1432][INFO    ][5364] Starting a new job with PID 5364
2020-01-31 09:04:16,378 [salt.minion      :1711][INFO    ][5364] Returning information for job: 20200131090416319105
2020-01-31 09:04:42,892 [salt.loaded.ext.module.maas:997 ][INFO    ][4469] Machine qyaery mark fixed
2020-01-31 09:04:44,001 [salt.loaded.ext.module.maas:684 ][INFO    ][4469] deploymachines hwe_kernel=ga-18.04 system_id=qyaery distro_series=bionic
2020-01-31 09:04:46,472 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131090446463585
2020-01-31 09:04:46,498 [salt.minion      :1432][INFO    ][5405] Starting a new job with PID 5405
2020-01-31 09:04:46,523 [salt.minion      :1711][INFO    ][5405] Returning information for job: 20200131090446463585
2020-01-31 09:04:46,614 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1641.19791198s left)
2020-01-31 09:05:16,656 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131090516643045
2020-01-31 09:05:16,679 [salt.minion      :1432][INFO    ][5438] Starting a new job with PID 5438
2020-01-31 09:05:16,700 [salt.minion      :1711][INFO    ][5438] Returning information for job: 20200131090516643045
2020-01-31 09:05:17,903 [salt.loaded.ext.module.maas:993 ][INFO    ][4469] Machine 64gfhp mark broken
2020-01-31 09:05:46,812 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131090546796799
2020-01-31 09:05:46,839 [salt.minion      :1432][INFO    ][5489] Starting a new job with PID 5489
2020-01-31 09:05:46,860 [salt.minion      :1711][INFO    ][5489] Returning information for job: 20200131090546796799
2020-01-31 09:05:48,561 [salt.loaded.ext.module.maas:997 ][INFO    ][4469] Machine 64gfhp mark fixed
2020-01-31 09:05:49,042 [salt.loaded.ext.module.maas:1001][INFO    ][4469] Machine 64gfhp fio test
2020-01-31 09:05:52,147 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1575.66495204s left)
2020-01-31 09:06:16,979 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131090616967115
2020-01-31 09:06:17,000 [salt.minion      :1432][INFO    ][5525] Starting a new job with PID 5525
2020-01-31 09:06:17,021 [salt.minion      :1711][INFO    ][5525] Returning information for job: 20200131090616967115
2020-01-31 09:06:25,445 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1542.36724997s left)
2020-01-31 09:06:47,131 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131090647122453
2020-01-31 09:06:47,151 [salt.minion      :1432][INFO    ][5575] Starting a new job with PID 5575
2020-01-31 09:06:47,168 [salt.minion      :1711][INFO    ][5575] Returning information for job: 20200131090647122453
2020-01-31 09:06:58,345 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1509.46700692s left)
2020-01-31 09:07:17,303 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131090717296127
2020-01-31 09:07:17,323 [salt.minion      :1432][INFO    ][5602] Starting a new job with PID 5602
2020-01-31 09:07:17,341 [salt.minion      :1711][INFO    ][5602] Returning information for job: 20200131090717296127
2020-01-31 09:07:31,428 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1476.38406801s left)
2020-01-31 09:07:47,486 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131090747472115
2020-01-31 09:07:47,514 [salt.minion      :1432][INFO    ][5664] Starting a new job with PID 5664
2020-01-31 09:07:47,532 [salt.minion      :1711][INFO    ][5664] Returning information for job: 20200131090747472115
2020-01-31 09:08:04,364 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1443.44791389s left)
2020-01-31 09:08:17,684 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131090817670455
2020-01-31 09:08:17,706 [salt.minion      :1432][INFO    ][5684] Starting a new job with PID 5684
2020-01-31 09:08:17,725 [salt.minion      :1711][INFO    ][5684] Returning information for job: 20200131090817670455
2020-01-31 09:08:37,478 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1410.33353305s left)
2020-01-31 09:08:47,867 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131090847856672
2020-01-31 09:08:47,891 [salt.minion      :1432][INFO    ][5758] Starting a new job with PID 5758
2020-01-31 09:08:47,912 [salt.minion      :1711][INFO    ][5758] Returning information for job: 20200131090847856672
2020-01-31 09:09:10,679 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1377.13218999s left)
2020-01-31 09:09:17,888 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131090917878548
2020-01-31 09:09:17,912 [salt.minion      :1432][INFO    ][5808] Starting a new job with PID 5808
2020-01-31 09:09:17,932 [salt.minion      :1711][INFO    ][5808] Returning information for job: 20200131090917878548
2020-01-31 09:09:43,774 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1344.03721404s left)
2020-01-31 09:09:47,907 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131090947898135
2020-01-31 09:09:47,936 [salt.minion      :1432][INFO    ][5861] Starting a new job with PID 5861
2020-01-31 09:09:47,959 [salt.minion      :1711][INFO    ][5861] Returning information for job: 20200131090947898135
2020-01-31 09:10:17,445 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1310.36704087s left)
2020-01-31 09:10:17,951 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131091017942700
2020-01-31 09:10:17,978 [salt.minion      :1432][INFO    ][5910] Starting a new job with PID 5910
2020-01-31 09:10:17,999 [salt.minion      :1711][INFO    ][5910] Returning information for job: 20200131091017942700
2020-01-31 09:10:47,991 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131091047985171
2020-01-31 09:10:48,009 [salt.minion      :1432][INFO    ][5943] Starting a new job with PID 5943
2020-01-31 09:10:48,027 [salt.minion      :1711][INFO    ][5943] Returning information for job: 20200131091047985171
2020-01-31 09:10:50,271 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1277.54049087s left)
2020-01-31 09:11:18,080 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131091118070935
2020-01-31 09:11:18,100 [salt.minion      :1432][INFO    ][5985] Starting a new job with PID 5985
2020-01-31 09:11:18,123 [salt.minion      :1711][INFO    ][5985] Returning information for job: 20200131091118070935
2020-01-31 09:11:23,616 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1244.19581699s left)
2020-01-31 09:11:48,118 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131091148106829
2020-01-31 09:11:48,144 [salt.minion      :1432][INFO    ][6018] Starting a new job with PID 6018
2020-01-31 09:11:48,165 [salt.minion      :1711][INFO    ][6018] Returning information for job: 20200131091148106829
2020-01-31 09:11:56,985 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1210.82706189s left)
2020-01-31 09:12:18,208 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131091218198502
2020-01-31 09:12:18,237 [salt.minion      :1432][INFO    ][6042] Starting a new job with PID 6042
2020-01-31 09:12:18,265 [salt.minion      :1711][INFO    ][6042] Returning information for job: 20200131091218198502
2020-01-31 09:12:30,504 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1177.30736685s left)
2020-01-31 09:12:48,279 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131091248272161
2020-01-31 09:12:48,302 [salt.minion      :1432][INFO    ][6079] Starting a new job with PID 6079
2020-01-31 09:12:48,323 [salt.minion      :1711][INFO    ][6079] Returning information for job: 20200131091248272161
2020-01-31 09:13:03,999 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1143.81280994s left)
2020-01-31 09:13:18,404 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131091318395262
2020-01-31 09:13:18,419 [salt.minion      :1432][INFO    ][6098] Starting a new job with PID 6098
2020-01-31 09:13:18,445 [salt.minion      :1711][INFO    ][6098] Returning information for job: 20200131091318395262
2020-01-31 09:13:37,535 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1110.27675986s left)
2020-01-31 09:13:48,476 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131091348462605
2020-01-31 09:13:48,502 [salt.minion      :1432][INFO    ][6133] Starting a new job with PID 6133
2020-01-31 09:13:48,523 [salt.minion      :1711][INFO    ][6133] Returning information for job: 20200131091348462605
2020-01-31 09:14:10,994 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1076.81747103s left)
2020-01-31 09:14:18,585 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131091418571761
2020-01-31 09:14:18,609 [salt.minion      :1432][INFO    ][6154] Starting a new job with PID 6154
2020-01-31 09:14:18,626 [salt.minion      :1711][INFO    ][6154] Returning information for job: 20200131091418571761
2020-01-31 09:14:44,336 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1043.47523904s left)
2020-01-31 09:14:48,691 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131091448682103
2020-01-31 09:14:48,716 [salt.minion      :1432][INFO    ][6188] Starting a new job with PID 6188
2020-01-31 09:14:48,736 [salt.minion      :1711][INFO    ][6188] Returning information for job: 20200131091448682103
2020-01-31 09:15:17,711 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1010.10070801s left)
2020-01-31 09:15:18,842 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131091518835736
2020-01-31 09:15:18,863 [salt.minion      :1432][INFO    ][6211] Starting a new job with PID 6211
2020-01-31 09:15:18,888 [salt.minion      :1711][INFO    ][6211] Returning information for job: 20200131091518835736
2020-01-31 09:15:48,991 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131091548982347
2020-01-31 09:15:49,017 [salt.minion      :1432][INFO    ][6265] Starting a new job with PID 6265
2020-01-31 09:15:49,039 [salt.minion      :1711][INFO    ][6265] Returning information for job: 20200131091548982347
2020-01-31 09:15:51,262 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (976.549913883s left)
2020-01-31 09:16:19,127 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131091619112736
2020-01-31 09:16:19,156 [salt.minion      :1432][INFO    ][6290] Starting a new job with PID 6290
2020-01-31 09:16:19,184 [salt.minion      :1711][INFO    ][6290] Returning information for job: 20200131091619112736
2020-01-31 09:16:24,927 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03']
sleep for:30s Timeout:2250s (942.884891033s left)
2020-01-31 09:16:49,319 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131091649312532
2020-01-31 09:16:49,340 [salt.minion      :1432][INFO    ][6328] Starting a new job with PID 6328
2020-01-31 09:16:49,361 [salt.minion      :1711][INFO    ][6328] Returning information for job: 20200131091649312532
2020-01-31 09:16:58,040 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03']
sleep for:30s Timeout:2250s (909.771198034s left)
2020-01-31 09:17:19,387 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131091719379027
2020-01-31 09:17:19,405 [salt.minion      :1432][INFO    ][6353] Starting a new job with PID 6353
2020-01-31 09:17:19,425 [salt.minion      :1711][INFO    ][6353] Returning information for job: 20200131091719379027
2020-01-31 09:17:31,186 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03']
sleep for:30s Timeout:2250s (876.625530958s left)
2020-01-31 09:17:49,608 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131091749596893
2020-01-31 09:17:49,633 [salt.minion      :1432][INFO    ][6404] Starting a new job with PID 6404
2020-01-31 09:17:49,655 [salt.minion      :1711][INFO    ][6404] Returning information for job: 20200131091749596893
2020-01-31 09:18:04,708 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm03']
sleep for:30s Timeout:2250s (843.104096889s left)
2020-01-31 09:18:19,639 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131091819630718
2020-01-31 09:18:19,658 [salt.minion      :1432][INFO    ][6428] Starting a new job with PID 6428
2020-01-31 09:18:19,682 [salt.minion      :1711][INFO    ][6428] Returning information for job: 20200131091819630718
2020-01-31 09:18:38,043 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm03']
sleep for:30s Timeout:2250s (809.768642902s left)
2020-01-31 09:18:49,814 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131091849801493
2020-01-31 09:18:49,839 [salt.minion      :1432][INFO    ][6471] Starting a new job with PID 6471
2020-01-31 09:18:49,857 [salt.minion      :1711][INFO    ][6471] Returning information for job: 20200131091849801493
2020-01-31 09:19:11,468 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (776.343806028s left)
2020-01-31 09:19:19,842 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131091919834526
2020-01-31 09:19:19,861 [salt.minion      :1432][INFO    ][6497] Starting a new job with PID 6497
2020-01-31 09:19:19,880 [salt.minion      :1711][INFO    ][6497] Returning information for job: 20200131091919834526
2020-01-31 09:19:44,680 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (743.131736994s left)
2020-01-31 09:19:50,052 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131091950039610
2020-01-31 09:19:50,076 [salt.minion      :1432][INFO    ][6539] Starting a new job with PID 6539
2020-01-31 09:19:50,096 [salt.minion      :1711][INFO    ][6539] Returning information for job: 20200131091950039610
2020-01-31 09:20:17,926 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (709.886132002s left)
2020-01-31 09:20:20,152 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131092020139704
2020-01-31 09:20:20,180 [salt.minion      :1432][INFO    ][6562] Starting a new job with PID 6562
2020-01-31 09:20:20,203 [salt.minion      :1711][INFO    ][6562] Returning information for job: 20200131092020139704
2020-01-31 09:20:50,211 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131092050200791
2020-01-31 09:20:50,234 [salt.minion      :1432][INFO    ][6596] Starting a new job with PID 6596
2020-01-31 09:20:50,252 [salt.minion      :1711][INFO    ][6596] Returning information for job: 20200131092050200791
2020-01-31 09:20:51,249 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (676.562792063s left)
2020-01-31 09:21:20,336 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131092120325056
2020-01-31 09:21:20,362 [salt.minion      :1432][INFO    ][6614] Starting a new job with PID 6614
2020-01-31 09:21:20,386 [salt.minion      :1711][INFO    ][6614] Returning information for job: 20200131092120325056
2020-01-31 09:21:24,630 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (643.181154966s left)
2020-01-31 09:21:50,447 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131092150438357
2020-01-31 09:21:50,474 [salt.minion      :1432][INFO    ][6651] Starting a new job with PID 6651
2020-01-31 09:21:50,492 [salt.minion      :1711][INFO    ][6651] Returning information for job: 20200131092150438357
2020-01-31 09:21:57,773 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (610.039072037s left)
2020-01-31 09:22:20,590 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131092220577632
2020-01-31 09:22:20,615 [salt.minion      :1432][INFO    ][6671] Starting a new job with PID 6671
2020-01-31 09:22:20,637 [salt.minion      :1711][INFO    ][6671] Returning information for job: 20200131092220577632
2020-01-31 09:22:31,067 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (576.74416399s left)
2020-01-31 09:22:50,720 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131092250706873
2020-01-31 09:22:50,744 [salt.minion      :1432][INFO    ][6707] Starting a new job with PID 6707
2020-01-31 09:22:50,768 [salt.minion      :1711][INFO    ][6707] Returning information for job: 20200131092250706873
2020-01-31 09:23:04,195 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (543.616798878s left)
2020-01-31 09:23:20,892 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131092320878320
2020-01-31 09:23:20,917 [salt.minion      :1432][INFO    ][6727] Starting a new job with PID 6727
2020-01-31 09:23:20,938 [salt.minion      :1711][INFO    ][6727] Returning information for job: 20200131092320878320
2020-01-31 09:23:37,514 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (510.297283888s left)
2020-01-31 09:23:51,048 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131092351036802
2020-01-31 09:23:51,076 [salt.minion      :1432][INFO    ][6760] Starting a new job with PID 6760
2020-01-31 09:23:51,096 [salt.minion      :1711][INFO    ][6760] Returning information for job: 20200131092351036802
2020-01-31 09:24:10,651 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (477.161056042s left)
2020-01-31 09:24:21,251 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131092421236465
2020-01-31 09:24:21,279 [salt.minion      :1432][INFO    ][6784] Starting a new job with PID 6784
2020-01-31 09:24:21,303 [salt.minion      :1711][INFO    ][6784] Returning information for job: 20200131092421236465
2020-01-31 09:24:43,851 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (443.961109877s left)
2020-01-31 09:24:51,476 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131092451467940
2020-01-31 09:24:51,496 [salt.minion      :1432][INFO    ][6820] Starting a new job with PID 6820
2020-01-31 09:24:51,517 [salt.minion      :1711][INFO    ][6820] Returning information for job: 20200131092451467940
2020-01-31 09:25:17,073 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (410.738164902s left)
2020-01-31 09:25:21,495 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131092521484215
2020-01-31 09:25:21,523 [salt.minion      :1432][INFO    ][6847] Starting a new job with PID 6847
2020-01-31 09:25:21,544 [salt.minion      :1711][INFO    ][6847] Returning information for job: 20200131092521484215
2020-01-31 09:25:50,180 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (377.631263018s left)
2020-01-31 09:25:51,556 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131092551549952
2020-01-31 09:25:51,568 [salt.minion      :1432][INFO    ][6894] Starting a new job with PID 6894
2020-01-31 09:25:51,590 [salt.minion      :1711][INFO    ][6894] Returning information for job: 20200131092551549952
2020-01-31 09:26:21,788 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131092621775266
2020-01-31 09:26:21,812 [salt.minion      :1432][INFO    ][6915] Starting a new job with PID 6915
2020-01-31 09:26:21,834 [salt.minion      :1711][INFO    ][6915] Returning information for job: 20200131092621775266
2020-01-31 09:26:23,490 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (344.321161032s left)
2020-01-31 09:26:51,922 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131092651908067
2020-01-31 09:26:51,950 [salt.minion      :1432][INFO    ][6949] Starting a new job with PID 6949
2020-01-31 09:26:51,972 [salt.minion      :1711][INFO    ][6949] Returning information for job: 20200131092651908067
2020-01-31 09:26:56,708 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (311.103525877s left)
2020-01-31 09:27:21,968 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131092721955219
2020-01-31 09:27:21,992 [salt.minion      :1432][INFO    ][6968] Starting a new job with PID 6968
2020-01-31 09:27:22,015 [salt.minion      :1711][INFO    ][6968] Returning information for job: 20200131092721955219
2020-01-31 09:27:29,994 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (277.817231894s left)
2020-01-31 09:27:52,132 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131092752116847
2020-01-31 09:27:52,159 [salt.minion      :1432][INFO    ][7003] Starting a new job with PID 7003
2020-01-31 09:27:52,180 [salt.minion      :1711][INFO    ][7003] Returning information for job: 20200131092752116847
2020-01-31 09:28:03,177 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (244.634844065s left)
2020-01-31 09:28:22,271 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131092822261787
2020-01-31 09:28:22,298 [salt.minion      :1432][INFO    ][7024] Starting a new job with PID 7024
2020-01-31 09:28:22,320 [salt.minion      :1711][INFO    ][7024] Returning information for job: 20200131092822261787
2020-01-31 09:28:36,255 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (211.55711484s left)
2020-01-31 09:28:52,479 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131092852470200
2020-01-31 09:28:52,504 [salt.minion      :1432][INFO    ][7058] Starting a new job with PID 7058
2020-01-31 09:28:52,523 [salt.minion      :1711][INFO    ][7058] Returning information for job: 20200131092852470200
2020-01-31 09:29:09,352 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (178.459290028s left)
2020-01-31 09:29:22,511 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131092922494898
2020-01-31 09:29:22,539 [salt.minion      :1432][INFO    ][7080] Starting a new job with PID 7080
2020-01-31 09:29:22,572 [salt.minion      :1711][INFO    ][7080] Returning information for job: 20200131092922494898
2020-01-31 09:29:42,467 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (145.344532013s left)
2020-01-31 09:29:52,552 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131092952539109
2020-01-31 09:29:52,584 [salt.minion      :1432][INFO    ][7119] Starting a new job with PID 7119
2020-01-31 09:29:52,608 [salt.minion      :1711][INFO    ][7119] Returning information for job: 20200131092952539109
2020-01-31 09:30:15,822 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (111.989275932s left)
2020-01-31 09:30:22,576 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131093022563076
2020-01-31 09:30:22,612 [salt.minion      :1432][INFO    ][7140] Starting a new job with PID 7140
2020-01-31 09:30:22,635 [salt.minion      :1711][INFO    ][7140] Returning information for job: 20200131093022563076
2020-01-31 09:30:48,927 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (78.8844709396s left)
2020-01-31 09:30:52,622 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131093052610577
2020-01-31 09:30:52,647 [salt.minion      :1432][INFO    ][7174] Starting a new job with PID 7174
2020-01-31 09:30:52,667 [salt.minion      :1711][INFO    ][7174] Returning information for job: 20200131093052610577
2020-01-31 09:31:22,040 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (45.7716419697s left)
2020-01-31 09:31:22,820 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131093122810540
2020-01-31 09:31:22,846 [salt.minion      :1432][INFO    ][7197] Starting a new job with PID 7197
2020-01-31 09:31:22,869 [salt.minion      :1711][INFO    ][7197] Returning information for job: 20200131093122810540
2020-01-31 09:31:52,894 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131093152883392
2020-01-31 09:31:52,917 [salt.minion      :1432][INFO    ][7229] Starting a new job with PID 7229
2020-01-31 09:31:52,940 [salt.minion      :1711][INFO    ][7229] Returning information for job: 20200131093152883392
2020-01-31 09:31:55,176 [salt.loaded.ext.module.maas:1024][INFO    ][4469] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (12.6353609562s left)
2020-01-31 09:32:22,968 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131093222956028
2020-01-31 09:32:22,990 [salt.minion      :1432][INFO    ][7258] Starting a new job with PID 7258
2020-01-31 09:32:23,008 [salt.minion      :1711][INFO    ][7258] Returning information for job: 20200131093222956028
2020-01-31 09:32:28,472 [salt.state       :302 ][ERROR   ][4469] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['cmp002', 'cmp001']not in Deployed state
2020-01-31 09:32:28,472 [salt.state       :1951][INFO    ][4469] Completed state [maas.wait_for_machine_status] at time 09:32:28.472532 duration_in_ms=2270676.756
2020-01-31 09:32:28,478 [salt.minion      :1711][INFO    ][4469] Returning information for job: 20200131085429247696
2020-01-31 09:32:39,572 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command pillar.get with jid 20200131093239556711
2020-01-31 09:32:39,599 [salt.minion      :1432][INFO    ][7272] Starting a new job with PID 7272
2020-01-31 09:32:39,609 [salt.minion      :1711][INFO    ][7272] Returning information for job: 20200131093239556711
2020-01-31 09:32:40,412 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command service.status with jid 20200131093240396923
2020-01-31 09:32:40,437 [salt.minion      :1432][INFO    ][7277] Starting a new job with PID 7277
2020-01-31 09:32:40,967 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][7277] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2020-01-31 09:32:41,015 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][7277] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2020-01-31 09:32:41,039 [salt.minion      :1711][INFO    ][7277] Returning information for job: 20200131093240396923
2020-01-31 09:32:41,824 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command state.apply with jid 20200131093241812031
2020-01-31 09:32:41,856 [salt.minion      :1432][INFO    ][7288] Starting a new job with PID 7288
2020-01-31 09:32:48,096 [salt.state       :915 ][INFO    ][7288] Loading fresh modules for state activity
2020-01-31 09:32:48,707 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7288] Executing command 'salt-minion --version' in directory '/root'
2020-01-31 09:32:49,086 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7288] Executing command 'salt-minion --version' in directory '/root'
2020-01-31 09:32:50,131 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7288] Executing command 'salt-minion --version' in directory '/root'
2020-01-31 09:32:50,449 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7288] Executing command 'salt-minion --version' in directory '/root'
2020-01-31 09:32:52,359 [salt.state       :1780][INFO    ][7288] Running state [salt-minion] at time 09:32:52.359053
2020-01-31 09:32:52,359 [salt.state       :1813][INFO    ][7288] Executing state pkg.installed for [salt-minion]
2020-01-31 09:32:52,360 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7288] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-01-31 09:32:52,480 [salt.state       :300 ][INFO    ][7288] All specified packages are already installed
2020-01-31 09:32:52,480 [salt.state       :1951][INFO    ][7288] Completed state [salt-minion] at time 09:32:52.480682 duration_in_ms=121.63
2020-01-31 09:32:52,481 [salt.state       :1780][INFO    ][7288] Running state [salt_minion_dependency_packages] at time 09:32:52.481011
2020-01-31 09:32:52,482 [salt.state       :1813][INFO    ][7288] Executing state pkg.installed for [salt_minion_dependency_packages]
2020-01-31 09:32:52,490 [salt.state       :300 ][INFO    ][7288] All specified packages are already installed
2020-01-31 09:32:52,491 [salt.state       :1951][INFO    ][7288] Completed state [salt_minion_dependency_packages] at time 09:32:52.491064 duration_in_ms=10.053
2020-01-31 09:32:52,495 [salt.state       :1780][INFO    ][7288] Running state [/etc/salt/minion.d/minion.conf] at time 09:32:52.495040
2020-01-31 09:32:52,495 [salt.state       :1813][INFO    ][7288] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2020-01-31 09:32:52,804 [salt.state       :300 ][INFO    ][7288] File /etc/salt/minion.d/minion.conf is in the correct state
2020-01-31 09:32:52,805 [salt.state       :1951][INFO    ][7288] Completed state [/etc/salt/minion.d/minion.conf] at time 09:32:52.805181 duration_in_ms=310.14
2020-01-31 09:32:52,806 [salt.state       :1780][INFO    ][7288] Running state [python-netaddr] at time 09:32:52.806083
2020-01-31 09:32:52,806 [salt.state       :1813][INFO    ][7288] Executing state pkg.installed for [python-netaddr]
2020-01-31 09:32:52,814 [salt.state       :300 ][INFO    ][7288] All specified packages are already installed
2020-01-31 09:32:52,815 [salt.state       :1951][INFO    ][7288] Completed state [python-netaddr] at time 09:32:52.815118 duration_in_ms=9.035
2020-01-31 09:32:52,818 [salt.state       :1780][INFO    ][7288] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 09:32:52.818578
2020-01-31 09:32:52,818 [salt.state       :1813][INFO    ][7288] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2020-01-31 09:32:52,830 [salt.state       :300 ][INFO    ][7288] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2020-01-31 09:32:52,831 [salt.state       :1951][INFO    ][7288] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 09:32:52.831080 duration_in_ms=12.502
2020-01-31 09:32:52,832 [salt.state       :1780][INFO    ][7288] Running state [salt-minion] at time 09:32:52.832552
2020-01-31 09:32:52,832 [salt.state       :1813][INFO    ][7288] Executing state service.running for [salt-minion]
2020-01-31 09:32:52,834 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7288] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2020-01-31 09:32:52,890 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7288] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2020-01-31 09:32:52,911 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7288] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2020-01-31 09:32:52,941 [salt.state       :300 ][INFO    ][7288] The service salt-minion is already running
2020-01-31 09:32:52,942 [salt.state       :1951][INFO    ][7288] Completed state [salt-minion] at time 09:32:52.942109 duration_in_ms=109.556
2020-01-31 09:32:52,944 [salt.state       :1780][INFO    ][7288] Running state [/etc/salt/grains.d] at time 09:32:52.944911
2020-01-31 09:32:52,945 [salt.state       :1813][INFO    ][7288] Executing state file.directory for [/etc/salt/grains.d]
2020-01-31 09:32:52,947 [salt.state       :300 ][INFO    ][7288] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2020-01-31 09:32:52,947 [salt.state       :1951][INFO    ][7288] Completed state [/etc/salt/grains.d] at time 09:32:52.947637 duration_in_ms=2.726
2020-01-31 09:32:52,948 [salt.state       :1780][INFO    ][7288] Running state [/etc/salt/grains] at time 09:32:52.948758
2020-01-31 09:32:52,950 [salt.state       :1813][INFO    ][7288] Executing state file.managed for [/etc/salt/grains]
2020-01-31 09:32:52,951 [salt.state       :300 ][INFO    ][7288] File /etc/salt/grains exists with proper permissions. No changes made.
2020-01-31 09:32:52,951 [salt.state       :1951][INFO    ][7288] Completed state [/etc/salt/grains] at time 09:32:52.951740 duration_in_ms=2.983
2020-01-31 09:32:52,952 [salt.state       :1780][INFO    ][7288] Running state [/etc/salt/grains.d/placeholder] at time 09:32:52.952239
2020-01-31 09:32:52,952 [salt.state       :1813][INFO    ][7288] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2020-01-31 09:32:52,953 [salt.state       :300 ][INFO    ][7288] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2020-01-31 09:32:52,953 [salt.state       :1951][INFO    ][7288] Completed state [/etc/salt/grains.d/placeholder] at time 09:32:52.953243 duration_in_ms=1.004
2020-01-31 09:32:52,953 [salt.state       :1780][INFO    ][7288] Running state [/etc/salt/grains.d/sphinx] at time 09:32:52.953764
2020-01-31 09:32:52,954 [salt.state       :1813][INFO    ][7288] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2020-01-31 09:32:52,955 [salt.state       :300 ][INFO    ][7288] File /etc/salt/grains.d/sphinx is in the correct state
2020-01-31 09:32:52,955 [salt.state       :1951][INFO    ][7288] Completed state [/etc/salt/grains.d/sphinx] at time 09:32:52.955484 duration_in_ms=1.72
2020-01-31 09:32:52,958 [salt.state       :1780][INFO    ][7288] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:32:52.958192
2020-01-31 09:32:52,958 [salt.state       :1813][INFO    ][7288] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2020-01-31 09:32:52,958 [salt.state       :300 ][INFO    ][7288] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2020-01-31 09:32:52,959 [salt.state       :1951][INFO    ][7288] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 09:32:52.959008 duration_in_ms=0.816
2020-01-31 09:32:52,959 [salt.state       :1780][INFO    ][7288] Running state [/etc/salt/grains.d/dns_records] at time 09:32:52.959519
2020-01-31 09:32:52,959 [salt.state       :1813][INFO    ][7288] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2020-01-31 09:32:52,960 [salt.state       :300 ][INFO    ][7288] File /etc/salt/grains.d/dns_records is in the correct state
2020-01-31 09:32:52,960 [salt.state       :1951][INFO    ][7288] Completed state [/etc/salt/grains.d/dns_records] at time 09:32:52.960937 duration_in_ms=1.418
2020-01-31 09:32:52,963 [salt.state       :1780][INFO    ][7288] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:32:52.963528
2020-01-31 09:32:52,964 [salt.state       :1813][INFO    ][7288] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2020-01-31 09:32:52,964 [salt.state       :300 ][INFO    ][7288] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2020-01-31 09:32:52,965 [salt.state       :1951][INFO    ][7288] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 09:32:52.965134 duration_in_ms=1.606
2020-01-31 09:32:52,966 [salt.state       :1780][INFO    ][7288] Running state [/etc/salt/grains.d/salt] at time 09:32:52.965992
2020-01-31 09:32:52,966 [salt.state       :1813][INFO    ][7288] Executing state file.managed for [/etc/salt/grains.d/salt]
2020-01-31 09:32:52,968 [salt.state       :300 ][INFO    ][7288] File /etc/salt/grains.d/salt is in the correct state
2020-01-31 09:32:52,968 [salt.state       :1951][INFO    ][7288] Completed state [/etc/salt/grains.d/salt] at time 09:32:52.968705 duration_in_ms=2.713
2020-01-31 09:32:52,970 [salt.state       :1780][INFO    ][7288] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:32:52.970857
2020-01-31 09:32:52,971 [salt.state       :1813][INFO    ][7288] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2020-01-31 09:32:52,972 [salt.state       :300 ][INFO    ][7288] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2020-01-31 09:32:52,972 [salt.state       :1951][INFO    ][7288] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 09:32:52.972352 duration_in_ms=1.496
2020-01-31 09:32:52,975 [salt.state       :1780][INFO    ][7288] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:32:52.975868
2020-01-31 09:32:52,976 [salt.state       :1813][INFO    ][7288] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2020-01-31 09:32:52,976 [salt.state       :300 ][INFO    ][7288] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2020-01-31 09:32:52,976 [salt.state       :1951][INFO    ][7288] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 09:32:52.976640 duration_in_ms=0.772
2020-01-31 09:32:52,977 [salt.state       :1780][INFO    ][7288] Running state [mine.update] at time 09:32:52.977628
2020-01-31 09:32:52,977 [salt.state       :1813][INFO    ][7288] Executing state module.wait for [mine.update]
2020-01-31 09:32:52,978 [salt.state       :300 ][INFO    ][7288] No changes made for mine.update
2020-01-31 09:32:52,978 [salt.state       :1951][INFO    ][7288] Completed state [mine.update] at time 09:32:52.978335 duration_in_ms=0.706
2020-01-31 09:32:52,978 [salt.state       :1780][INFO    ][7288] Running state [ca-certificates] at time 09:32:52.978578
2020-01-31 09:32:52,978 [salt.state       :1813][INFO    ][7288] Executing state pkg.installed for [ca-certificates]
2020-01-31 09:32:52,988 [salt.state       :300 ][INFO    ][7288] All specified packages are already installed
2020-01-31 09:32:52,988 [salt.state       :1951][INFO    ][7288] Completed state [ca-certificates] at time 09:32:52.988348 duration_in_ms=9.77
2020-01-31 09:32:52,989 [salt.state       :1780][INFO    ][7288] Running state [update-ca-certificates] at time 09:32:52.989040
2020-01-31 09:32:52,989 [salt.state       :1813][INFO    ][7288] Executing state cmd.wait for [update-ca-certificates]
2020-01-31 09:32:52,989 [salt.state       :300 ][INFO    ][7288] No changes made for update-ca-certificates
2020-01-31 09:32:52,990 [salt.state       :1951][INFO    ][7288] Completed state [update-ca-certificates] at time 09:32:52.990098 duration_in_ms=1.057
2020-01-31 09:32:52,990 [salt.state       :1780][INFO    ][7288] Running state [iptables] at time 09:32:52.990356
2020-01-31 09:32:52,990 [salt.state       :1813][INFO    ][7288] Executing state pkg.installed for [iptables]
2020-01-31 09:32:52,999 [salt.state       :300 ][INFO    ][7288] All specified packages are already installed
2020-01-31 09:32:52,999 [salt.state       :1951][INFO    ][7288] Completed state [iptables] at time 09:32:52.999639 duration_in_ms=9.283
2020-01-31 09:32:52,999 [salt.state       :1780][INFO    ][7288] Running state [iptables-persistent] at time 09:32:52.999879
2020-01-31 09:32:53,000 [salt.state       :1813][INFO    ][7288] Executing state pkg.installed for [iptables-persistent]
2020-01-31 09:32:53,008 [salt.state       :300 ][INFO    ][7288] All specified packages are already installed
2020-01-31 09:32:53,009 [salt.state       :1951][INFO    ][7288] Completed state [iptables-persistent] at time 09:32:53.008947 duration_in_ms=9.068
2020-01-31 09:32:53,010 [salt.state       :1780][INFO    ][7288] Running state [iptables_modules_v4_load] at time 09:32:53.010251
2020-01-31 09:32:53,010 [salt.state       :1813][INFO    ][7288] Executing state kmod.present for [iptables_modules_v4_load]
2020-01-31 09:32:53,011 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7288] Executing command 'lsmod' in directory '/root'
2020-01-31 09:32:53,032 [salt.state       :300 ][INFO    ][7288] Kernel modules iptable_filter, ip_tables are already present
2020-01-31 09:32:53,032 [salt.state       :1951][INFO    ][7288] Completed state [iptables_modules_v4_load] at time 09:32:53.032735 duration_in_ms=22.483
2020-01-31 09:32:53,035 [salt.state       :1780][INFO    ][7288] Running state [/etc/iptables/rules.v4] at time 09:32:53.035208
2020-01-31 09:32:53,035 [salt.state       :1813][INFO    ][7288] Executing state file.managed for [/etc/iptables/rules.v4]
2020-01-31 09:32:53,142 [salt.state       :300 ][INFO    ][7288] File /etc/iptables/rules.v4 is in the correct state
2020-01-31 09:32:53,142 [salt.state       :1951][INFO    ][7288] Completed state [/etc/iptables/rules.v4] at time 09:32:53.142443 duration_in_ms=107.235
2020-01-31 09:32:53,143 [salt.state       :1780][INFO    ][7288] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 09:32:53.143506
2020-01-31 09:32:53,143 [salt.state       :1813][INFO    ][7288] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2020-01-31 09:32:53,144 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7288] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2020-01-31 09:32:53,162 [salt.state       :300 ][INFO    ][7288] onlyif execution failed
2020-01-31 09:32:53,162 [salt.state       :1951][INFO    ][7288] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 09:32:53.162390 duration_in_ms=18.883
2020-01-31 09:32:53,163 [salt.state       :1780][INFO    ][7288] Running state [netfilter-persistent] at time 09:32:53.163819
2020-01-31 09:32:53,164 [salt.state       :1813][INFO    ][7288] Executing state service.running for [netfilter-persistent]
2020-01-31 09:32:53,165 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7288] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2020-01-31 09:32:53,192 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7288] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2020-01-31 09:32:53,218 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7288] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2020-01-31 09:32:53,246 [salt.state       :300 ][INFO    ][7288] The service netfilter-persistent is already running
2020-01-31 09:32:53,247 [salt.state       :1951][INFO    ][7288] Completed state [netfilter-persistent] at time 09:32:53.247027 duration_in_ms=83.206
2020-01-31 09:32:53,249 [salt.state       :1780][INFO    ][7288] Running state [iptables_extra.remove_stale_tables] at time 09:32:53.248915
2020-01-31 09:32:53,251 [salt.state       :1813][INFO    ][7288] Executing state module.wait for [iptables_extra.remove_stale_tables]
2020-01-31 09:32:53,251 [salt.state       :300 ][INFO    ][7288] No changes made for iptables_extra.remove_stale_tables
2020-01-31 09:32:53,252 [salt.state       :1951][INFO    ][7288] Completed state [iptables_extra.remove_stale_tables] at time 09:32:53.252060 duration_in_ms=3.145
2020-01-31 09:32:53,253 [salt.state       :1780][INFO    ][7288] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 09:32:53.252571
2020-01-31 09:32:53,253 [salt.state       :1813][INFO    ][7288] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2020-01-31 09:32:53,254 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7288] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2020-01-31 09:32:53,272 [salt.state       :300 ][INFO    ][7288] onlyif execution failed
2020-01-31 09:32:53,272 [salt.state       :1951][INFO    ][7288] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 09:32:53.272729 duration_in_ms=20.156
2020-01-31 09:32:53,277 [salt.state       :1780][INFO    ][7288] Running state [/etc/iptables/rules.v6] at time 09:32:53.276974
2020-01-31 09:32:53,277 [salt.state       :1813][INFO    ][7288] Executing state file.absent for [/etc/iptables/rules.v6]
2020-01-31 09:32:53,277 [salt.state       :300 ][INFO    ][7288] File /etc/iptables/rules.v6 is not present
2020-01-31 09:32:53,278 [salt.state       :1951][INFO    ][7288] Completed state [/etc/iptables/rules.v6] at time 09:32:53.278110 duration_in_ms=1.136
2020-01-31 09:32:53,278 [salt.state       :1780][INFO    ][7288] Running state [iptables_extra.flush_all] at time 09:32:53.278896
2020-01-31 09:32:53,279 [salt.state       :1813][INFO    ][7288] Executing state module.wait for [iptables_extra.flush_all]
2020-01-31 09:32:53,279 [salt.state       :300 ][INFO    ][7288] No changes made for iptables_extra.flush_all
2020-01-31 09:32:53,279 [salt.state       :1951][INFO    ][7288] Completed state [iptables_extra.flush_all] at time 09:32:53.279706 duration_in_ms=0.81
2020-01-31 09:32:53,283 [salt.minion      :1711][INFO    ][7288] Returning information for job: 20200131093241812031
2020-01-31 09:32:54,060 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command state.apply with jid 20200131093254048154
2020-01-31 09:32:54,092 [salt.minion      :1432][INFO    ][7376] Starting a new job with PID 7376
2020-01-31 09:32:55,093 [salt.state       :915 ][INFO    ][7376] Loading fresh modules for state activity
2020-01-31 09:32:56,028 [salt.state       :1780][INFO    ][7376] Running state [maas-rack-controller] at time 09:32:56.028157
2020-01-31 09:32:56,028 [salt.state       :1813][INFO    ][7376] Executing state pkg.installed for [maas-rack-controller]
2020-01-31 09:32:56,030 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7376] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-01-31 09:32:56,133 [salt.state       :300 ][INFO    ][7376] All specified packages are already installed
2020-01-31 09:32:56,134 [salt.state       :1951][INFO    ][7376] Completed state [maas-rack-controller] at time 09:32:56.134172 duration_in_ms=106.017
2020-01-31 09:32:56,134 [salt.state       :1780][INFO    ][7376] Running state [ipmitool] at time 09:32:56.134505
2020-01-31 09:32:56,134 [salt.state       :1813][INFO    ][7376] Executing state pkg.installed for [ipmitool]
2020-01-31 09:32:56,144 [salt.state       :300 ][INFO    ][7376] All specified packages are already installed
2020-01-31 09:32:56,144 [salt.state       :1951][INFO    ][7376] Completed state [ipmitool] at time 09:32:56.144510 duration_in_ms=10.005
2020-01-31 09:32:56,148 [salt.state       :1780][INFO    ][7376] Running state [/etc/maas/rackd.conf] at time 09:32:56.148107
2020-01-31 09:32:56,148 [salt.state       :1813][INFO    ][7376] Executing state file.line for [/etc/maas/rackd.conf]
2020-01-31 09:32:56,149 [salt.state       :300 ][INFO    ][7376] No changes needed to be made
2020-01-31 09:32:56,149 [salt.state       :1951][INFO    ][7376] Completed state [/etc/maas/rackd.conf] at time 09:32:56.149489 duration_in_ms=1.382
2020-01-31 09:32:56,149 [salt.state       :1780][INFO    ][7376] Running state [/etc/maas/rackd.conf] at time 09:32:56.149724
2020-01-31 09:32:56,149 [salt.state       :1813][INFO    ][7376] Executing state file.managed for [/etc/maas/rackd.conf]
2020-01-31 09:32:56,150 [salt.loaded.int.states.file:2298][WARNING ][7376] 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-01-31 09:32:56,150 [salt.state       :300 ][INFO    ][7376] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2020-01-31 09:32:56,151 [salt.state       :1951][INFO    ][7376] Completed state [/etc/maas/rackd.conf] at time 09:32:56.150976 duration_in_ms=1.252
2020-01-31 09:32:56,152 [salt.state       :1780][INFO    ][7376] Running state [maas-rackd] at time 09:32:56.152067
2020-01-31 09:32:56,152 [salt.state       :1813][INFO    ][7376] Executing state service.running for [maas-rackd]
2020-01-31 09:32:56,152 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7376] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2020-01-31 09:32:56,195 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7376] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2020-01-31 09:32:56,222 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7376] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2020-01-31 09:32:56,250 [salt.state       :300 ][INFO    ][7376] The service maas-rackd is already running
2020-01-31 09:32:56,251 [salt.state       :1951][INFO    ][7376] Completed state [maas-rackd] at time 09:32:56.251018 duration_in_ms=98.95
2020-01-31 09:32:56,254 [salt.minion      :1711][INFO    ][7376] Returning information for job: 20200131093254048154
2020-01-31 09:32:57,048 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command state.apply with jid 20200131093257038929
2020-01-31 09:32:57,074 [salt.minion      :1432][INFO    ][7399] Starting a new job with PID 7399
2020-01-31 09:32:58,051 [salt.state       :915 ][INFO    ][7399] Loading fresh modules for state activity
2020-01-31 09:32:59,091 [salt.state       :1780][INFO    ][7399] Running state [maas-region-controller] at time 09:32:59.091054
2020-01-31 09:32:59,091 [salt.state       :1813][INFO    ][7399] Executing state pkg.installed for [maas-region-controller]
2020-01-31 09:32:59,094 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7399] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-01-31 09:32:59,208 [salt.state       :300 ][INFO    ][7399] All specified packages are already installed
2020-01-31 09:32:59,209 [salt.state       :1951][INFO    ][7399] Completed state [maas-region-controller] at time 09:32:59.208991 duration_in_ms=117.939
2020-01-31 09:32:59,209 [salt.state       :1780][INFO    ][7399] Running state [python-oauth] at time 09:32:59.209670
2020-01-31 09:32:59,209 [salt.state       :1813][INFO    ][7399] Executing state pkg.installed for [python-oauth]
2020-01-31 09:32:59,220 [salt.state       :300 ][INFO    ][7399] All specified packages are already installed
2020-01-31 09:32:59,221 [salt.state       :1951][INFO    ][7399] Completed state [python-oauth] at time 09:32:59.220967 duration_in_ms=11.297
2020-01-31 09:32:59,224 [salt.state       :1780][INFO    ][7399] Running state [/etc/maas/regiond.conf] at time 09:32:59.224615
2020-01-31 09:32:59,224 [salt.state       :1813][INFO    ][7399] Executing state file.replace for [/etc/maas/regiond.conf]
2020-01-31 09:32:59,231 [salt.state       :300 ][INFO    ][7399] No changes needed to be made
2020-01-31 09:32:59,231 [salt.state       :1951][INFO    ][7399] Completed state [/etc/maas/regiond.conf] at time 09:32:59.231285 duration_in_ms=6.671
2020-01-31 09:32:59,231 [salt.state       :1780][INFO    ][7399] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 09:32:59.231756
2020-01-31 09:32:59,232 [salt.state       :1813][INFO    ][7399] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2020-01-31 09:32:59,295 [salt.state       :300 ][INFO    ][7399] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2020-01-31 09:32:59,295 [salt.state       :1951][INFO    ][7399] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 09:32:59.295373 duration_in_ms=63.617
2020-01-31 09:32:59,295 [salt.state       :1780][INFO    ][7399] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:32:59.295924
2020-01-31 09:32:59,296 [salt.state       :1813][INFO    ][7399] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-01-31 09:32:59,300 [salt.state       :300 ][INFO    ][7399] No changes needed to be made
2020-01-31 09:32:59,300 [salt.state       :1951][INFO    ][7399] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:32:59.300888 duration_in_ms=4.964
2020-01-31 09:32:59,301 [salt.state       :1780][INFO    ][7399] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:32:59.301362
2020-01-31 09:32:59,301 [salt.state       :1813][INFO    ][7399] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-01-31 09:32:59,304 [salt.state       :300 ][INFO    ][7399] No changes needed to be made
2020-01-31 09:32:59,305 [salt.state       :1951][INFO    ][7399] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:32:59.305106 duration_in_ms=3.744
2020-01-31 09:32:59,305 [salt.state       :1780][INFO    ][7399] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:32:59.305586
2020-01-31 09:32:59,305 [salt.state       :1813][INFO    ][7399] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2020-01-31 09:32:59,338 [salt.state       :300 ][INFO    ][7399] No changes needed to be made
2020-01-31 09:32:59,338 [salt.state       :1951][INFO    ][7399] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:32:59.338912 duration_in_ms=33.326
2020-01-31 09:32:59,339 [salt.state       :1780][INFO    ][7399] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:32:59.339185
2020-01-31 09:32:59,339 [salt.state       :1813][INFO    ][7399] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2020-01-31 09:32:59,362 [salt.state       :300 ][INFO    ][7399] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2020-01-31 09:32:59,363 [salt.state       :1951][INFO    ][7399] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:32:59.363019 duration_in_ms=23.832
2020-01-31 09:32:59,364 [salt.state       :1780][INFO    ][7399] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:32:59.364040
2020-01-31 09:32:59,364 [salt.state       :1813][INFO    ][7399] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2020-01-31 09:32:59,423 [salt.state       :300 ][INFO    ][7399] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2020-01-31 09:32:59,423 [salt.state       :1951][INFO    ][7399] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:32:59.423597 duration_in_ms=59.558
2020-01-31 09:32:59,424 [salt.state       :1780][INFO    ][7399] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:32:59.424086
2020-01-31 09:32:59,424 [salt.state       :1813][INFO    ][7399] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2020-01-31 09:32:59,488 [salt.state       :300 ][INFO    ][7399] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2020-01-31 09:32:59,488 [salt.state       :1951][INFO    ][7399] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:32:59.488864 duration_in_ms=64.778
2020-01-31 09:32:59,489 [salt.state       :1780][INFO    ][7399] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:32:59.489747
2020-01-31 09:32:59,490 [salt.state       :1813][INFO    ][7399] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2020-01-31 09:32:59,557 [salt.state       :300 ][INFO    ][7399] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2020-01-31 09:32:59,557 [salt.state       :1951][INFO    ][7399] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:32:59.557835 duration_in_ms=68.087
2020-01-31 09:32:59,559 [salt.state       :1780][INFO    ][7399] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 09:32:59.558926
2020-01-31 09:32:59,559 [salt.state       :1813][INFO    ][7399] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic]
2020-01-31 09:32:59,622 [salt.state       :300 ][INFO    ][7399] File /etc/maas/preseeds/curtin_userdata_amd64_generic_bionic is in the correct state
2020-01-31 09:32:59,622 [salt.state       :1951][INFO    ][7399] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 09:32:59.622880 duration_in_ms=63.954
2020-01-31 09:32:59,623 [salt.state       :1780][INFO    ][7399] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 09:32:59.623375
2020-01-31 09:32:59,623 [salt.state       :1813][INFO    ][7399] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic]
2020-01-31 09:32:59,688 [salt.state       :300 ][INFO    ][7399] File /etc/maas/preseeds/curtin_userdata_arm64_generic_bionic is in the correct state
2020-01-31 09:32:59,688 [salt.state       :1951][INFO    ][7399] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 09:32:59.688323 duration_in_ms=64.948
2020-01-31 09:32:59,688 [salt.state       :1780][INFO    ][7399] Running state [/root/.pgpass] at time 09:32:59.688594
2020-01-31 09:32:59,688 [salt.state       :1813][INFO    ][7399] Executing state file.managed for [/root/.pgpass]
2020-01-31 09:32:59,739 [salt.state       :300 ][INFO    ][7399] File /root/.pgpass is in the correct state
2020-01-31 09:32:59,739 [salt.state       :1951][INFO    ][7399] Completed state [/root/.pgpass] at time 09:32:59.739408 duration_in_ms=50.813
2020-01-31 09:32:59,748 [salt.state       :1780][INFO    ][7399] Running state [maas-region migrate --noinput] at time 09:32:59.748904
2020-01-31 09:32:59,749 [salt.state       :1813][INFO    ][7399] Executing state cmd.run for [maas-region migrate --noinput]
2020-01-31 09:32:59,750 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7399] Executing command 'maas-region migrate --noinput' in directory '/root'
2020-01-31 09:33:02,614 [salt.state       :300 ][INFO    ][7399] {'pid': 7430, '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-01-31 09:33:02,614 [salt.state       :1951][INFO    ][7399] Completed state [maas-region migrate --noinput] at time 09:33:02.614789 duration_in_ms=2865.884
2020-01-31 09:33:02,615 [salt.state       :2022][WARNING ][7399] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-01-31 09:33:02,617 [salt.state       :1780][INFO    ][7399] Running state [maas-regiond] at time 09:33:02.617873
2020-01-31 09:33:02,618 [salt.state       :1813][INFO    ][7399] Executing state service.running for [maas-regiond]
2020-01-31 09:33:02,620 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7399] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2020-01-31 09:33:02,674 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7399] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2020-01-31 09:33:02,699 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7399] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2020-01-31 09:33:02,726 [salt.state       :300 ][INFO    ][7399] The service maas-regiond is already running
2020-01-31 09:33:02,727 [salt.state       :1951][INFO    ][7399] Completed state [maas-regiond] at time 09:33:02.727369 duration_in_ms=109.495
2020-01-31 09:33:02,732 [salt.state       :1780][INFO    ][7399] Running state [bind9] at time 09:33:02.732449
2020-01-31 09:33:02,733 [salt.state       :1813][INFO    ][7399] Executing state service.running for [bind9]
2020-01-31 09:33:02,734 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7399] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2020-01-31 09:33:02,763 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7399] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2020-01-31 09:33:02,784 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7399] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2020-01-31 09:33:02,810 [salt.state       :300 ][INFO    ][7399] The service bind9 is already running
2020-01-31 09:33:02,811 [salt.state       :1951][INFO    ][7399] Completed state [bind9] at time 09:33:02.810949 duration_in_ms=78.501
2020-01-31 09:33:02,813 [salt.state       :1780][INFO    ][7399] Running state [maasng.wait_for_http_code] at time 09:33:02.813156
2020-01-31 09:33:02,816 [salt.state       :1813][INFO    ][7399] Executing state module.run for [maasng.wait_for_http_code]
2020-01-31 09:33:02,816 [salt.utils.decorators:613 ][WARNING ][7399] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-31 09:33:02,824 [salt.state       :300 ][INFO    ][7399] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2020-01-31 09:33:02,824 [salt.state       :1951][INFO    ][7399] Completed state [maasng.wait_for_http_code] at time 09:33:02.824403 duration_in_ms=11.247
2020-01-31 09:33:02,827 [salt.state       :1780][INFO    ][7399] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:33:02.827804
2020-01-31 09:33:02,828 [salt.state       :1813][INFO    ][7399] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2020-01-31 09:33:02,828 [salt.state       :300 ][INFO    ][7399] /var/lib/maas/.setup_admin exists
2020-01-31 09:33:02,829 [salt.state       :1951][INFO    ][7399] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:33:02.829200 duration_in_ms=1.396
2020-01-31 09:33:02,830 [salt.state       :1780][INFO    ][7399] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:33:02.830211
2020-01-31 09:33:02,830 [salt.state       :1813][INFO    ][7399] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-31 09:33:02,831 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7399] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-31 09:33:04,828 [salt.state       :300 ][INFO    ][7399] {'pid': 7444, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-31 09:33:04,830 [salt.state       :1951][INFO    ][7399] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:33:04.830127 duration_in_ms=1999.914
2020-01-31 09:33:04,839 [salt.state       :1780][INFO    ][7399] Running state [maas_region_boot_source_resources_mirror] at time 09:33:04.839766
2020-01-31 09:33:04,840 [salt.state       :1813][INFO    ][7399] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2020-01-31 09:33:04,902 [salt.state       :300 ][INFO    ][7399] {'changes': {}}
2020-01-31 09:33:04,902 [salt.state       :1951][INFO    ][7399] Completed state [maas_region_boot_source_resources_mirror] at time 09:33:04.902772 duration_in_ms=63.005
2020-01-31 09:33:04,904 [salt.state       :1780][INFO    ][7399] Running state [maasng.boot_resources_import] at time 09:33:04.904066
2020-01-31 09:33:04,904 [salt.state       :1813][INFO    ][7399] Executing state module.run for [maasng.boot_resources_import]
2020-01-31 09:33:04,905 [salt.utils.decorators:613 ][WARNING ][7399] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-31 09:33:04,999 [salt.loaded.ext.module.maasng:1600][INFO    ][7399] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2020-01-31 09:33:10,037 [salt.loaded.ext.module.maasng:1600][INFO    ][7399] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2020-01-31 09:33:12,107 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131093312096428
2020-01-31 09:33:12,137 [salt.minion      :1432][INFO    ][7468] Starting a new job with PID 7468
2020-01-31 09:33:12,162 [salt.minion      :1711][INFO    ][7468] Returning information for job: 20200131093312096428
2020-01-31 09:33:15,104 [salt.state       :300 ][INFO    ][7399] {'ret': True}
2020-01-31 09:33:15,104 [salt.state       :1951][INFO    ][7399] Completed state [maasng.boot_resources_import] at time 09:33:15.104487 duration_in_ms=10200.42
2020-01-31 09:33:15,106 [salt.state       :1780][INFO    ][7399] Running state [maas_region_boot_sources_selection_bionic] at time 09:33:15.106320
2020-01-31 09:33:15,106 [salt.state       :1813][INFO    ][7399] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_bionic]
2020-01-31 09:33:15,250 [salt.state       :300 ][INFO    ][7399] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2020-01-31 09:33:15,251 [salt.state       :1951][INFO    ][7399] Completed state [maas_region_boot_sources_selection_bionic] at time 09:33:15.251205 duration_in_ms=144.885
2020-01-31 09:33:15,252 [salt.state       :1780][INFO    ][7399] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 09:33:15.252733
2020-01-31 09:33:15,253 [salt.state       :1813][INFO    ][7399] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2020-01-31 09:33:15,254 [salt.utils.decorators:613 ][WARNING ][7399] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-31 09:33:15,254 [salt.loaded.ext.module.maasng:1771][INFO    ][7399] boot-sources sync initiated for ALL Rack's
2020-01-31 09:33:16,001 [salt.state       :300 ][INFO    ][7399] {'ret': True}
2020-01-31 09:33:16,002 [salt.state       :1951][INFO    ][7399] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 09:33:16.002127 duration_in_ms=749.394
2020-01-31 09:33:16,003 [salt.state       :1780][INFO    ][7399] Running state [maas.process_maas_config] at time 09:33:16.003891
2020-01-31 09:33:16,004 [salt.state       :1813][INFO    ][7399] Executing state module.run for [maas.process_maas_config]
2020-01-31 09:33:16,004 [salt.utils.decorators:613 ][WARNING ][7399] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-31 09:33:16,005 [salt.loaded.ext.module.maas:92  ][INFO    ][7399] maasconfig name=enable_http_proxy value=True
2020-01-31 09:33:16,048 [salt.loaded.ext.module.maas:92  ][INFO    ][7399] maasconfig name=upstream_dns value=8.8.8.8
2020-01-31 09:33:16,091 [salt.loaded.ext.module.maas:92  ][INFO    ][7399] maasconfig name=commissioning_distro_series value=bionic
2020-01-31 09:33:16,144 [salt.loaded.ext.module.maas:92  ][INFO    ][7399] maasconfig name=default_osystem value=ubuntu
2020-01-31 09:33:16,213 [salt.loaded.ext.module.maas:92  ][INFO    ][7399] maasconfig name=active_discovery_interval value=600
2020-01-31 09:33:16,255 [salt.loaded.ext.module.maas:92  ][INFO    ][7399] maasconfig name=dnssec_validation value=no
2020-01-31 09:33:16,292 [salt.loaded.ext.module.maas:92  ][INFO    ][7399] maasconfig name=kernel_opts value=spectre_v2=off nopti kpti=off nospec_store_bypass_disable noibrs noibpb
2020-01-31 09:33:16,331 [salt.loaded.ext.module.maas:92  ][INFO    ][7399] maasconfig name=network_discovery value=enabled
2020-01-31 09:33:16,420 [salt.loaded.ext.module.maas:92  ][INFO    ][7399] maasconfig name=enable_third_party_drivers value=True
2020-01-31 09:33:16,466 [salt.loaded.ext.module.maas:92  ][INFO    ][7399] maasconfig name=default_storage_layout value=lvm
2020-01-31 09:33:16,507 [salt.loaded.ext.module.maas:92  ][INFO    ][7399] maasconfig name=maas_name value=mas01
2020-01-31 09:33:17,369 [salt.loaded.ext.module.maas:92  ][INFO    ][7399] maasconfig name=disk_erase_with_secure_erase value=False
2020-01-31 09:33:17,622 [salt.loaded.ext.module.maas:92  ][INFO    ][7399] maasconfig name=default_distro_series value=bionic
2020-01-31 09:33:17,664 [salt.loaded.ext.module.maas:92  ][INFO    ][7399] maasconfig name=ntp_external_only value=True
2020-01-31 09:33:17,693 [salt.loaded.ext.module.maas:92  ][INFO    ][7399] maasconfig name=default_min_hwe_kernel value=ga-18.04
2020-01-31 09:33:17,742 [salt.state       :300 ][INFO    ][7399] {'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-01-31 09:33:17,742 [salt.state       :1951][INFO    ][7399] Completed state [maas.process_maas_config] at time 09:33:17.742793 duration_in_ms=1738.901
2020-01-31 09:33:17,743 [salt.state       :1780][INFO    ][7399] Running state [pxe_admin] at time 09:33:17.743606
2020-01-31 09:33:17,744 [salt.state       :1813][INFO    ][7399] Executing state maasng.fabric_present for [pxe_admin]
2020-01-31 09:33:18,028 [salt.loaded.ext.module.maasng:945 ][INFO    ][7399] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-01-31 09:33:18,343 [salt.loaded.ext.module.maasng:1008][WARNING ][7399] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2020-01-31 09:33:18,343 [salt.loaded.ext.module.maasng:1011][WARNING ][7399] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2020-01-31 09:33:18,383 [salt.state       :300 ][INFO    ][7399] {'new': 'Fabric  pxe_admin created', 'result': True}
2020-01-31 09:33:18,384 [salt.state       :1951][INFO    ][7399] Completed state [pxe_admin] at time 09:33:18.384183 duration_in_ms=640.575
2020-01-31 09:33:18,384 [salt.state       :1780][INFO    ][7399] Running state [vlan 0] at time 09:33:18.384643
2020-01-31 09:33:18,385 [salt.state       :1813][INFO    ][7399] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-01-31 09:33:18,424 [salt.loaded.ext.module.maasng:945 ][INFO    ][7399] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-01-31 09:33:18,577 [salt.loaded.ext.module.maasng:945 ][INFO    ][7399] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-01-31 09:33:18,796 [salt.loaded.ext.module.maasng:945 ][INFO    ][7399] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-01-31 09:33:18,864 [salt.state       :300 ][INFO    ][7399] {'new': 'Vlan untagged was updated'}
2020-01-31 09:33:18,864 [salt.state       :1951][INFO    ][7399] Completed state [vlan 0] at time 09:33:18.864457 duration_in_ms=479.814
2020-01-31 09:33:18,865 [salt.state       :1780][INFO    ][7399] Running state [192.168.11.0/24] at time 09:33:18.865802
2020-01-31 09:33:18,866 [salt.state       :1813][INFO    ][7399] Executing state maasng.subnet_present for [192.168.11.0/24]
2020-01-31 09:33:19,007 [salt.loaded.ext.module.maasng:945 ][INFO    ][7399] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'x8c8r8', 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-01-31 09:33:19,008 [salt.loaded.ext.module.maasng:1235][WARNING ][7399] Ignoring parameter vlan:0
2020-01-31 09:33:19,054 [salt.state       :300 ][INFO    ][7399] Subnet 192.168.11.0/24 has been updated for pxe_admin
2020-01-31 09:33:19,055 [salt.state       :1951][INFO    ][7399] Completed state [192.168.11.0/24] at time 09:33:19.054979 duration_in_ms=189.176
2020-01-31 09:33:19,055 [salt.state       :1780][INFO    ][7399] Running state [maas_create_iprange_1] at time 09:33:19.055756
2020-01-31 09:33:19,056 [salt.state       :1813][INFO    ][7399] Executing state maasng.iprange_present for [maas_create_iprange_1]
2020-01-31 09:33:19,093 [salt.state       :300 ][INFO    ][7399] Iprange maas_create_iprange_1 already exist.
2020-01-31 09:33:19,094 [salt.state       :1951][INFO    ][7399] Completed state [maas_create_iprange_1] at time 09:33:19.094326 duration_in_ms=38.57
2020-01-31 09:33:19,094 [salt.state       :1780][INFO    ][7399] Running state [vlan 0] at time 09:33:19.094602
2020-01-31 09:33:19,094 [salt.state       :1813][INFO    ][7399] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-01-31 09:33:19,143 [salt.loaded.ext.module.maasng:945 ][INFO    ][7399] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-01-31 09:33:19,215 [salt.loaded.ext.module.maasng:945 ][INFO    ][7399] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-01-31 09:33:19,473 [salt.loaded.ext.module.maasng:945 ][INFO    ][7399] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-01-31 09:33:19,566 [salt.state       :300 ][INFO    ][7399] {'new': 'Vlan untagged was updated'}
2020-01-31 09:33:19,566 [salt.state       :1951][INFO    ][7399] Completed state [vlan 0] at time 09:33:19.566532 duration_in_ms=471.929
2020-01-31 09:33:19,567 [salt.state       :1780][INFO    ][7399] Running state [opnfv] at time 09:33:19.567523
2020-01-31 09:33:19,568 [salt.state       :1813][INFO    ][7399] Executing state maasng.sshkey_present for [opnfv]
2020-01-31 09:33:19,596 [salt.loaded.ext.module.maasng:1903][INFO    ][7399] [{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-01-31 09:33:19,597 [salt.state       :300 ][INFO    ][7399] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2020-01-31 09:33:19,598 [salt.state       :1951][INFO    ][7399] Completed state [opnfv] at time 09:33:19.598095 duration_in_ms=30.573
2020-01-31 09:33:19,602 [salt.minion      :1711][INFO    ][7399] Returning information for job: 20200131093257038929
2020-01-31 09:33:20,456 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command state.apply with jid 20200131093320438806
2020-01-31 09:33:20,499 [salt.minion      :1432][INFO    ][7517] Starting a new job with PID 7517
2020-01-31 09:33:26,689 [salt.state       :915 ][INFO    ][7517] Loading fresh modules for state activity
2020-01-31 09:33:26,784 [salt.state       :1780][INFO    ][7517] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:33:26.784071
2020-01-31 09:33:26,784 [salt.state       :1813][INFO    ][7517] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-31 09:33:26,786 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7517] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-31 09:33:28,730 [salt.state       :300 ][INFO    ][7517] {'pid': 7547, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-31 09:33:28,731 [salt.state       :1951][INFO    ][7517] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:33:28.731168 duration_in_ms=1947.097
2020-01-31 09:33:28,732 [salt.state       :1780][INFO    ][7517] Running state [maas.process_machines] at time 09:33:28.732826
2020-01-31 09:33:28,733 [salt.state       :1813][INFO    ][7517] Executing state module.run for [maas.process_machines]
2020-01-31 09:33:28,734 [salt.utils.decorators:613 ][WARNING ][7517] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-31 09:33:29,352 [salt.loaded.ext.module.maas:412 ][WARNING ][7517] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-31 09:33:29,353 [salt.loaded.ext.module.maas:92  ][INFO    ][7517] 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=r3w6n8 architecture=amd64/generic power_parameters_power_user=opnfv
2020-01-31 09:33:30,090 [salt.loaded.ext.module.maas:412 ][WARNING ][7517] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-31 09:33:30,091 [salt.loaded.ext.module.maas:92  ][INFO    ][7517] 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=64gfhp architecture=amd64/generic power_parameters_power_user=opnfv
2020-01-31 09:33:30,827 [salt.loaded.ext.module.maas:412 ][WARNING ][7517] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-31 09:33:30,828 [salt.loaded.ext.module.maas:92  ][INFO    ][7517] machine hostname=kvm01 power_type=ipmi mac_addresses=['14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=wx33xe architecture=amd64/generic power_parameters_power_user=opnfv
2020-01-31 09:33:31,611 [salt.loaded.ext.module.maas:412 ][WARNING ][7517] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-31 09:33:31,612 [salt.loaded.ext.module.maas:92  ][INFO    ][7517] machine hostname=kvm03 power_type=ipmi mac_addresses=['14:58:d0:54:7a:28'] power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 system_id=qyaery architecture=amd64/generic power_parameters_power_user=opnfv
2020-01-31 09:33:32,355 [salt.loaded.ext.module.maas:412 ][WARNING ][7517] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-01-31 09:33:32,356 [salt.loaded.ext.module.maas:92  ][INFO    ][7517] machine hostname=kvm02 power_type=ipmi mac_addresses=['14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=ydcs6h architecture=amd64/generic power_parameters_power_user=opnfv
2020-01-31 09:33:33,104 [salt.state       :300 ][INFO    ][7517] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2020-01-31 09:33:33,105 [salt.state       :1951][INFO    ][7517] Completed state [maas.process_machines] at time 09:33:33.105101 duration_in_ms=4372.273
2020-01-31 09:33:33,110 [salt.minion      :1711][INFO    ][7517] Returning information for job: 20200131093320438806
2020-01-31 09:34:06,804 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command state.apply with jid 20200131093406785031
2020-01-31 09:34:06,830 [salt.minion      :1432][INFO    ][7577] Starting a new job with PID 7577
2020-01-31 09:34:12,931 [salt.state       :915 ][INFO    ][7577] Loading fresh modules for state activity
2020-01-31 09:34:13,040 [salt.state       :1780][INFO    ][7577] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:34:13.040564
2020-01-31 09:34:13,040 [salt.state       :1813][INFO    ][7577] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-31 09:34:13,042 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7577] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-31 09:34:15,062 [salt.state       :300 ][INFO    ][7577] {'pid': 7584, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-31 09:34:15,063 [salt.state       :1951][INFO    ][7577] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:34:15.063128 duration_in_ms=2022.565
2020-01-31 09:34:15,067 [salt.state       :1780][INFO    ][7577] Running state [maas.wait_for_machine_status] at time 09:34:15.067388
2020-01-31 09:34:15,067 [salt.state       :1813][INFO    ][7577] Executing state module.run for [maas.wait_for_machine_status]
2020-01-31 09:34:15,068 [salt.utils.decorators:613 ][WARNING ][7577] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-31 09:34:18,334 [salt.state       :300 ][INFO    ][7577] {'ret': True}
2020-01-31 09:34:18,334 [salt.state       :1951][INFO    ][7577] Completed state [maas.wait_for_machine_status] at time 09:34:18.334779 duration_in_ms=3267.389
2020-01-31 09:34:18,338 [salt.minion      :1711][INFO    ][7577] Returning information for job: 20200131093406785031
2020-01-31 09:34:19,111 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command state.apply with jid 20200131093419099427
2020-01-31 09:34:19,138 [salt.minion      :1432][INFO    ][7603] Starting a new job with PID 7603
2020-01-31 09:34:20,058 [salt.state       :915 ][INFO    ][7603] Loading fresh modules for state activity
2020-01-31 09:34:20,199 [salt.state       :1780][INFO    ][7603] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:34:20.199819
2020-01-31 09:34:20,200 [salt.state       :1813][INFO    ][7603] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-31 09:34:20,202 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7603] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-31 09:34:22,162 [salt.state       :300 ][INFO    ][7603] {'pid': 7610, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-31 09:34:22,163 [salt.state       :1951][INFO    ][7603] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:34:22.163098 duration_in_ms=1963.276
2020-01-31 09:34:22,166 [salt.state       :1780][INFO    ][7603] Running state [maas_machines_storage_cmp002_lvm] at time 09:34:22.166260
2020-01-31 09:34:22,166 [salt.state       :1813][INFO    ][7603] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2020-01-31 09:34:23,559 [salt.loaded.ext.module.maasng:610 ][INFO    ][7603] r3w6n8
2020-01-31 09:34:23,560 [salt.loaded.ext.module.maasng:626 ][INFO    ][7603] sda
2020-01-31 09:34:24,158 [salt.loaded.ext.module.maasng:361 ][INFO    ][7603] r3w6n8
2020-01-31 09:34:24,236 [salt.loaded.ext.module.maasng:367 ][INFO    ][7603] [{u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'b3c82407-1bf4-4912-8986-978e2ce36656', u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/1/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r3w6n8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'ed25640a-7274-4fc0-aa99-951cf05eb9dc', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 8, u'size': 800101236736}], u'filesystem': None, u'uuid': None, u'id': 1, u'system_id': u'r3w6n8', 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'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/13/', u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'e1464294-76f8-4dff-9737-8249fe47985a', u'fstype': u'ext4'}, u'uuid': u'aa7e56fc-0bf3-4a13-afd1-f14e6d691a22', u'id': 13, u'system_id': u'r3w6n8', 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'type': u'virtual', u'serial': None, u'size': 800097042432}]
2020-01-31 09:34:24,236 [salt.loaded.ext.module.maasng:632 ][INFO    ][7603] vgroot
2020-01-31 09:34:24,236 [salt.loaded.ext.module.maasng:635 ][INFO    ][7603] lvroot
2020-01-31 09:34:24,236 [salt.loaded.ext.module.maasng:639 ][INFO    ][7603] 107374182400
2020-01-31 09:34:24,840 [salt.loaded.ext.module.maasng:645 ][INFO    ][7603] {u'node_type_name': u'Machine', u'hwe_kernel': None, u'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-18.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'cpu_speed': 2800, u'owner': None, u'pod': None, u'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/r3w6n8/', u'current_commissioning_result_id': 20, 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'r3w6n8', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/15/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'2ddc2d18-2bee-4a0d-b52c-50de6a76346d', u'fstype': u'ext4'}, u'uuid': u'4bd2fee8-c6e8-4ec6-aaeb-f7adc8fff9ce', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'r3w6n8', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 15, u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'd822d498-4996-4594-bfae-b509703d4e1d', u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/1/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r3w6n8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'773fd5c7-331f-40ef-a68d-e16114704258', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 10, u'device_id': 1}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'r3w6n8', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/15/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'2ddc2d18-2bee-4a0d-b52c-50de6a76346d', u'fstype': u'ext4'}, u'uuid': u'4bd2fee8-c6e8-4ec6-aaeb-f7adc8fff9ce', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'r3w6n8', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 15, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'r3w6n8', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id': 1, u'available_size': 0, u'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'd822d498-4996-4594-bfae-b509703d4e1d', u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/1/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r3w6n8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'773fd5c7-331f-40ef-a68d-e16114704258', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 10, u'device_id': 1}]}], u'ip_addresses': [], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'r3w6n8', u'id': 10}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 21, 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'other_test_status': -1, u'locked': False, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 64, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'r3w6n8', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/interfaces/4/'}, u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 64, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'r3w6n8', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/interfaces/4/'}, {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'system_id': u'r3w6n8', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/interfaces/11/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 65, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'r3w6n8', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/interfaces/12/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'r3w6n8', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/interfaces/13/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 66, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'r3w6n8', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/interfaces/14/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 67, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'r3w6n8', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/interfaces/15/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'r3w6n8', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id': 1, u'available_size': 0, u'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'd822d498-4996-4594-bfae-b509703d4e1d', u'resource_uri': u'/MAAS/api/2.0/nodes/r3w6n8/blockdevices/1/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r3w6n8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'773fd5c7-331f-40ef-a68d-e16114704258', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 10, u'device_id': 1}]}}
2020-01-31 09:34:24,843 [salt.state       :300 ][INFO    ][7603] {'new': {'storage_layout': 'lvm'}}
2020-01-31 09:34:24,844 [salt.state       :1951][INFO    ][7603] Completed state [maas_machines_storage_cmp002_lvm] at time 09:34:24.844151 duration_in_ms=2677.889
2020-01-31 09:34:24,844 [salt.state       :1780][INFO    ][7603] Running state [maas_machines_storage_cmp001_lvm] at time 09:34:24.844774
2020-01-31 09:34:24,845 [salt.state       :1813][INFO    ][7603] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2020-01-31 09:34:26,100 [salt.loaded.ext.module.maasng:610 ][INFO    ][7603] 64gfhp
2020-01-31 09:34:26,101 [salt.loaded.ext.module.maasng:626 ][INFO    ][7603] sda
2020-01-31 09:34:26,684 [salt.loaded.ext.module.maasng:361 ][INFO    ][7603] 64gfhp
2020-01-31 09:34:26,756 [salt.loaded.ext.module.maasng:367 ][INFO    ][7603] [{u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'4f2c0184-4abd-48e1-8725-a900350f30e1', u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/2/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'64gfhp', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'01dd1260-ebe0-4c18-88cf-39dd653d1092', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 9, u'device_id': 2}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'64gfhp', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/14/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'6adbf11f-7532-4d50-b6ed-a3046f99c0a6', u'fstype': u'ext4'}, u'uuid': u'db750670-92b9-42a0-a57f-e9395210f703', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'64gfhp', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 14, u'serial': None, u'size': 800097042432}]
2020-01-31 09:34:26,756 [salt.loaded.ext.module.maasng:632 ][INFO    ][7603] vgroot
2020-01-31 09:34:26,756 [salt.loaded.ext.module.maasng:635 ][INFO    ][7603] lvroot
2020-01-31 09:34:26,757 [salt.loaded.ext.module.maasng:639 ][INFO    ][7603] 107374182400
2020-01-31 09:34:27,299 [salt.loaded.ext.module.maasng:645 ][INFO    ][7603] {u'node_type_name': u'Machine', u'hwe_kernel': None, u'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-18.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'cpu_speed': 2800, u'owner': None, u'pod': None, u'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/64gfhp/', u'current_commissioning_result_id': 24, u'hardware_info': {u'mainboard_firmware_version': u'I31', u'mainboard_product': u'Unknown', u'system_vendor': u'HP', u'cpu_model': u'Intel(R) Xeon(R) CPU E5-2680 v2', u'mainboard_firmware_date': u'06/01/2015', u'mainboard_vendor': u'Unknown', u'system_serial': u'USE410T2XY', u'system_version': u'Unknown', u'system_product': u'ProLiant BL460c Gen8 (735151-B21)'}, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'64gfhp', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/16/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'af15291f-6632-4973-806e-86c7991a2291', u'fstype': u'ext4'}, u'uuid': u'68015012-4034-4428-8b47-e1073ac48fc1', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'64gfhp', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 16, u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'2a74a495-c2d7-4828-be1d-5caf10d690a9', u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/2/partition/11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'64gfhp', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'6defd756-8368-43aa-8919-7f24198e65d2', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 11, u'device_id': 2}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'64gfhp', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/16/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'af15291f-6632-4973-806e-86c7991a2291', u'fstype': u'ext4'}, u'uuid': u'68015012-4034-4428-8b47-e1073ac48fc1', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'64gfhp', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 16, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u'64gfhp', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id': 2, u'available_size': 0, u'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'2a74a495-c2d7-4828-be1d-5caf10d690a9', u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/2/partition/11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'64gfhp', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'6defd756-8368-43aa-8919-7f24198e65d2', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 11, u'device_id': 2}]}], u'ip_addresses': [], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'64gfhp', u'id': 11}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 25, 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'other_test_status': -1, u'locked': False, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 74, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'64gfhp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/interfaces/5/'}, u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 74, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'64gfhp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/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:1f:d4', u'system_id': u'64gfhp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/interfaces/16/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 75, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'x8c8r8', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'64gfhp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/interfaces/17/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'64gfhp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/interfaces/18/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 76, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'64gfhp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/interfaces/19/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 77, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'64gfhp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/interfaces/20/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u'64gfhp', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id': 2, u'available_size': 0, u'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'2a74a495-c2d7-4828-be1d-5caf10d690a9', u'resource_uri': u'/MAAS/api/2.0/nodes/64gfhp/blockdevices/2/partition/11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'64gfhp', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'6defd756-8368-43aa-8919-7f24198e65d2', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 11, u'device_id': 2}]}}
2020-01-31 09:34:27,300 [salt.state       :300 ][INFO    ][7603] {'new': {'storage_layout': 'lvm'}}
2020-01-31 09:34:27,300 [salt.state       :1951][INFO    ][7603] Completed state [maas_machines_storage_cmp001_lvm] at time 09:34:27.300733 duration_in_ms=2455.959
2020-01-31 09:34:27,303 [salt.minion      :1711][INFO    ][7603] Returning information for job: 20200131093419099427
2020-01-31 09:34:28,034 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command state.apply with jid 20200131093428022057
2020-01-31 09:34:28,056 [salt.minion      :1432][INFO    ][7642] Starting a new job with PID 7642
2020-01-31 09:34:28,976 [salt.state       :915 ][INFO    ][7642] Loading fresh modules for state activity
2020-01-31 09:34:29,070 [salt.state       :1780][INFO    ][7642] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:34:29.070878
2020-01-31 09:34:29,071 [salt.state       :1813][INFO    ][7642] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-31 09:34:29,072 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7642] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-31 09:34:30,957 [salt.state       :300 ][INFO    ][7642] {'pid': 7649, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-31 09:34:30,958 [salt.state       :1951][INFO    ][7642] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:34:30.958638 duration_in_ms=1887.76
2020-01-31 09:34:30,961 [salt.state       :1780][INFO    ][7642] Running state [maas.deploy_machines] at time 09:34:30.960947
2020-01-31 09:34:30,961 [salt.state       :1813][INFO    ][7642] Executing state module.run for [maas.deploy_machines]
2020-01-31 09:34:30,962 [salt.utils.decorators:613 ][WARNING ][7642] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-31 09:34:31,553 [salt.loaded.ext.module.maas:684 ][INFO    ][7642] deploymachines hwe_kernel=ga-18.04 system_id=r3w6n8 distro_series=bionic
2020-01-31 09:34:33,430 [salt.loaded.ext.module.maas:684 ][INFO    ][7642] deploymachines hwe_kernel=ga-18.04 system_id=64gfhp distro_series=bionic
2020-01-31 09:34:35,374 [salt.state       :300 ][INFO    ][7642] {'ret': {'updated': ['kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': ['cmp002', 'cmp001']}}
2020-01-31 09:34:35,375 [salt.state       :1951][INFO    ][7642] Completed state [maas.deploy_machines] at time 09:34:35.375173 duration_in_ms=4414.224
2020-01-31 09:34:35,380 [salt.minion      :1711][INFO    ][7642] Returning information for job: 20200131093428022057
2020-01-31 09:34:36,192 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command state.apply with jid 20200131093436144247
2020-01-31 09:34:36,214 [salt.minion      :1432][INFO    ][7670] Starting a new job with PID 7670
2020-01-31 09:34:42,331 [salt.state       :915 ][INFO    ][7670] Loading fresh modules for state activity
2020-01-31 09:34:42,431 [salt.state       :1780][INFO    ][7670] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:34:42.431044
2020-01-31 09:34:42,431 [salt.state       :1813][INFO    ][7670] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-01-31 09:34:42,433 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7670] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-01-31 09:34:44,284 [salt.state       :300 ][INFO    ][7670] {'pid': 7681, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-01-31 09:34:44,285 [salt.state       :1951][INFO    ][7670] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:34:44.285240 duration_in_ms=1854.197
2020-01-31 09:34:44,287 [salt.state       :1780][INFO    ][7670] Running state [maas.wait_for_machine_status] at time 09:34:44.287368
2020-01-31 09:34:44,287 [salt.state       :1813][INFO    ][7670] Executing state module.run for [maas.wait_for_machine_status]
2020-01-31 09:34:44,288 [salt.utils.decorators:613 ][WARNING ][7670] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-01-31 09:34:47,812 [salt.loaded.ext.module.maas:1024][INFO    ][7670] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2246.48319817s left)
2020-01-31 09:34:51,188 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131093451173032
2020-01-31 09:34:51,218 [salt.minion      :1432][INFO    ][7700] Starting a new job with PID 7700
2020-01-31 09:34:51,252 [salt.minion      :1711][INFO    ][7700] Returning information for job: 20200131093451173032
2020-01-31 09:35:20,999 [salt.loaded.ext.module.maas:1024][INFO    ][7670] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2213.29594111s left)
2020-01-31 09:35:21,280 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131093521268799
2020-01-31 09:35:21,302 [salt.minion      :1432][INFO    ][7728] Starting a new job with PID 7728
2020-01-31 09:35:21,325 [salt.minion      :1711][INFO    ][7728] Returning information for job: 20200131093521268799
2020-01-31 09:35:51,335 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131093551326117
2020-01-31 09:35:51,357 [salt.minion      :1432][INFO    ][7772] Starting a new job with PID 7772
2020-01-31 09:35:51,383 [salt.minion      :1711][INFO    ][7772] Returning information for job: 20200131093551326117
2020-01-31 09:35:54,197 [salt.loaded.ext.module.maas:1024][INFO    ][7670] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2180.09840107s left)
2020-01-31 09:36:21,405 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131093621396815
2020-01-31 09:36:21,428 [salt.minion      :1432][INFO    ][7792] Starting a new job with PID 7792
2020-01-31 09:36:21,448 [salt.minion      :1711][INFO    ][7792] Returning information for job: 20200131093621396815
2020-01-31 09:36:27,367 [salt.loaded.ext.module.maas:1024][INFO    ][7670] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2146.92793012s left)
2020-01-31 09:36:51,458 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131093651448998
2020-01-31 09:36:51,481 [salt.minion      :1432][INFO    ][7827] Starting a new job with PID 7827
2020-01-31 09:36:51,500 [salt.minion      :1711][INFO    ][7827] Returning information for job: 20200131093651448998
2020-01-31 09:37:00,460 [salt.loaded.ext.module.maas:1024][INFO    ][7670] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2113.83501101s left)
2020-01-31 09:37:21,535 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131093721521054
2020-01-31 09:37:21,561 [salt.minion      :1432][INFO    ][7857] Starting a new job with PID 7857
2020-01-31 09:37:21,580 [salt.minion      :1711][INFO    ][7857] Returning information for job: 20200131093721521054
2020-01-31 09:37:33,794 [salt.loaded.ext.module.maas:1024][INFO    ][7670] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2080.50131512s left)
2020-01-31 09:37:51,601 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131093751586264
2020-01-31 09:37:51,628 [salt.minion      :1432][INFO    ][7920] Starting a new job with PID 7920
2020-01-31 09:37:51,651 [salt.minion      :1711][INFO    ][7920] Returning information for job: 20200131093751586264
2020-01-31 09:38:07,328 [salt.loaded.ext.module.maas:1024][INFO    ][7670] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2046.96699405s left)
2020-01-31 09:38:21,715 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131093821699815
2020-01-31 09:38:21,742 [salt.minion      :1432][INFO    ][7964] Starting a new job with PID 7964
2020-01-31 09:38:21,764 [salt.minion      :1711][INFO    ][7964] Returning information for job: 20200131093821699815
2020-01-31 09:38:41,041 [salt.loaded.ext.module.maas:1024][INFO    ][7670] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2013.25378609s left)
2020-01-31 09:38:51,803 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131093851788467
2020-01-31 09:38:51,828 [salt.minion      :1432][INFO    ][8000] Starting a new job with PID 8000
2020-01-31 09:38:51,849 [salt.minion      :1711][INFO    ][8000] Returning information for job: 20200131093851788467
2020-01-31 09:39:14,588 [salt.loaded.ext.module.maas:1024][INFO    ][7670] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1979.70674801s left)
2020-01-31 09:39:21,890 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131093921875357
2020-01-31 09:39:21,917 [salt.minion      :1432][INFO    ][8021] Starting a new job with PID 8021
2020-01-31 09:39:21,939 [salt.minion      :1711][INFO    ][8021] Returning information for job: 20200131093921875357
2020-01-31 09:39:48,103 [salt.loaded.ext.module.maas:1024][INFO    ][7670] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1946.19244599s left)
2020-01-31 09:39:51,983 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131093951975832
2020-01-31 09:39:52,007 [salt.minion      :1432][INFO    ][8055] Starting a new job with PID 8055
2020-01-31 09:39:52,024 [salt.minion      :1711][INFO    ][8055] Returning information for job: 20200131093951975832
2020-01-31 09:40:21,631 [salt.loaded.ext.module.maas:1024][INFO    ][7670] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1912.663867s left)
2020-01-31 09:40:22,065 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131094022053984
2020-01-31 09:40:22,094 [salt.minion      :1432][INFO    ][8076] Starting a new job with PID 8076
2020-01-31 09:40:22,117 [salt.minion      :1711][INFO    ][8076] Returning information for job: 20200131094022053984
2020-01-31 09:40:52,186 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131094052173088
2020-01-31 09:40:52,205 [salt.minion      :1432][INFO    ][8110] Starting a new job with PID 8110
2020-01-31 09:40:52,226 [salt.minion      :1711][INFO    ][8110] Returning information for job: 20200131094052173088
2020-01-31 09:40:55,221 [salt.loaded.ext.module.maas:1024][INFO    ][7670] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1879.07460213s left)
2020-01-31 09:41:22,250 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131094122239656
2020-01-31 09:41:22,275 [salt.minion      :1432][INFO    ][8130] Starting a new job with PID 8130
2020-01-31 09:41:22,294 [salt.minion      :1711][INFO    ][8130] Returning information for job: 20200131094122239656
2020-01-31 09:41:28,664 [salt.loaded.ext.module.maas:1024][INFO    ][7670] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1845.63090301s left)
2020-01-31 09:41:52,346 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131094152335134
2020-01-31 09:41:52,366 [salt.minion      :1432][INFO    ][8165] Starting a new job with PID 8165
2020-01-31 09:41:52,388 [salt.minion      :1711][INFO    ][8165] Returning information for job: 20200131094152335134
2020-01-31 09:42:02,187 [salt.loaded.ext.module.maas:1024][INFO    ][7670] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1812.10774016s left)
2020-01-31 09:42:22,423 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131094222412047
2020-01-31 09:42:22,447 [salt.minion      :1432][INFO    ][8184] Starting a new job with PID 8184
2020-01-31 09:42:22,465 [salt.minion      :1711][INFO    ][8184] Returning information for job: 20200131094222412047
2020-01-31 09:42:35,562 [salt.loaded.ext.module.maas:1024][INFO    ][7670] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1778.73264003s left)
2020-01-31 09:42:52,509 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131094252496399
2020-01-31 09:42:52,547 [salt.minion      :1432][INFO    ][8220] Starting a new job with PID 8220
2020-01-31 09:42:52,573 [salt.minion      :1711][INFO    ][8220] Returning information for job: 20200131094252496399
2020-01-31 09:43:08,884 [salt.loaded.ext.module.maas:1024][INFO    ][7670] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1745.41105914s left)
2020-01-31 09:43:22,645 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131094322630518
2020-01-31 09:43:22,670 [salt.minion      :1432][INFO    ][8244] Starting a new job with PID 8244
2020-01-31 09:43:22,692 [salt.minion      :1711][INFO    ][8244] Returning information for job: 20200131094322630518
2020-01-31 09:43:42,335 [salt.loaded.ext.module.maas:1024][INFO    ][7670] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1711.96035314s left)
2020-01-31 09:43:52,800 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131094352786595
2020-01-31 09:43:52,832 [salt.minion      :1432][INFO    ][8279] Starting a new job with PID 8279
2020-01-31 09:43:52,858 [salt.minion      :1711][INFO    ][8279] Returning information for job: 20200131094352786595
2020-01-31 09:44:15,924 [salt.loaded.ext.module.maas:1024][INFO    ][7670] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1678.37111998s left)
2020-01-31 09:44:22,904 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131094422896569
2020-01-31 09:44:22,928 [salt.minion      :1432][INFO    ][8305] Starting a new job with PID 8305
2020-01-31 09:44:22,954 [salt.minion      :1711][INFO    ][8305] Returning information for job: 20200131094422896569
2020-01-31 09:44:49,407 [salt.loaded.ext.module.maas:1024][INFO    ][7670] Waiting status:Deployed for machines:['cmp001']
sleep for:30s Timeout:2250s (1644.88800812s left)
2020-01-31 09:44:53,035 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131094453024739
2020-01-31 09:44:53,061 [salt.minion      :1432][INFO    ][8350] Starting a new job with PID 8350
2020-01-31 09:44:53,091 [salt.minion      :1711][INFO    ][8350] Returning information for job: 20200131094453024739
2020-01-31 09:45:22,927 [salt.loaded.ext.module.maas:1024][INFO    ][7670] Waiting status:Deployed for machines:['cmp001']
sleep for:30s Timeout:2250s (1611.36847806s left)
2020-01-31 09:45:23,200 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131094523187540
2020-01-31 09:45:23,226 [salt.minion      :1432][INFO    ][8394] Starting a new job with PID 8394
2020-01-31 09:45:23,244 [salt.minion      :1711][INFO    ][8394] Returning information for job: 20200131094523187540
2020-01-31 09:45:53,295 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command saltutil.find_job with jid 20200131094553285118
2020-01-31 09:45:53,319 [salt.minion      :1432][INFO    ][8446] Starting a new job with PID 8446
2020-01-31 09:45:53,345 [salt.minion      :1711][INFO    ][8446] Returning information for job: 20200131094553285118
2020-01-31 09:45:56,398 [salt.state       :300 ][INFO    ][7670] {'ret': True}
2020-01-31 09:45:56,398 [salt.state       :1951][INFO    ][7670] Completed state [maas.wait_for_machine_status] at time 09:45:56.398594 duration_in_ms=672111.224
2020-01-31 09:45:56,402 [salt.minion      :1711][INFO    ][7670] Returning information for job: 20200131093436144247
2020-01-31 09:46:29,768 [salt.utils.schedule:1377][INFO    ][3636] Running scheduled job: __mine_interval
2020-01-31 10:46:29,768 [salt.utils.schedule:1377][INFO    ][3636] Running scheduled job: __mine_interval
2020-01-31 11:20:50,824 [salt.minion      :1308][INFO    ][3636] User sudo_ubuntu Executing command cp.push_dir with jid 20200131112050813078
2020-01-31 11:20:50,845 [salt.minion      :1432][INFO    ][12935] Starting a new job with PID 12935
