2020-02-22 18:48:30,451 [salt.utils.decorators:613 ][WARNING ][1542] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 18:48:31,582 [salt.utils.decorators:613 ][WARNING ][1542] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 18:48:41,486 [salt.loaded.int.states.file:2298][WARNING ][1702] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2020-02-22 18:48:50,608 [salt.state       :2022][WARNING ][1894] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-02-22 18:48:51,404 [salt.utils.decorators:613 ][WARNING ][1894] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 18:49:08,918 [salt.utils.decorators:613 ][WARNING ][1894] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 18:49:31,147 [salt.utils.decorators:613 ][WARNING ][1894] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 18:49:31,871 [salt.utils.decorators:613 ][WARNING ][1894] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 18:49:34,280 [salt.loaded.ext.module.maasng:1008][WARNING ][1894] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2020-02-22 18:49:34,281 [salt.loaded.ext.module.maasng:1011][WARNING ][1894] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2020-02-22 18:49:34,817 [salt.loaded.ext.module.maasng:1235][WARNING ][1894] Ignoring parameter vlan:0
2020-02-22 18:49:46,067 [salt.utils.decorators:613 ][WARNING ][3297] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 18:49:46,115 [salt.loaded.ext.module.maas:412 ][WARNING ][3297] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-22 18:49:46,977 [salt.loaded.ext.module.maas:412 ][WARNING ][3297] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-22 18:49:47,802 [salt.loaded.ext.module.maas:412 ][WARNING ][3297] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-22 18:49:48,689 [salt.loaded.ext.module.maas:412 ][WARNING ][3297] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-22 18:49:49,556 [salt.loaded.ext.module.maas:412 ][WARNING ][3297] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-22 18:49:56,737 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3609] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2020-02-22 18:49:56,792 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3609] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2020-02-22 18:49:56,853 [salt.utils.parsers:1051][WARNING ][154] Minion received a SIGTERM. Exiting.
2020-02-22 18:49:58,063 [salt.cli.daemons :293 ][INFO    ][3659] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2020-02-22 18:49:58,315 [salt.cli.daemons :82  ][INFO    ][3659] Starting up the Salt Minion
2020-02-22 18:49:58,316 [salt.utils.event :1017][INFO    ][3659] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2020-02-22 18:49:59,759 [salt.minion      :976 ][INFO    ][3659] Creating minion process manager
2020-02-22 18:50:01,840 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3659] Executing command ['date', '+%z'] in directory '/root'
2020-02-22 18:50:01,867 [salt.utils.schedule:568 ][INFO    ][3659] Updating job settings for scheduled job: __mine_interval
2020-02-22 18:50:01,869 [salt.minion      :1108][INFO    ][3659] Added mine.update to scheduler
2020-02-22 18:50:01,878 [salt.minion      :1975][INFO    ][3659] Minion is starting as user 'root'
2020-02-22 18:50:01,903 [salt.minion      :2336][INFO    ][3659] Minion is ready to receive requests!
2020-02-22 18:50:22,213 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command state.apply with jid 20200222185022199464
2020-02-22 18:50:22,239 [salt.minion      :1432][INFO    ][3747] Starting a new job with PID 3747
2020-02-22 18:50:28,709 [salt.state       :915 ][INFO    ][3747] Loading fresh modules for state activity
2020-02-22 18:50:28,769 [salt.fileclient  :1219][INFO    ][3747] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2020-02-22 18:50:28,822 [salt.state       :1780][INFO    ][3747] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:50:28.822230
2020-02-22 18:50:28,822 [salt.state       :1813][INFO    ][3747] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-22 18:50:28,827 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3747] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-22 18:50:31,149 [salt.state       :300 ][INFO    ][3747] {'pid': 3767, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-22 18:50:31,150 [salt.state       :1951][INFO    ][3747] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:50:31.150439 duration_in_ms=2328.208
2020-02-22 18:50:31,154 [salt.state       :1780][INFO    ][3747] Running state [maas.wait_for_machine_status] at time 18:50:31.154780
2020-02-22 18:50:31,155 [salt.state       :1813][INFO    ][3747] Executing state module.run for [maas.wait_for_machine_status]
2020-02-22 18:50:31,156 [salt.utils.decorators:613 ][WARNING ][3747] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 18:50:32,306 [salt.loaded.ext.module.maas:1024][INFO    ][3747] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.87342596s left)
2020-02-22 18:50:37,337 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222185037326748
2020-02-22 18:50:37,362 [salt.minion      :1432][INFO    ][3776] Starting a new job with PID 3776
2020-02-22 18:50:37,386 [salt.minion      :1711][INFO    ][3776] Returning information for job: 20200222185037326748
2020-02-22 18:51:03,318 [salt.loaded.ext.module.maas:1024][INFO    ][3747] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.86063886s left)
2020-02-22 18:51:07,400 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222185107385712
2020-02-22 18:51:07,427 [salt.minion      :1432][INFO    ][3803] Starting a new job with PID 3803
2020-02-22 18:51:07,457 [salt.minion      :1711][INFO    ][3803] Returning information for job: 20200222185107385712
2020-02-22 18:51:34,207 [salt.loaded.ext.module.maas:1024][INFO    ][3747] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.97158289s left)
2020-02-22 18:51:37,495 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222185137481272
2020-02-22 18:51:37,521 [salt.minion      :1432][INFO    ][3827] Starting a new job with PID 3827
2020-02-22 18:51:37,544 [salt.minion      :1711][INFO    ][3827] Returning information for job: 20200222185137481272
2020-02-22 18:52:05,290 [salt.loaded.ext.module.maas:1024][INFO    ][3747] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.8886199s left)
2020-02-22 18:52:07,563 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222185207550721
2020-02-22 18:52:07,590 [salt.minion      :1432][INFO    ][3855] Starting a new job with PID 3855
2020-02-22 18:52:07,618 [salt.minion      :1711][INFO    ][3855] Returning information for job: 20200222185207550721
2020-02-22 18:52:36,419 [salt.loaded.ext.module.maas:1024][INFO    ][3747] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.76002097s left)
2020-02-22 18:52:37,618 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222185237611361
2020-02-22 18:52:37,639 [salt.minion      :1432][INFO    ][3891] Starting a new job with PID 3891
2020-02-22 18:52:37,666 [salt.minion      :1711][INFO    ][3891] Returning information for job: 20200222185237611361
2020-02-22 18:53:07,684 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222185307668128
2020-02-22 18:53:07,702 [salt.minion      :1432][INFO    ][3942] Starting a new job with PID 3942
2020-02-22 18:53:07,709 [salt.loaded.ext.module.maas:1024][INFO    ][3747] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.47000599s left)
2020-02-22 18:53:07,732 [salt.minion      :1711][INFO    ][3942] Returning information for job: 20200222185307668128
2020-02-22 18:53:37,783 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222185337771005
2020-02-22 18:53:37,806 [salt.minion      :1432][INFO    ][3994] Starting a new job with PID 3994
2020-02-22 18:53:37,835 [salt.minion      :1711][INFO    ][3994] Returning information for job: 20200222185337771005
2020-02-22 18:53:39,029 [salt.loaded.ext.module.maas:1024][INFO    ][3747] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.14960194s left)
2020-02-22 18:54:07,899 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222185407887276
2020-02-22 18:54:07,917 [salt.minion      :1432][INFO    ][4067] Starting a new job with PID 4067
2020-02-22 18:54:07,943 [salt.minion      :1711][INFO    ][4067] Returning information for job: 20200222185407887276
2020-02-22 18:54:10,608 [salt.loaded.ext.module.maas:1024][INFO    ][3747] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1280.57130003s left)
2020-02-22 18:54:37,929 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222185437919514
2020-02-22 18:54:37,950 [salt.minion      :1432][INFO    ][4107] Starting a new job with PID 4107
2020-02-22 18:54:37,977 [salt.minion      :1711][INFO    ][4107] Returning information for job: 20200222185437919514
2020-02-22 18:54:42,702 [salt.loaded.ext.module.maas:1024][INFO    ][3747] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1248.47671604s left)
2020-02-22 18:55:08,075 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222185508060607
2020-02-22 18:55:08,099 [salt.minion      :1432][INFO    ][4202] Starting a new job with PID 4202
2020-02-22 18:55:08,128 [salt.minion      :1711][INFO    ][4202] Returning information for job: 20200222185508060607
2020-02-22 18:55:15,170 [salt.loaded.ext.module.maas:1024][INFO    ][3747] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1216.00890994s left)
2020-02-22 18:55:38,179 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222185538166694
2020-02-22 18:55:38,206 [salt.minion      :1432][INFO    ][4232] Starting a new job with PID 4232
2020-02-22 18:55:38,234 [salt.minion      :1711][INFO    ][4232] Returning information for job: 20200222185538166694
2020-02-22 18:55:47,619 [salt.loaded.ext.module.maas:1024][INFO    ][3747] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1183.56020403s left)
2020-02-22 18:56:08,290 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222185608274169
2020-02-22 18:56:08,321 [salt.minion      :1432][INFO    ][4264] Starting a new job with PID 4264
2020-02-22 18:56:08,347 [salt.minion      :1711][INFO    ][4264] Returning information for job: 20200222185608274169
2020-02-22 18:56:20,363 [salt.loaded.ext.module.maas:1024][INFO    ][3747] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1150.81609488s left)
2020-02-22 18:56:38,451 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222185638437992
2020-02-22 18:56:38,471 [salt.minion      :1432][INFO    ][4344] Starting a new job with PID 4344
2020-02-22 18:56:38,495 [salt.minion      :1711][INFO    ][4344] Returning information for job: 20200222185638437992
2020-02-22 18:56:53,766 [salt.loaded.ext.module.maas:1024][INFO    ][3747] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1117.41307497s left)
2020-02-22 18:57:08,561 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222185708543234
2020-02-22 18:57:08,588 [salt.minion      :1432][INFO    ][4375] Starting a new job with PID 4375
2020-02-22 18:57:08,613 [salt.minion      :1711][INFO    ][4375] Returning information for job: 20200222185708543234
2020-02-22 18:57:27,110 [salt.state       :300 ][INFO    ][3747] {'ret': True}
2020-02-22 18:57:27,110 [salt.state       :1951][INFO    ][3747] Completed state [maas.wait_for_machine_status] at time 18:57:27.110351 duration_in_ms=415955.572
2020-02-22 18:57:27,113 [salt.minion      :1711][INFO    ][3747] Returning information for job: 20200222185022199464
2020-02-22 18:57:28,124 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command state.apply with jid 20200222185728111220
2020-02-22 18:57:28,150 [salt.minion      :1432][INFO    ][4410] Starting a new job with PID 4410
2020-02-22 18:57:34,527 [salt.state       :915 ][INFO    ][4410] Loading fresh modules for state activity
2020-02-22 18:57:34,580 [salt.fileclient  :1219][INFO    ][4410] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2020-02-22 18:57:34,693 [salt.state       :1780][INFO    ][4410] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:57:34.693775
2020-02-22 18:57:34,694 [salt.state       :1813][INFO    ][4410] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-22 18:57:34,696 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4410] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-22 18:57:37,111 [salt.state       :300 ][INFO    ][4410] {'pid': 4417, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-22 18:57:37,112 [salt.state       :1951][INFO    ][4410] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:57:37.112524 duration_in_ms=2418.749
2020-02-22 18:57:37,118 [salt.state       :1780][INFO    ][4410] Running state [maas_machines_storage_cmp002_lvm] at time 18:57:37.118147
2020-02-22 18:57:37,118 [salt.state       :1813][INFO    ][4410] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2020-02-22 18:57:38,584 [salt.loaded.ext.module.maasng:610 ][INFO    ][4410] 7k7h3r
2020-02-22 18:57:38,585 [salt.loaded.ext.module.maasng:626 ][INFO    ][4410] sda
2020-02-22 18:57:39,265 [salt.loaded.ext.module.maasng:361 ][INFO    ][4410] 7k7h3r
2020-02-22 18:57:39,344 [salt.loaded.ext.module.maasng:367 ][INFO    ][4410] [{u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'52f83ac3-dc7b-4e67-954a-7d27014a1662', u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7k7h3r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'4e168b23-13c7-4a94-acd2-7380fe6c299c', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'device_id': 1}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'7k7h3r', u'partition_table_type': u'MBR', u'filesystem': None, 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/7k7h3r/blockdevices/3/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'uuid': u'329a4cc8-55f3-4add-8d67-8b0d46c13057', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'7k7h3r', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'612729db-0004-482f-90f8-18fa988dad4c', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 3, u'serial': None, u'size': 800097042432}]
2020-02-22 18:57:39,345 [salt.loaded.ext.module.maasng:632 ][INFO    ][4410] vgroot
2020-02-22 18:57:39,345 [salt.loaded.ext.module.maasng:635 ][INFO    ][4410] lvroot
2020-02-22 18:57:39,346 [salt.loaded.ext.module.maasng:639 ][INFO    ][4410] 107374182400
2020-02-22 18:57:39,990 [salt.loaded.ext.module.maasng:645 ][INFO    ][4410] {u'hwe_kernel': None, u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], 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'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'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/blockdevices/1/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'7k7h3r', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'firmware_version': u'5.22', u'partitions': [{u'uuid': u'6843161b-6ac0-4ab3-bf3a-da81bb83aac9', u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7k7h3r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'c3ef626d-28f0-4bea-8bd6-18051da7eb4a', 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'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'7k7h3r', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'6843161b-6ac0-4ab3-bf3a-da81bb83aac9', u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7k7h3r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'c3ef626d-28f0-4bea-8bd6-18051da7eb4a', 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'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/blockdevices/11/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'badc9874-83c1-4390-87f5-d2e431ef715e', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'7k7h3r', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'2e94ebab-27c1-487b-bec7-cfb8e2f2f644', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'block_size': 4096, u'type': u'virtual', u'id': 11, u'serial': None, u'partitions': []}], 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/7k7h3r/', 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'node_type': 0, u'testing_status': 2, u'system_id': u'7k7h3r', 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'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eb3pgt', 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': 21, 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'eb3pgt', 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'effective_mtu': 1500, u'id': 4, 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'eb3pgt', 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'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'7k7h3r', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/interfaces/4/'}, u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'7k7h3r', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eb3pgt', 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': 21, 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'eb3pgt', 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'effective_mtu': 1500, u'id': 4, 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'eb3pgt', 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'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'7k7h3r', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/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'effective_mtu': 1500, u'id': 11, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'7k7h3r', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/interfaces/11/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eb3pgt', 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': 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': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eb3pgt', 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'effective_mtu': 1500, u'id': 12, 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'eb3pgt', 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'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'7k7h3r', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/interfaces/12/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 13, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'7k7h3r', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/interfaces/13/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f0', 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'effective_mtu': 1500, u'id': 14, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'7k7h3r', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/interfaces/14/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 24, 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'effective_mtu': 1500, u'id': 15, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'7k7h3r', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/interfaces/15/'}], 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'bcaches': [], u'locked': False, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/blockdevices/1/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'7k7h3r', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'firmware_version': u'5.22', u'partitions': [{u'uuid': u'6843161b-6ac0-4ab3-bf3a-da81bb83aac9', u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7k7h3r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'c3ef626d-28f0-4bea-8bd6-18051da7eb4a', 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'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'badc9874-83c1-4390-87f5-d2e431ef715e', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/blockdevices/11/', u'system_id': u'7k7h3r', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'2e94ebab-27c1-487b-bec7-cfb8e2f2f644', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'partitions': [], u'type': u'virtual', u'id': 11, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2020-02-22 18:57:39,992 [salt.state       :300 ][INFO    ][4410] {'new': {'storage_layout': 'lvm'}}
2020-02-22 18:57:39,992 [salt.state       :1951][INFO    ][4410] Completed state [maas_machines_storage_cmp002_lvm] at time 18:57:39.992637 duration_in_ms=2874.49
2020-02-22 18:57:39,993 [salt.state       :1780][INFO    ][4410] Running state [maas_machines_storage_cmp001_lvm] at time 18:57:39.993123
2020-02-22 18:57:39,993 [salt.state       :1813][INFO    ][4410] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2020-02-22 18:57:41,301 [salt.loaded.ext.module.maasng:610 ][INFO    ][4410] qgs64b
2020-02-22 18:57:41,301 [salt.loaded.ext.module.maasng:626 ][INFO    ][4410] sda
2020-02-22 18:57:41,982 [salt.loaded.ext.module.maasng:361 ][INFO    ][4410] qgs64b
2020-02-22 18:57:42,067 [salt.loaded.ext.module.maasng:367 ][INFO    ][4410] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qgs64b', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'8e3205a3-cc0a-4c7d-9da9-f03ecc567024', u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qgs64b', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'78a9122c-1d8b-4bba-9598-7ebb5938673c', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'size': 800101236736}]}, {u'size': 800097042432, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/4/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'uuid': u'e7585824-ceb0-4c0f-ba58-464b60c11a5f', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qgs64b', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'c9c0b96a-c5c4-41c9-86cc-f3fb61e080e9', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'block_size': 4096, u'type': u'virtual', u'id': 4, u'serial': None, u'partitions': []}]
2020-02-22 18:57:42,067 [salt.loaded.ext.module.maasng:632 ][INFO    ][4410] vgroot
2020-02-22 18:57:42,068 [salt.loaded.ext.module.maasng:635 ][INFO    ][4410] lvroot
2020-02-22 18:57:42,068 [salt.loaded.ext.module.maasng:639 ][INFO    ][4410] 107374182400
2020-02-22 18:57:42,715 [salt.loaded.ext.module.maasng:645 ][INFO    ][4410] {u'hwe_kernel': None, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'eb3pgt', 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.39', u'id': 30, 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'eb3pgt', 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'effective_mtu': 1500, 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'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'eb3pgt', 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.39'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'qgs64b', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/interfaces/5/'}, 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'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/qgs64b/', 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'commissioning_status': 2, u'testing_status': 2, u'system_id': u'qgs64b', 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'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'35f81fe8-11f3-4cd3-9bba-4b622f9d8d20', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'qgs64b', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'bc7ff355-e8a8-421c-b0c2-c531bbafed92', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'partitions': []}], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'qgs64b', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'pool': {u'id': 0, u'resource_uri': u'/MAAS/api/2.0/resourcepool/0/', u'name': u'default', u'description': u'Default pool'}, u'storage_test_status': 2, u'other_test_status': -1, u'locked': False, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'path': u'/dev/disk/by-dname/sda', 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'qgs64b', u'partition_table_type': u'MBR', u'filesystem': None, 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'f0c14c10-e05b-41a3-ad93-6786d667a894', u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qgs64b', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd00bdbad-cbcd-4389-91ae-c162e5b079eb', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'min_hwe_kernel': u'ga-18.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'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'eb3pgt', 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.39', u'id': 30, 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'eb3pgt', 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'effective_mtu': 1500, 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'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'eb3pgt', 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.39'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'qgs64b', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/interfaces/5/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 16, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'qgs64b', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/interfaces/16/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eb3pgt', 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': 31, 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'eb3pgt', 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'effective_mtu': 1500, u'id': 17, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eb3pgt', 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.44'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'qgs64b', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/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'effective_mtu': 1500, u'id': 18, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'qgs64b', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/interfaces/18/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 32, 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'effective_mtu': 1500, u'id': 19, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'qgs64b', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/interfaces/19/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 33, 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'effective_mtu': 1500, u'id': 20, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'qgs64b', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/interfaces/20/'}], u'address_ttl': None, u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'f0c14c10-e05b-41a3-ad93-6786d667a894', u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qgs64b', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd00bdbad-cbcd-4389-91ae-c162e5b079eb', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'qgs64b', u'partition_table_type': u'MBR', u'filesystem': None, 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/qgs64b/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'35f81fe8-11f3-4cd3-9bba-4b622f9d8d20', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'qgs64b', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'bc7ff355-e8a8-421c-b0c2-c531bbafed92', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'size': 107374182400}], u'distro_series': u'', u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'path': u'/dev/disk/by-dname/sda', 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'qgs64b', u'partition_table_type': u'MBR', u'filesystem': None, 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'f0c14c10-e05b-41a3-ad93-6786d667a894', u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qgs64b', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd00bdbad-cbcd-4389-91ae-c162e5b079eb', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}]}}
2020-02-22 18:57:42,717 [salt.state       :300 ][INFO    ][4410] {'new': {'storage_layout': 'lvm'}}
2020-02-22 18:57:42,717 [salt.state       :1951][INFO    ][4410] Completed state [maas_machines_storage_cmp001_lvm] at time 18:57:42.717878 duration_in_ms=2724.754
2020-02-22 18:57:42,723 [salt.minion      :1711][INFO    ][4410] Returning information for job: 20200222185728111220
2020-02-22 18:57:43,751 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command state.apply with jid 20200222185743727128
2020-02-22 18:57:43,783 [salt.minion      :1432][INFO    ][4434] Starting a new job with PID 4434
2020-02-22 18:57:44,814 [salt.state       :915 ][INFO    ][4434] Loading fresh modules for state activity
2020-02-22 18:57:44,874 [salt.fileclient  :1219][INFO    ][4434] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2020-02-22 18:57:44,919 [salt.state       :1780][INFO    ][4434] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:57:44.919296
2020-02-22 18:57:44,919 [salt.state       :1813][INFO    ][4434] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-22 18:57:44,921 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4434] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-22 18:57:47,232 [salt.state       :300 ][INFO    ][4434] {'pid': 4442, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-22 18:57:47,233 [salt.state       :1951][INFO    ][4434] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:57:47.233492 duration_in_ms=2314.197
2020-02-22 18:57:47,236 [salt.state       :1780][INFO    ][4434] Running state [maas.deploy_machines] at time 18:57:47.236515
2020-02-22 18:57:47,237 [salt.state       :1813][INFO    ][4434] Executing state module.run for [maas.deploy_machines]
2020-02-22 18:57:47,237 [salt.utils.decorators:613 ][WARNING ][4434] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 18:57:47,921 [salt.loaded.ext.module.maas:684 ][INFO    ][4434] deploymachines hwe_kernel=ga-18.04 system_id=7k7h3r distro_series=bionic
2020-02-22 18:57:49,835 [salt.loaded.ext.module.maas:684 ][INFO    ][4434] deploymachines hwe_kernel=ga-18.04 system_id=qgs64b distro_series=bionic
2020-02-22 18:57:51,795 [salt.loaded.ext.module.maas:684 ][INFO    ][4434] deploymachines hwe_kernel=ga-18.04 system_id=7f38de distro_series=bionic
2020-02-22 18:57:53,807 [salt.loaded.ext.module.maas:684 ][INFO    ][4434] deploymachines hwe_kernel=ga-18.04 system_id=gb6tfx distro_series=bionic
2020-02-22 18:57:55,725 [salt.loaded.ext.module.maas:684 ][INFO    ][4434] deploymachines hwe_kernel=ga-18.04 system_id=e4qd7k distro_series=bionic
2020-02-22 18:57:57,718 [salt.state       :300 ][INFO    ][4434] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2020-02-22 18:57:57,719 [salt.state       :1951][INFO    ][4434] Completed state [maas.deploy_machines] at time 18:57:57.719154 duration_in_ms=10482.638
2020-02-22 18:57:57,725 [salt.minion      :1711][INFO    ][4434] Returning information for job: 20200222185743727128
2020-02-22 18:57:58,676 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command state.apply with jid 20200222185758660816
2020-02-22 18:57:58,700 [salt.minion      :1432][INFO    ][4494] Starting a new job with PID 4494
2020-02-22 18:58:05,028 [salt.state       :915 ][INFO    ][4494] Loading fresh modules for state activity
2020-02-22 18:58:05,091 [salt.fileclient  :1219][INFO    ][4494] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2020-02-22 18:58:05,147 [salt.state       :1780][INFO    ][4494] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:58:05.147558
2020-02-22 18:58:05,147 [salt.state       :1813][INFO    ][4494] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-22 18:58:05,150 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4494] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-22 18:58:07,467 [salt.state       :300 ][INFO    ][4494] {'pid': 4508, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-22 18:58:07,468 [salt.state       :1951][INFO    ][4494] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 18:58:07.468144 duration_in_ms=2320.587
2020-02-22 18:58:07,470 [salt.state       :1780][INFO    ][4494] Running state [maas.wait_for_machine_status] at time 18:58:07.470683
2020-02-22 18:58:07,471 [salt.state       :1813][INFO    ][4494] Executing state module.run for [maas.wait_for_machine_status]
2020-02-22 18:58:07,471 [salt.utils.decorators:613 ][WARNING ][4494] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 18:58:11,032 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.45405793s left)
2020-02-22 18:58:13,783 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222185813770447
2020-02-22 18:58:13,817 [salt.minion      :1432][INFO    ][4532] Starting a new job with PID 4532
2020-02-22 18:58:13,846 [salt.minion      :1711][INFO    ][4532] Returning information for job: 20200222185813770447
2020-02-22 18:58:43,879 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222185843865253
2020-02-22 18:58:43,904 [salt.minion      :1432][INFO    ][4568] Starting a new job with PID 4568
2020-02-22 18:58:43,943 [salt.minion      :1711][INFO    ][4568] Returning information for job: 20200222185843865253
2020-02-22 18:58:44,518 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.96814179s left)
2020-02-22 18:59:13,964 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222185913953140
2020-02-22 18:59:13,989 [salt.minion      :1432][INFO    ][4608] Starting a new job with PID 4608
2020-02-22 18:59:14,022 [salt.minion      :1711][INFO    ][4608] Returning information for job: 20200222185913953140
2020-02-22 18:59:18,047 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.43906188s left)
2020-02-22 18:59:44,042 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222185944030562
2020-02-22 18:59:44,067 [salt.minion      :1432][INFO    ][4638] Starting a new job with PID 4638
2020-02-22 18:59:44,096 [salt.minion      :1711][INFO    ][4638] Returning information for job: 20200222185944030562
2020-02-22 18:59:51,568 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2145.91837382s left)
2020-02-22 19:00:14,119 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222190014104381
2020-02-22 19:00:14,145 [salt.minion      :1432][INFO    ][4668] Starting a new job with PID 4668
2020-02-22 19:00:14,168 [salt.minion      :1711][INFO    ][4668] Returning information for job: 20200222190014104381
2020-02-22 19:00:25,061 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.42561102s left)
2020-02-22 19:00:44,189 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222190044176798
2020-02-22 19:00:44,213 [salt.minion      :1432][INFO    ][4702] Starting a new job with PID 4702
2020-02-22 19:00:44,237 [salt.minion      :1711][INFO    ][4702] Returning information for job: 20200222190044176798
2020-02-22 19:00:58,834 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2078.65191293s left)
2020-02-22 19:01:14,286 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222190114272151
2020-02-22 19:01:14,308 [salt.minion      :1432][INFO    ][4762] Starting a new job with PID 4762
2020-02-22 19:01:14,330 [salt.minion      :1711][INFO    ][4762] Returning information for job: 20200222190114272151
2020-02-22 19:01:29,562 [salt.loaded.ext.module.maas:993 ][INFO    ][4494] Machine 7k7h3r mark broken
2020-02-22 19:01:44,375 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222190144365792
2020-02-22 19:01:44,402 [salt.minion      :1432][INFO    ][4820] Starting a new job with PID 4820
2020-02-22 19:01:44,427 [salt.minion      :1711][INFO    ][4820] Returning information for job: 20200222190144365792
2020-02-22 19:02:00,268 [salt.loaded.ext.module.maas:997 ][INFO    ][4494] Machine 7k7h3r mark fixed
2020-02-22 19:02:01,495 [salt.loaded.ext.module.maas:684 ][INFO    ][4494] deploymachines hwe_kernel=ga-18.04 system_id=7k7h3r distro_series=bionic
2020-02-22 19:02:04,432 [salt.loaded.ext.module.maas:993 ][INFO    ][4494] Machine qgs64b mark broken
2020-02-22 19:02:14,454 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222190214439787
2020-02-22 19:02:14,479 [salt.minion      :1432][INFO    ][4863] Starting a new job with PID 4863
2020-02-22 19:02:14,507 [salt.minion      :1711][INFO    ][4863] Returning information for job: 20200222190214439787
2020-02-22 19:02:35,075 [salt.loaded.ext.module.maas:997 ][INFO    ][4494] Machine qgs64b mark fixed
2020-02-22 19:02:36,189 [salt.loaded.ext.module.maas:684 ][INFO    ][4494] deploymachines hwe_kernel=ga-18.04 system_id=qgs64b distro_series=bionic
2020-02-22 19:02:40,102 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1977.38466191s left)
2020-02-22 19:02:44,550 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222190244539159
2020-02-22 19:02:44,574 [salt.minion      :1432][INFO    ][4925] Starting a new job with PID 4925
2020-02-22 19:02:44,601 [salt.minion      :1711][INFO    ][4925] Returning information for job: 20200222190244539159
2020-02-22 19:03:13,619 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1943.86692095s left)
2020-02-22 19:03:14,666 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222190314654039
2020-02-22 19:03:14,684 [salt.minion      :1432][INFO    ][4973] Starting a new job with PID 4973
2020-02-22 19:03:14,714 [salt.minion      :1711][INFO    ][4973] Returning information for job: 20200222190314654039
2020-02-22 19:03:44,759 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222190344748072
2020-02-22 19:03:44,782 [salt.minion      :1432][INFO    ][5045] Starting a new job with PID 5045
2020-02-22 19:03:44,805 [salt.minion      :1711][INFO    ][5045] Returning information for job: 20200222190344748072
2020-02-22 19:03:47,207 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1910.27961898s left)
2020-02-22 19:04:14,883 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222190414865389
2020-02-22 19:04:14,910 [salt.minion      :1432][INFO    ][5080] Starting a new job with PID 5080
2020-02-22 19:04:14,937 [salt.minion      :1711][INFO    ][5080] Returning information for job: 20200222190414865389
2020-02-22 19:04:20,714 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1876.77286386s left)
2020-02-22 19:04:44,998 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222190444985747
2020-02-22 19:04:45,020 [salt.minion      :1432][INFO    ][5117] Starting a new job with PID 5117
2020-02-22 19:04:45,046 [salt.minion      :1711][INFO    ][5117] Returning information for job: 20200222190444985747
2020-02-22 19:04:54,610 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1842.87646198s left)
2020-02-22 19:05:15,105 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222190515087214
2020-02-22 19:05:15,129 [salt.minion      :1432][INFO    ][5164] Starting a new job with PID 5164
2020-02-22 19:05:15,155 [salt.minion      :1711][INFO    ][5164] Returning information for job: 20200222190515087214
2020-02-22 19:05:28,103 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1809.38346887s left)
2020-02-22 19:05:45,244 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222190545230923
2020-02-22 19:05:45,262 [salt.minion      :1432][INFO    ][5208] Starting a new job with PID 5208
2020-02-22 19:05:45,286 [salt.minion      :1711][INFO    ][5208] Returning information for job: 20200222190545230923
2020-02-22 19:05:58,784 [salt.loaded.ext.module.maas:993 ][INFO    ][4494] Machine 7k7h3r mark broken
2020-02-22 19:06:15,375 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222190615358694
2020-02-22 19:06:15,404 [salt.minion      :1432][INFO    ][5272] Starting a new job with PID 5272
2020-02-22 19:06:15,430 [salt.minion      :1711][INFO    ][5272] Returning information for job: 20200222190615358694
2020-02-22 19:06:29,442 [salt.loaded.ext.module.maas:997 ][INFO    ][4494] Machine 7k7h3r mark fixed
2020-02-22 19:06:29,923 [salt.loaded.ext.module.maas:1001][INFO    ][4494] Machine 7k7h3r fio test
2020-02-22 19:06:33,878 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1743.60799098s left)
2020-02-22 19:06:45,519 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222190645512104
2020-02-22 19:06:45,539 [salt.minion      :1432][INFO    ][5305] Starting a new job with PID 5305
2020-02-22 19:06:45,568 [salt.minion      :1711][INFO    ][5305] Returning information for job: 20200222190645512104
2020-02-22 19:07:05,255 [salt.loaded.ext.module.maas:993 ][INFO    ][4494] Machine qgs64b mark broken
2020-02-22 19:07:15,659 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222190715649202
2020-02-22 19:07:15,682 [salt.minion      :1432][INFO    ][5341] Starting a new job with PID 5341
2020-02-22 19:07:15,707 [salt.minion      :1711][INFO    ][5341] Returning information for job: 20200222190715649202
2020-02-22 19:07:35,862 [salt.loaded.ext.module.maas:997 ][INFO    ][4494] Machine qgs64b mark fixed
2020-02-22 19:07:36,351 [salt.loaded.ext.module.maas:1001][INFO    ][4494] Machine qgs64b fio test
2020-02-22 19:07:39,617 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1677.86902785s left)
2020-02-22 19:07:45,804 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222190745795941
2020-02-22 19:07:45,822 [salt.minion      :1432][INFO    ][5374] Starting a new job with PID 5374
2020-02-22 19:07:45,858 [salt.minion      :1711][INFO    ][5374] Returning information for job: 20200222190745795941
2020-02-22 19:08:12,566 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1644.92026901s left)
2020-02-22 19:08:15,968 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222190815956855
2020-02-22 19:08:15,994 [salt.minion      :1432][INFO    ][5401] Starting a new job with PID 5401
2020-02-22 19:08:16,021 [salt.minion      :1711][INFO    ][5401] Returning information for job: 20200222190815956855
2020-02-22 19:08:45,630 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1611.85597301s left)
2020-02-22 19:08:46,133 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222190846121469
2020-02-22 19:08:46,156 [salt.minion      :1432][INFO    ][5433] Starting a new job with PID 5433
2020-02-22 19:08:46,182 [salt.minion      :1711][INFO    ][5433] Returning information for job: 20200222190846121469
2020-02-22 19:09:16,281 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222190916271502
2020-02-22 19:09:16,300 [salt.minion      :1432][INFO    ][5481] Starting a new job with PID 5481
2020-02-22 19:09:16,323 [salt.minion      :1711][INFO    ][5481] Returning information for job: 20200222190916271502
2020-02-22 19:09:18,653 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1578.83310199s left)
2020-02-22 19:09:46,449 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222190946440731
2020-02-22 19:09:46,474 [salt.minion      :1432][INFO    ][5524] Starting a new job with PID 5524
2020-02-22 19:09:46,497 [salt.minion      :1711][INFO    ][5524] Returning information for job: 20200222190946440731
2020-02-22 19:09:51,501 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1545.98560095s left)
2020-02-22 19:10:16,614 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222191016600038
2020-02-22 19:10:16,642 [salt.minion      :1432][INFO    ][5553] Starting a new job with PID 5553
2020-02-22 19:10:16,665 [salt.minion      :1711][INFO    ][5553] Returning information for job: 20200222191016600038
2020-02-22 19:10:24,438 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1513.04808283s left)
2020-02-22 19:10:46,791 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222191046781501
2020-02-22 19:10:46,818 [salt.minion      :1432][INFO    ][5593] Starting a new job with PID 5593
2020-02-22 19:10:46,844 [salt.minion      :1711][INFO    ][5593] Returning information for job: 20200222191046781501
2020-02-22 19:10:57,208 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1480.27880001s left)
2020-02-22 19:11:16,982 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222191116969436
2020-02-22 19:11:17,010 [salt.minion      :1432][INFO    ][5624] Starting a new job with PID 5624
2020-02-22 19:11:17,034 [salt.minion      :1711][INFO    ][5624] Returning information for job: 20200222191116969436
2020-02-22 19:11:30,362 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1447.12461591s left)
2020-02-22 19:11:47,190 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222191147181601
2020-02-22 19:11:47,213 [salt.minion      :1432][INFO    ][5702] Starting a new job with PID 5702
2020-02-22 19:11:47,246 [salt.minion      :1711][INFO    ][5702] Returning information for job: 20200222191147181601
2020-02-22 19:12:03,515 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm03']
sleep for:30s Timeout:2250s (1413.97122693s left)
2020-02-22 19:12:17,236 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222191217221435
2020-02-22 19:12:17,263 [salt.minion      :1432][INFO    ][5756] Starting a new job with PID 5756
2020-02-22 19:12:17,289 [salt.minion      :1711][INFO    ][5756] Returning information for job: 20200222191217221435
2020-02-22 19:12:36,867 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1380.61980891s left)
2020-02-22 19:12:47,438 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222191247429632
2020-02-22 19:12:47,458 [salt.minion      :1432][INFO    ][5810] Starting a new job with PID 5810
2020-02-22 19:12:47,485 [salt.minion      :1711][INFO    ][5810] Returning information for job: 20200222191247429632
2020-02-22 19:13:10,240 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1347.24685287s left)
2020-02-22 19:13:17,634 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222191317623217
2020-02-22 19:13:17,662 [salt.minion      :1432][INFO    ][5837] Starting a new job with PID 5837
2020-02-22 19:13:17,695 [salt.minion      :1711][INFO    ][5837] Returning information for job: 20200222191317623217
2020-02-22 19:13:43,543 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1313.94337487s left)
2020-02-22 19:13:47,863 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222191347850754
2020-02-22 19:13:47,889 [salt.minion      :1432][INFO    ][5863] Starting a new job with PID 5863
2020-02-22 19:13:47,919 [salt.minion      :1711][INFO    ][5863] Returning information for job: 20200222191347850754
2020-02-22 19:14:17,021 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1280.46516991s left)
2020-02-22 19:14:18,079 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222191418070008
2020-02-22 19:14:18,106 [salt.minion      :1432][INFO    ][5893] Starting a new job with PID 5893
2020-02-22 19:14:18,139 [salt.minion      :1711][INFO    ][5893] Returning information for job: 20200222191418070008
2020-02-22 19:14:48,123 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222191448108055
2020-02-22 19:14:48,142 [salt.minion      :1432][INFO    ][5919] Starting a new job with PID 5919
2020-02-22 19:14:48,173 [salt.minion      :1711][INFO    ][5919] Returning information for job: 20200222191448108055
2020-02-22 19:14:50,510 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1246.97613001s left)
2020-02-22 19:15:18,169 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222191518158729
2020-02-22 19:15:18,187 [salt.minion      :1432][INFO    ][5946] Starting a new job with PID 5946
2020-02-22 19:15:18,214 [salt.minion      :1711][INFO    ][5946] Returning information for job: 20200222191518158729
2020-02-22 19:15:24,019 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1213.46694589s left)
2020-02-22 19:15:48,215 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222191548203057
2020-02-22 19:15:48,240 [salt.minion      :1432][INFO    ][5975] Starting a new job with PID 5975
2020-02-22 19:15:48,281 [salt.minion      :1711][INFO    ][5975] Returning information for job: 20200222191548203057
2020-02-22 19:15:57,509 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1179.97781587s left)
2020-02-22 19:16:18,284 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222191618273444
2020-02-22 19:16:18,307 [salt.minion      :1432][INFO    ][6004] Starting a new job with PID 6004
2020-02-22 19:16:18,342 [salt.minion      :1711][INFO    ][6004] Returning information for job: 20200222191618273444
2020-02-22 19:16:30,919 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1146.56747484s left)
2020-02-22 19:16:48,350 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222191648337563
2020-02-22 19:16:48,377 [salt.minion      :1432][INFO    ][6031] Starting a new job with PID 6031
2020-02-22 19:16:48,409 [salt.minion      :1711][INFO    ][6031] Returning information for job: 20200222191648337563
2020-02-22 19:17:04,471 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1113.01566386s left)
2020-02-22 19:17:18,435 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222191718425171
2020-02-22 19:17:18,465 [salt.minion      :1432][INFO    ][6067] Starting a new job with PID 6067
2020-02-22 19:17:18,499 [salt.minion      :1711][INFO    ][6067] Returning information for job: 20200222191718425171
2020-02-22 19:17:37,755 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1079.73156881s left)
2020-02-22 19:17:48,512 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222191748495176
2020-02-22 19:17:48,546 [salt.minion      :1432][INFO    ][6092] Starting a new job with PID 6092
2020-02-22 19:17:48,572 [salt.minion      :1711][INFO    ][6092] Returning information for job: 20200222191748495176
2020-02-22 19:18:11,200 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1046.28607583s left)
2020-02-22 19:18:18,647 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222191818633399
2020-02-22 19:18:18,677 [salt.minion      :1432][INFO    ][6119] Starting a new job with PID 6119
2020-02-22 19:18:18,702 [salt.minion      :1711][INFO    ][6119] Returning information for job: 20200222191818633399
2020-02-22 19:18:44,686 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1012.80081701s left)
2020-02-22 19:18:48,782 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222191848768730
2020-02-22 19:18:48,804 [salt.minion      :1432][INFO    ][6154] Starting a new job with PID 6154
2020-02-22 19:18:48,835 [salt.minion      :1711][INFO    ][6154] Returning information for job: 20200222191848768730
2020-02-22 19:19:18,112 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (979.3743608s left)
2020-02-22 19:19:18,908 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222191918892746
2020-02-22 19:19:18,935 [salt.minion      :1432][INFO    ][6193] Starting a new job with PID 6193
2020-02-22 19:19:18,965 [salt.minion      :1711][INFO    ][6193] Returning information for job: 20200222191918892746
2020-02-22 19:19:49,039 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222191949028779
2020-02-22 19:19:49,058 [salt.minion      :1432][INFO    ][6222] Starting a new job with PID 6222
2020-02-22 19:19:49,086 [salt.minion      :1711][INFO    ][6222] Returning information for job: 20200222191949028779
2020-02-22 19:19:51,370 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (946.116005898s left)
2020-02-22 19:20:19,200 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222192019187098
2020-02-22 19:20:19,226 [salt.minion      :1432][INFO    ][6248] Starting a new job with PID 6248
2020-02-22 19:20:19,251 [salt.minion      :1711][INFO    ][6248] Returning information for job: 20200222192019187098
2020-02-22 19:20:24,589 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (912.898667812s left)
2020-02-22 19:20:49,350 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222192049340938
2020-02-22 19:20:49,373 [salt.minion      :1432][INFO    ][6280] Starting a new job with PID 6280
2020-02-22 19:20:49,401 [salt.minion      :1711][INFO    ][6280] Returning information for job: 20200222192049340938
2020-02-22 19:20:57,893 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (879.59322691s left)
2020-02-22 19:21:19,501 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222192119491756
2020-02-22 19:21:19,521 [salt.minion      :1432][INFO    ][6313] Starting a new job with PID 6313
2020-02-22 19:21:19,545 [salt.minion      :1711][INFO    ][6313] Returning information for job: 20200222192119491756
2020-02-22 19:21:31,178 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (846.308373928s left)
2020-02-22 19:21:49,687 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222192149677415
2020-02-22 19:21:49,703 [salt.minion      :1432][INFO    ][6343] Starting a new job with PID 6343
2020-02-22 19:21:49,731 [salt.minion      :1711][INFO    ][6343] Returning information for job: 20200222192149677415
2020-02-22 19:22:04,340 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (813.146511793s left)
2020-02-22 19:22:19,890 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222192219881290
2020-02-22 19:22:19,912 [salt.minion      :1432][INFO    ][6373] Starting a new job with PID 6373
2020-02-22 19:22:19,937 [salt.minion      :1711][INFO    ][6373] Returning information for job: 20200222192219881290
2020-02-22 19:22:37,459 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (780.027331829s left)
2020-02-22 19:22:50,088 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222192250080773
2020-02-22 19:22:50,106 [salt.minion      :1432][INFO    ][6401] Starting a new job with PID 6401
2020-02-22 19:22:50,130 [salt.minion      :1711][INFO    ][6401] Returning information for job: 20200222192250080773
2020-02-22 19:23:10,685 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (746.801110983s left)
2020-02-22 19:23:20,106 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222192320095002
2020-02-22 19:23:20,130 [salt.minion      :1432][INFO    ][6429] Starting a new job with PID 6429
2020-02-22 19:23:20,157 [salt.minion      :1711][INFO    ][6429] Returning information for job: 20200222192320095002
2020-02-22 19:23:43,849 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (713.637849808s left)
2020-02-22 19:23:50,136 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222192350127039
2020-02-22 19:23:50,155 [salt.minion      :1432][INFO    ][6454] Starting a new job with PID 6454
2020-02-22 19:23:50,181 [salt.minion      :1711][INFO    ][6454] Returning information for job: 20200222192350127039
2020-02-22 19:24:17,160 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (680.327590942s left)
2020-02-22 19:24:20,177 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222192420165104
2020-02-22 19:24:20,195 [salt.minion      :1432][INFO    ][6482] Starting a new job with PID 6482
2020-02-22 19:24:20,221 [salt.minion      :1711][INFO    ][6482] Returning information for job: 20200222192420165104
2020-02-22 19:24:50,277 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222192450265550
2020-02-22 19:24:50,298 [salt.minion      :1432][INFO    ][6513] Starting a new job with PID 6513
2020-02-22 19:24:50,326 [salt.minion      :1711][INFO    ][6513] Returning information for job: 20200222192450265550
2020-02-22 19:24:50,368 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (647.118556976s left)
2020-02-22 19:25:20,355 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222192520346206
2020-02-22 19:25:20,379 [salt.minion      :1432][INFO    ][6541] Starting a new job with PID 6541
2020-02-22 19:25:20,403 [salt.minion      :1711][INFO    ][6541] Returning information for job: 20200222192520346206
2020-02-22 19:25:23,554 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (613.932043791s left)
2020-02-22 19:25:50,462 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222192550454202
2020-02-22 19:25:50,482 [salt.minion      :1432][INFO    ][6568] Starting a new job with PID 6568
2020-02-22 19:25:50,512 [salt.minion      :1711][INFO    ][6568] Returning information for job: 20200222192550454202
2020-02-22 19:25:56,746 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (580.740342855s left)
2020-02-22 19:26:20,566 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222192620557943
2020-02-22 19:26:20,595 [salt.minion      :1432][INFO    ][6596] Starting a new job with PID 6596
2020-02-22 19:26:20,624 [salt.minion      :1711][INFO    ][6596] Returning information for job: 20200222192620557943
2020-02-22 19:26:30,148 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (547.338447809s left)
2020-02-22 19:26:50,740 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222192650729270
2020-02-22 19:26:50,764 [salt.minion      :1432][INFO    ][6622] Starting a new job with PID 6622
2020-02-22 19:26:50,792 [salt.minion      :1711][INFO    ][6622] Returning information for job: 20200222192650729270
2020-02-22 19:27:03,206 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (514.282593966s left)
2020-02-22 19:27:20,863 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222192720851477
2020-02-22 19:27:20,889 [salt.minion      :1432][INFO    ][6653] Starting a new job with PID 6653
2020-02-22 19:27:20,919 [salt.minion      :1711][INFO    ][6653] Returning information for job: 20200222192720851477
2020-02-22 19:27:36,447 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (481.039791822s left)
2020-02-22 19:27:51,067 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222192751051224
2020-02-22 19:27:51,094 [salt.minion      :1432][INFO    ][6680] Starting a new job with PID 6680
2020-02-22 19:27:51,121 [salt.minion      :1711][INFO    ][6680] Returning information for job: 20200222192751051224
2020-02-22 19:28:09,642 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (447.844119787s left)
2020-02-22 19:28:21,223 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222192821212023
2020-02-22 19:28:21,251 [salt.minion      :1432][INFO    ][6709] Starting a new job with PID 6709
2020-02-22 19:28:21,281 [salt.minion      :1711][INFO    ][6709] Returning information for job: 20200222192821212023
2020-02-22 19:28:42,718 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (414.768615961s left)
2020-02-22 19:28:51,428 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222192851414625
2020-02-22 19:28:51,453 [salt.minion      :1432][INFO    ][6743] Starting a new job with PID 6743
2020-02-22 19:28:51,476 [salt.minion      :1711][INFO    ][6743] Returning information for job: 20200222192851414625
2020-02-22 19:29:16,173 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (381.313116789s left)
2020-02-22 19:29:21,627 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222192921614448
2020-02-22 19:29:21,652 [salt.minion      :1432][INFO    ][6783] Starting a new job with PID 6783
2020-02-22 19:29:21,682 [salt.minion      :1711][INFO    ][6783] Returning information for job: 20200222192921614448
2020-02-22 19:29:49,435 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (348.051748991s left)
2020-02-22 19:29:51,654 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222192951639303
2020-02-22 19:29:51,679 [salt.minion      :1432][INFO    ][6811] Starting a new job with PID 6811
2020-02-22 19:29:51,706 [salt.minion      :1711][INFO    ][6811] Returning information for job: 20200222192951639303
2020-02-22 19:30:21,702 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222193021693872
2020-02-22 19:30:21,724 [salt.minion      :1432][INFO    ][6839] Starting a new job with PID 6839
2020-02-22 19:30:21,750 [salt.minion      :1711][INFO    ][6839] Returning information for job: 20200222193021693872
2020-02-22 19:30:22,563 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (314.923461914s left)
2020-02-22 19:30:51,770 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222193051755877
2020-02-22 19:30:51,791 [salt.minion      :1432][INFO    ][6864] Starting a new job with PID 6864
2020-02-22 19:30:51,819 [salt.minion      :1711][INFO    ][6864] Returning information for job: 20200222193051755877
2020-02-22 19:30:55,745 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (281.741144896s left)
2020-02-22 19:31:21,858 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222193121842697
2020-02-22 19:31:21,886 [salt.minion      :1432][INFO    ][6892] Starting a new job with PID 6892
2020-02-22 19:31:21,920 [salt.minion      :1711][INFO    ][6892] Returning information for job: 20200222193121842697
2020-02-22 19:31:28,849 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (248.637338877s left)
2020-02-22 19:31:51,930 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222193151919380
2020-02-22 19:31:51,957 [salt.minion      :1432][INFO    ][6917] Starting a new job with PID 6917
2020-02-22 19:31:51,986 [salt.minion      :1711][INFO    ][6917] Returning information for job: 20200222193151919380
2020-02-22 19:32:02,153 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (215.333303928s left)
2020-02-22 19:32:22,080 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222193222063155
2020-02-22 19:32:22,107 [salt.minion      :1432][INFO    ][6946] Starting a new job with PID 6946
2020-02-22 19:32:22,143 [salt.minion      :1711][INFO    ][6946] Returning information for job: 20200222193222063155
2020-02-22 19:32:35,309 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (182.177644014s left)
2020-02-22 19:32:52,228 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222193252213732
2020-02-22 19:32:52,255 [salt.minion      :1432][INFO    ][6976] Starting a new job with PID 6976
2020-02-22 19:32:52,283 [salt.minion      :1711][INFO    ][6976] Returning information for job: 20200222193252213732
2020-02-22 19:33:08,619 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (148.867156982s left)
2020-02-22 19:33:22,375 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222193322364876
2020-02-22 19:33:22,402 [salt.minion      :1432][INFO    ][7005] Starting a new job with PID 7005
2020-02-22 19:33:22,428 [salt.minion      :1711][INFO    ][7005] Returning information for job: 20200222193322364876
2020-02-22 19:33:41,746 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (115.740147829s left)
2020-02-22 19:33:52,527 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222193352516767
2020-02-22 19:33:52,556 [salt.minion      :1432][INFO    ][7031] Starting a new job with PID 7031
2020-02-22 19:33:52,583 [salt.minion      :1711][INFO    ][7031] Returning information for job: 20200222193352516767
2020-02-22 19:34:14,815 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (82.6711528301s left)
2020-02-22 19:34:22,558 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222193422547879
2020-02-22 19:34:22,581 [salt.minion      :1432][INFO    ][7059] Starting a new job with PID 7059
2020-02-22 19:34:22,610 [salt.minion      :1711][INFO    ][7059] Returning information for job: 20200222193422547879
2020-02-22 19:34:48,212 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (49.2748229504s left)
2020-02-22 19:34:52,746 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222193452734967
2020-02-22 19:34:52,773 [salt.minion      :1432][INFO    ][7087] Starting a new job with PID 7087
2020-02-22 19:34:52,799 [salt.minion      :1711][INFO    ][7087] Returning information for job: 20200222193452734967
2020-02-22 19:35:21,582 [salt.loaded.ext.module.maas:1024][INFO    ][4494] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (15.9048929214s left)
2020-02-22 19:35:22,770 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222193522756239
2020-02-22 19:35:22,797 [salt.minion      :1432][INFO    ][7118] Starting a new job with PID 7118
2020-02-22 19:35:22,831 [salt.minion      :1711][INFO    ][7118] Returning information for job: 20200222193522756239
2020-02-22 19:35:52,986 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222193552971002
2020-02-22 19:35:53,008 [salt.minion      :1432][INFO    ][7143] Starting a new job with PID 7143
2020-02-22 19:35:53,034 [salt.minion      :1711][INFO    ][7143] Returning information for job: 20200222193552971002
2020-02-22 19:35:54,878 [salt.state       :302 ][ERROR   ][4494] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['cmp002', 'cmp001']not in Deployed state
2020-02-22 19:35:54,879 [salt.state       :1951][INFO    ][4494] Completed state [maas.wait_for_machine_status] at time 19:35:54.879379 duration_in_ms=2267408.692
2020-02-22 19:35:54,885 [salt.minion      :1711][INFO    ][4494] Returning information for job: 20200222185758660816
2020-02-22 19:36:06,171 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command pillar.get with jid 20200222193606161912
2020-02-22 19:36:06,198 [salt.minion      :1432][INFO    ][7166] Starting a new job with PID 7166
2020-02-22 19:36:06,208 [salt.minion      :1711][INFO    ][7166] Returning information for job: 20200222193606161912
2020-02-22 19:36:07,176 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command service.status with jid 20200222193607163413
2020-02-22 19:36:07,203 [salt.minion      :1432][INFO    ][7171] Starting a new job with PID 7171
2020-02-22 19:36:08,105 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][7171] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2020-02-22 19:36:08,154 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][7171] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2020-02-22 19:36:08,183 [salt.minion      :1711][INFO    ][7171] Returning information for job: 20200222193607163413
2020-02-22 19:36:09,195 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command state.apply with jid 20200222193609178955
2020-02-22 19:36:09,218 [salt.minion      :1432][INFO    ][7182] Starting a new job with PID 7182
2020-02-22 19:36:15,761 [salt.state       :915 ][INFO    ][7182] Loading fresh modules for state activity
2020-02-22 19:36:16,403 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7182] Executing command 'salt-minion --version' in directory '/root'
2020-02-22 19:36:16,833 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7182] Executing command 'salt-minion --version' in directory '/root'
2020-02-22 19:36:18,002 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7182] Executing command 'salt-minion --version' in directory '/root'
2020-02-22 19:36:18,389 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7182] Executing command 'salt-minion --version' in directory '/root'
2020-02-22 19:36:20,787 [salt.state       :1780][INFO    ][7182] Running state [salt-minion] at time 19:36:20.787790
2020-02-22 19:36:20,788 [salt.state       :1813][INFO    ][7182] Executing state pkg.installed for [salt-minion]
2020-02-22 19:36:20,789 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7182] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-22 19:36:20,919 [salt.state       :300 ][INFO    ][7182] All specified packages are already installed
2020-02-22 19:36:20,919 [salt.state       :1951][INFO    ][7182] Completed state [salt-minion] at time 19:36:20.919675 duration_in_ms=131.886
2020-02-22 19:36:20,920 [salt.state       :1780][INFO    ][7182] Running state [salt_minion_dependency_packages] at time 19:36:20.920323
2020-02-22 19:36:20,920 [salt.state       :1813][INFO    ][7182] Executing state pkg.installed for [salt_minion_dependency_packages]
2020-02-22 19:36:20,933 [salt.state       :300 ][INFO    ][7182] All specified packages are already installed
2020-02-22 19:36:20,933 [salt.state       :1951][INFO    ][7182] Completed state [salt_minion_dependency_packages] at time 19:36:20.933654 duration_in_ms=13.332
2020-02-22 19:36:20,938 [salt.state       :1780][INFO    ][7182] Running state [/etc/salt/minion.d/minion.conf] at time 19:36:20.938495
2020-02-22 19:36:20,938 [salt.state       :1813][INFO    ][7182] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2020-02-22 19:36:21,259 [salt.state       :300 ][INFO    ][7182] File /etc/salt/minion.d/minion.conf is in the correct state
2020-02-22 19:36:21,260 [salt.state       :1951][INFO    ][7182] Completed state [/etc/salt/minion.d/minion.conf] at time 19:36:21.259950 duration_in_ms=321.455
2020-02-22 19:36:21,260 [salt.state       :1780][INFO    ][7182] Running state [python-netaddr] at time 19:36:21.260306
2020-02-22 19:36:21,260 [salt.state       :1813][INFO    ][7182] Executing state pkg.installed for [python-netaddr]
2020-02-22 19:36:21,278 [salt.state       :300 ][INFO    ][7182] All specified packages are already installed
2020-02-22 19:36:21,279 [salt.state       :1951][INFO    ][7182] Completed state [python-netaddr] at time 19:36:21.279018 duration_in_ms=18.71
2020-02-22 19:36:21,283 [salt.state       :1780][INFO    ][7182] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 19:36:21.283790
2020-02-22 19:36:21,284 [salt.state       :1813][INFO    ][7182] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2020-02-22 19:36:21,298 [salt.state       :300 ][INFO    ][7182] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2020-02-22 19:36:21,299 [salt.state       :1951][INFO    ][7182] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 19:36:21.299369 duration_in_ms=15.578
2020-02-22 19:36:21,302 [salt.state       :1780][INFO    ][7182] Running state [salt-minion] at time 19:36:21.302442
2020-02-22 19:36:21,302 [salt.state       :1813][INFO    ][7182] Executing state service.running for [salt-minion]
2020-02-22 19:36:21,303 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7182] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2020-02-22 19:36:21,346 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7182] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2020-02-22 19:36:21,371 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7182] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2020-02-22 19:36:21,402 [salt.state       :300 ][INFO    ][7182] The service salt-minion is already running
2020-02-22 19:36:21,403 [salt.state       :1951][INFO    ][7182] Completed state [salt-minion] at time 19:36:21.403521 duration_in_ms=101.078
2020-02-22 19:36:21,406 [salt.state       :1780][INFO    ][7182] Running state [/etc/salt/grains.d] at time 19:36:21.406649
2020-02-22 19:36:21,407 [salt.state       :1813][INFO    ][7182] Executing state file.directory for [/etc/salt/grains.d]
2020-02-22 19:36:21,410 [salt.state       :300 ][INFO    ][7182] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2020-02-22 19:36:21,410 [salt.state       :1951][INFO    ][7182] Completed state [/etc/salt/grains.d] at time 19:36:21.410544 duration_in_ms=3.894
2020-02-22 19:36:21,411 [salt.state       :1780][INFO    ][7182] Running state [/etc/salt/grains] at time 19:36:21.411841
2020-02-22 19:36:21,412 [salt.state       :1813][INFO    ][7182] Executing state file.managed for [/etc/salt/grains]
2020-02-22 19:36:21,413 [salt.state       :300 ][INFO    ][7182] File /etc/salt/grains exists with proper permissions. No changes made.
2020-02-22 19:36:21,414 [salt.state       :1951][INFO    ][7182] Completed state [/etc/salt/grains] at time 19:36:21.414172 duration_in_ms=2.332
2020-02-22 19:36:21,414 [salt.state       :1780][INFO    ][7182] Running state [/etc/salt/grains.d/placeholder] at time 19:36:21.414728
2020-02-22 19:36:21,415 [salt.state       :1813][INFO    ][7182] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2020-02-22 19:36:21,415 [salt.state       :300 ][INFO    ][7182] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2020-02-22 19:36:21,415 [salt.state       :1951][INFO    ][7182] Completed state [/etc/salt/grains.d/placeholder] at time 19:36:21.415929 duration_in_ms=1.201
2020-02-22 19:36:21,417 [salt.state       :1780][INFO    ][7182] Running state [/etc/salt/grains.d/sphinx] at time 19:36:21.417074
2020-02-22 19:36:21,417 [salt.state       :1813][INFO    ][7182] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2020-02-22 19:36:21,418 [salt.state       :300 ][INFO    ][7182] File /etc/salt/grains.d/sphinx is in the correct state
2020-02-22 19:36:21,419 [salt.state       :1951][INFO    ][7182] Completed state [/etc/salt/grains.d/sphinx] at time 19:36:21.419164 duration_in_ms=2.09
2020-02-22 19:36:21,422 [salt.state       :1780][INFO    ][7182] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 19:36:21.422808
2020-02-22 19:36:21,423 [salt.state       :1813][INFO    ][7182] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2020-02-22 19:36:21,423 [salt.state       :300 ][INFO    ][7182] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2020-02-22 19:36:21,423 [salt.state       :1951][INFO    ][7182] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 19:36:21.423706 duration_in_ms=0.898
2020-02-22 19:36:21,424 [salt.state       :1780][INFO    ][7182] Running state [/etc/salt/grains.d/dns_records] at time 19:36:21.424230
2020-02-22 19:36:21,424 [salt.state       :1813][INFO    ][7182] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2020-02-22 19:36:21,425 [salt.state       :300 ][INFO    ][7182] File /etc/salt/grains.d/dns_records is in the correct state
2020-02-22 19:36:21,426 [salt.state       :1951][INFO    ][7182] Completed state [/etc/salt/grains.d/dns_records] at time 19:36:21.426164 duration_in_ms=1.934
2020-02-22 19:36:21,427 [salt.state       :1780][INFO    ][7182] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 19:36:21.427111
2020-02-22 19:36:21,427 [salt.state       :1813][INFO    ][7182] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2020-02-22 19:36:21,427 [salt.state       :300 ][INFO    ][7182] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2020-02-22 19:36:21,428 [salt.state       :1951][INFO    ][7182] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 19:36:21.427967 duration_in_ms=0.857
2020-02-22 19:36:21,428 [salt.state       :1780][INFO    ][7182] Running state [/etc/salt/grains.d/salt] at time 19:36:21.428796
2020-02-22 19:36:21,429 [salt.state       :1813][INFO    ][7182] Executing state file.managed for [/etc/salt/grains.d/salt]
2020-02-22 19:36:21,431 [salt.state       :300 ][INFO    ][7182] File /etc/salt/grains.d/salt is in the correct state
2020-02-22 19:36:21,431 [salt.state       :1951][INFO    ][7182] Completed state [/etc/salt/grains.d/salt] at time 19:36:21.431885 duration_in_ms=3.09
2020-02-22 19:36:21,433 [salt.state       :1780][INFO    ][7182] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 19:36:21.433737
2020-02-22 19:36:21,434 [salt.state       :1813][INFO    ][7182] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2020-02-22 19:36:21,434 [salt.state       :300 ][INFO    ][7182] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2020-02-22 19:36:21,435 [salt.state       :1951][INFO    ][7182] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 19:36:21.435073 duration_in_ms=1.336
2020-02-22 19:36:21,439 [salt.state       :1780][INFO    ][7182] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 19:36:21.438940
2020-02-22 19:36:21,439 [salt.state       :1813][INFO    ][7182] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2020-02-22 19:36:21,439 [salt.state       :300 ][INFO    ][7182] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2020-02-22 19:36:21,440 [salt.state       :1951][INFO    ][7182] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 19:36:21.439983 duration_in_ms=1.043
2020-02-22 19:36:21,440 [salt.state       :1780][INFO    ][7182] Running state [mine.update] at time 19:36:21.440855
2020-02-22 19:36:21,441 [salt.state       :1813][INFO    ][7182] Executing state module.wait for [mine.update]
2020-02-22 19:36:21,441 [salt.state       :300 ][INFO    ][7182] No changes made for mine.update
2020-02-22 19:36:21,441 [salt.state       :1951][INFO    ][7182] Completed state [mine.update] at time 19:36:21.441812 duration_in_ms=0.956
2020-02-22 19:36:21,442 [salt.state       :1780][INFO    ][7182] Running state [ca-certificates] at time 19:36:21.442139
2020-02-22 19:36:21,442 [salt.state       :1813][INFO    ][7182] Executing state pkg.installed for [ca-certificates]
2020-02-22 19:36:21,454 [salt.state       :300 ][INFO    ][7182] All specified packages are already installed
2020-02-22 19:36:21,454 [salt.state       :1951][INFO    ][7182] Completed state [ca-certificates] at time 19:36:21.454697 duration_in_ms=12.558
2020-02-22 19:36:21,455 [salt.state       :1780][INFO    ][7182] Running state [update-ca-certificates] at time 19:36:21.455426
2020-02-22 19:36:21,455 [salt.state       :1813][INFO    ][7182] Executing state cmd.wait for [update-ca-certificates]
2020-02-22 19:36:21,456 [salt.state       :300 ][INFO    ][7182] No changes made for update-ca-certificates
2020-02-22 19:36:21,457 [salt.state       :1951][INFO    ][7182] Completed state [update-ca-certificates] at time 19:36:21.457561 duration_in_ms=2.135
2020-02-22 19:36:21,457 [salt.state       :1780][INFO    ][7182] Running state [iptables] at time 19:36:21.457852
2020-02-22 19:36:21,458 [salt.state       :1813][INFO    ][7182] Executing state pkg.installed for [iptables]
2020-02-22 19:36:21,467 [salt.state       :300 ][INFO    ][7182] All specified packages are already installed
2020-02-22 19:36:21,467 [salt.state       :1951][INFO    ][7182] Completed state [iptables] at time 19:36:21.467209 duration_in_ms=9.357
2020-02-22 19:36:21,467 [salt.state       :1780][INFO    ][7182] Running state [iptables-persistent] at time 19:36:21.467471
2020-02-22 19:36:21,467 [salt.state       :1813][INFO    ][7182] Executing state pkg.installed for [iptables-persistent]
2020-02-22 19:36:21,477 [salt.state       :300 ][INFO    ][7182] All specified packages are already installed
2020-02-22 19:36:21,477 [salt.state       :1951][INFO    ][7182] Completed state [iptables-persistent] at time 19:36:21.477780 duration_in_ms=10.309
2020-02-22 19:36:21,478 [salt.state       :1780][INFO    ][7182] Running state [iptables_modules_v4_load] at time 19:36:21.478812
2020-02-22 19:36:21,479 [salt.state       :1813][INFO    ][7182] Executing state kmod.present for [iptables_modules_v4_load]
2020-02-22 19:36:21,479 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7182] Executing command 'lsmod' in directory '/root'
2020-02-22 19:36:21,508 [salt.state       :300 ][INFO    ][7182] Kernel modules iptable_filter, ip_tables are already present
2020-02-22 19:36:21,508 [salt.state       :1951][INFO    ][7182] Completed state [iptables_modules_v4_load] at time 19:36:21.508625 duration_in_ms=29.812
2020-02-22 19:36:21,510 [salt.state       :1780][INFO    ][7182] Running state [/etc/iptables/rules.v4] at time 19:36:21.509910
2020-02-22 19:36:21,510 [salt.state       :1813][INFO    ][7182] Executing state file.managed for [/etc/iptables/rules.v4]
2020-02-22 19:36:21,617 [salt.state       :300 ][INFO    ][7182] File /etc/iptables/rules.v4 is in the correct state
2020-02-22 19:36:21,618 [salt.state       :1951][INFO    ][7182] Completed state [/etc/iptables/rules.v4] at time 19:36:21.618134 duration_in_ms=108.224
2020-02-22 19:36:21,619 [salt.state       :1780][INFO    ][7182] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 19:36:21.619283
2020-02-22 19:36:21,619 [salt.state       :1813][INFO    ][7182] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2020-02-22 19:36:21,620 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7182] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2020-02-22 19:36:21,643 [salt.state       :300 ][INFO    ][7182] onlyif execution failed
2020-02-22 19:36:21,643 [salt.state       :1951][INFO    ][7182] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 19:36:21.643810 duration_in_ms=24.526
2020-02-22 19:36:21,646 [salt.state       :1780][INFO    ][7182] Running state [netfilter-persistent] at time 19:36:21.646239
2020-02-22 19:36:21,646 [salt.state       :1813][INFO    ][7182] Executing state service.running for [netfilter-persistent]
2020-02-22 19:36:21,650 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7182] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2020-02-22 19:36:21,676 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7182] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2020-02-22 19:36:21,699 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7182] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2020-02-22 19:36:21,723 [salt.state       :300 ][INFO    ][7182] The service netfilter-persistent is already running
2020-02-22 19:36:21,723 [salt.state       :1951][INFO    ][7182] Completed state [netfilter-persistent] at time 19:36:21.723545 duration_in_ms=77.306
2020-02-22 19:36:21,725 [salt.state       :1780][INFO    ][7182] Running state [iptables_extra.remove_stale_tables] at time 19:36:21.725129
2020-02-22 19:36:21,725 [salt.state       :1813][INFO    ][7182] Executing state module.wait for [iptables_extra.remove_stale_tables]
2020-02-22 19:36:21,726 [salt.state       :300 ][INFO    ][7182] No changes made for iptables_extra.remove_stale_tables
2020-02-22 19:36:21,726 [salt.state       :1951][INFO    ][7182] Completed state [iptables_extra.remove_stale_tables] at time 19:36:21.726737 duration_in_ms=1.608
2020-02-22 19:36:21,727 [salt.state       :1780][INFO    ][7182] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 19:36:21.727241
2020-02-22 19:36:21,727 [salt.state       :1813][INFO    ][7182] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2020-02-22 19:36:21,729 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7182] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2020-02-22 19:36:21,748 [salt.state       :300 ][INFO    ][7182] onlyif execution failed
2020-02-22 19:36:21,749 [salt.state       :1951][INFO    ][7182] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 19:36:21.749120 duration_in_ms=21.878
2020-02-22 19:36:21,751 [salt.state       :1780][INFO    ][7182] Running state [/etc/iptables/rules.v6] at time 19:36:21.751112
2020-02-22 19:36:21,751 [salt.state       :1813][INFO    ][7182] Executing state file.absent for [/etc/iptables/rules.v6]
2020-02-22 19:36:21,752 [salt.state       :300 ][INFO    ][7182] File /etc/iptables/rules.v6 is not present
2020-02-22 19:36:21,753 [salt.state       :1951][INFO    ][7182] Completed state [/etc/iptables/rules.v6] at time 19:36:21.753034 duration_in_ms=1.922
2020-02-22 19:36:21,754 [salt.state       :1780][INFO    ][7182] Running state [iptables_extra.flush_all] at time 19:36:21.754474
2020-02-22 19:36:21,755 [salt.state       :1813][INFO    ][7182] Executing state module.wait for [iptables_extra.flush_all]
2020-02-22 19:36:21,755 [salt.state       :300 ][INFO    ][7182] No changes made for iptables_extra.flush_all
2020-02-22 19:36:21,756 [salt.state       :1951][INFO    ][7182] Completed state [iptables_extra.flush_all] at time 19:36:21.756043 duration_in_ms=1.569
2020-02-22 19:36:21,762 [salt.minion      :1711][INFO    ][7182] Returning information for job: 20200222193609178955
2020-02-22 19:36:22,754 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command state.apply with jid 20200222193622741723
2020-02-22 19:36:22,784 [salt.minion      :1432][INFO    ][7264] Starting a new job with PID 7264
2020-02-22 19:36:23,835 [salt.state       :915 ][INFO    ][7264] Loading fresh modules for state activity
2020-02-22 19:36:25,230 [salt.state       :1780][INFO    ][7264] Running state [maas-rack-controller] at time 19:36:25.230187
2020-02-22 19:36:25,230 [salt.state       :1813][INFO    ][7264] Executing state pkg.installed for [maas-rack-controller]
2020-02-22 19:36:25,231 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7264] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-22 19:36:25,348 [salt.state       :300 ][INFO    ][7264] All specified packages are already installed
2020-02-22 19:36:25,349 [salt.state       :1951][INFO    ][7264] Completed state [maas-rack-controller] at time 19:36:25.349153 duration_in_ms=118.966
2020-02-22 19:36:25,349 [salt.state       :1780][INFO    ][7264] Running state [ipmitool] at time 19:36:25.349504
2020-02-22 19:36:25,349 [salt.state       :1813][INFO    ][7264] Executing state pkg.installed for [ipmitool]
2020-02-22 19:36:25,360 [salt.state       :300 ][INFO    ][7264] All specified packages are already installed
2020-02-22 19:36:25,360 [salt.state       :1951][INFO    ][7264] Completed state [ipmitool] at time 19:36:25.360306 duration_in_ms=10.802
2020-02-22 19:36:25,363 [salt.state       :1780][INFO    ][7264] Running state [/etc/maas/rackd.conf] at time 19:36:25.363633
2020-02-22 19:36:25,364 [salt.state       :1813][INFO    ][7264] Executing state file.line for [/etc/maas/rackd.conf]
2020-02-22 19:36:25,365 [salt.state       :300 ][INFO    ][7264] No changes needed to be made
2020-02-22 19:36:25,365 [salt.state       :1951][INFO    ][7264] Completed state [/etc/maas/rackd.conf] at time 19:36:25.365535 duration_in_ms=1.902
2020-02-22 19:36:25,365 [salt.state       :1780][INFO    ][7264] Running state [/etc/maas/rackd.conf] at time 19:36:25.365843
2020-02-22 19:36:25,366 [salt.state       :1813][INFO    ][7264] Executing state file.managed for [/etc/maas/rackd.conf]
2020-02-22 19:36:25,366 [salt.loaded.int.states.file:2298][WARNING ][7264] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2020-02-22 19:36:25,367 [salt.state       :300 ][INFO    ][7264] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2020-02-22 19:36:25,367 [salt.state       :1951][INFO    ][7264] Completed state [/etc/maas/rackd.conf] at time 19:36:25.367257 duration_in_ms=1.414
2020-02-22 19:36:25,369 [salt.state       :1780][INFO    ][7264] Running state [maas-rackd] at time 19:36:25.368985
2020-02-22 19:36:25,369 [salt.state       :1813][INFO    ][7264] Executing state service.running for [maas-rackd]
2020-02-22 19:36:25,370 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7264] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2020-02-22 19:36:25,415 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7264] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2020-02-22 19:36:25,443 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7264] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2020-02-22 19:36:25,471 [salt.state       :300 ][INFO    ][7264] The service maas-rackd is already running
2020-02-22 19:36:25,471 [salt.state       :1951][INFO    ][7264] Completed state [maas-rackd] at time 19:36:25.471613 duration_in_ms=102.628
2020-02-22 19:36:25,473 [salt.minion      :1711][INFO    ][7264] Returning information for job: 20200222193622741723
2020-02-22 19:36:26,445 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command state.apply with jid 20200222193626432824
2020-02-22 19:36:26,477 [salt.minion      :1432][INFO    ][7300] Starting a new job with PID 7300
2020-02-22 19:36:27,533 [salt.state       :915 ][INFO    ][7300] Loading fresh modules for state activity
2020-02-22 19:36:29,046 [salt.state       :1780][INFO    ][7300] Running state [maas-region-controller] at time 19:36:29.045947
2020-02-22 19:36:29,046 [salt.state       :1813][INFO    ][7300] Executing state pkg.installed for [maas-region-controller]
2020-02-22 19:36:29,047 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7300] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-22 19:36:29,177 [salt.state       :300 ][INFO    ][7300] All specified packages are already installed
2020-02-22 19:36:29,177 [salt.state       :1951][INFO    ][7300] Completed state [maas-region-controller] at time 19:36:29.177613 duration_in_ms=131.666
2020-02-22 19:36:29,178 [salt.state       :1780][INFO    ][7300] Running state [python-oauth] at time 19:36:29.177975
2020-02-22 19:36:29,178 [salt.state       :1813][INFO    ][7300] Executing state pkg.installed for [python-oauth]
2020-02-22 19:36:29,190 [salt.state       :300 ][INFO    ][7300] All specified packages are already installed
2020-02-22 19:36:29,190 [salt.state       :1951][INFO    ][7300] Completed state [python-oauth] at time 19:36:29.190394 duration_in_ms=12.418
2020-02-22 19:36:29,194 [salt.state       :1780][INFO    ][7300] Running state [/etc/maas/regiond.conf] at time 19:36:29.194260
2020-02-22 19:36:29,194 [salt.state       :1813][INFO    ][7300] Executing state file.replace for [/etc/maas/regiond.conf]
2020-02-22 19:36:29,201 [salt.state       :300 ][INFO    ][7300] No changes needed to be made
2020-02-22 19:36:29,201 [salt.state       :1951][INFO    ][7300] Completed state [/etc/maas/regiond.conf] at time 19:36:29.201570 duration_in_ms=7.31
2020-02-22 19:36:29,202 [salt.state       :1780][INFO    ][7300] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 19:36:29.202069
2020-02-22 19:36:29,202 [salt.state       :1813][INFO    ][7300] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2020-02-22 19:36:29,270 [salt.state       :300 ][INFO    ][7300] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2020-02-22 19:36:29,270 [salt.state       :1951][INFO    ][7300] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 19:36:29.270687 duration_in_ms=68.618
2020-02-22 19:36:29,271 [salt.state       :1780][INFO    ][7300] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 19:36:29.271228
2020-02-22 19:36:29,271 [salt.state       :1813][INFO    ][7300] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-02-22 19:36:29,278 [salt.state       :300 ][INFO    ][7300] No changes needed to be made
2020-02-22 19:36:29,278 [salt.state       :1951][INFO    ][7300] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 19:36:29.278526 duration_in_ms=7.297
2020-02-22 19:36:29,279 [salt.state       :1780][INFO    ][7300] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 19:36:29.279527
2020-02-22 19:36:29,280 [salt.state       :1813][INFO    ][7300] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-02-22 19:36:29,287 [salt.state       :300 ][INFO    ][7300] No changes needed to be made
2020-02-22 19:36:29,287 [salt.state       :1951][INFO    ][7300] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 19:36:29.287405 duration_in_ms=7.879
2020-02-22 19:36:29,288 [salt.state       :1780][INFO    ][7300] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 19:36:29.287945
2020-02-22 19:36:29,288 [salt.state       :1813][INFO    ][7300] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2020-02-22 19:36:29,323 [salt.state       :300 ][INFO    ][7300] No changes needed to be made
2020-02-22 19:36:29,324 [salt.state       :1951][INFO    ][7300] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 19:36:29.324240 duration_in_ms=36.295
2020-02-22 19:36:29,324 [salt.state       :1780][INFO    ][7300] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 19:36:29.324641
2020-02-22 19:36:29,324 [salt.state       :1813][INFO    ][7300] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2020-02-22 19:36:29,343 [salt.state       :300 ][INFO    ][7300] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2020-02-22 19:36:29,343 [salt.state       :1951][INFO    ][7300] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 19:36:29.343354 duration_in_ms=18.713
2020-02-22 19:36:29,344 [salt.state       :1780][INFO    ][7300] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 19:36:29.344041
2020-02-22 19:36:29,344 [salt.state       :1813][INFO    ][7300] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2020-02-22 19:36:29,399 [salt.state       :300 ][INFO    ][7300] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2020-02-22 19:36:29,401 [salt.state       :1951][INFO    ][7300] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 19:36:29.400204 duration_in_ms=56.162
2020-02-22 19:36:29,402 [salt.state       :1780][INFO    ][7300] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 19:36:29.402016
2020-02-22 19:36:29,402 [salt.state       :1813][INFO    ][7300] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2020-02-22 19:36:29,462 [salt.state       :300 ][INFO    ][7300] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2020-02-22 19:36:29,462 [salt.state       :1951][INFO    ][7300] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 19:36:29.462726 duration_in_ms=60.711
2020-02-22 19:36:29,463 [salt.state       :1780][INFO    ][7300] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 19:36:29.463225
2020-02-22 19:36:29,463 [salt.state       :1813][INFO    ][7300] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2020-02-22 19:36:29,543 [salt.state       :300 ][INFO    ][7300] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2020-02-22 19:36:29,543 [salt.state       :1951][INFO    ][7300] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 19:36:29.543459 duration_in_ms=80.234
2020-02-22 19:36:29,544 [salt.state       :1780][INFO    ][7300] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 19:36:29.543957
2020-02-22 19:36:29,544 [salt.state       :1813][INFO    ][7300] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic]
2020-02-22 19:36:29,611 [salt.state       :300 ][INFO    ][7300] File /etc/maas/preseeds/curtin_userdata_amd64_generic_bionic is in the correct state
2020-02-22 19:36:29,611 [salt.state       :1951][INFO    ][7300] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 19:36:29.611736 duration_in_ms=67.779
2020-02-22 19:36:29,612 [salt.state       :1780][INFO    ][7300] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 19:36:29.612259
2020-02-22 19:36:29,612 [salt.state       :1813][INFO    ][7300] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic]
2020-02-22 19:36:29,690 [salt.state       :300 ][INFO    ][7300] File /etc/maas/preseeds/curtin_userdata_arm64_generic_bionic is in the correct state
2020-02-22 19:36:29,691 [salt.state       :1951][INFO    ][7300] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 19:36:29.691328 duration_in_ms=79.067
2020-02-22 19:36:29,691 [salt.state       :1780][INFO    ][7300] Running state [/root/.pgpass] at time 19:36:29.691751
2020-02-22 19:36:29,692 [salt.state       :1813][INFO    ][7300] Executing state file.managed for [/root/.pgpass]
2020-02-22 19:36:29,747 [salt.state       :300 ][INFO    ][7300] File /root/.pgpass is in the correct state
2020-02-22 19:36:29,748 [salt.state       :1951][INFO    ][7300] Completed state [/root/.pgpass] at time 19:36:29.747957 duration_in_ms=56.206
2020-02-22 19:36:29,754 [salt.state       :1780][INFO    ][7300] Running state [maas-region migrate --noinput] at time 19:36:29.754564
2020-02-22 19:36:29,754 [salt.state       :1813][INFO    ][7300] Executing state cmd.run for [maas-region migrate --noinput]
2020-02-22 19:36:29,755 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7300] Executing command 'maas-region migrate --noinput' in directory '/root'
2020-02-22 19:36:33,009 [salt.state       :300 ][INFO    ][7300] {'pid': 7331, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Apply all migrations: auth, contenttypes, maasserver, metadataserver, piston3, sessions, sites\nRunning migrations:\n  No migrations to apply.'}
2020-02-22 19:36:33,010 [salt.state       :1951][INFO    ][7300] Completed state [maas-region migrate --noinput] at time 19:36:33.009865 duration_in_ms=3255.3
2020-02-22 19:36:33,010 [salt.state       :2022][WARNING ][7300] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-02-22 19:36:33,013 [salt.state       :1780][INFO    ][7300] Running state [maas-regiond] at time 19:36:33.013615
2020-02-22 19:36:33,014 [salt.state       :1813][INFO    ][7300] Executing state service.running for [maas-regiond]
2020-02-22 19:36:33,015 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7300] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2020-02-22 19:36:33,066 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7300] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2020-02-22 19:36:33,090 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7300] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2020-02-22 19:36:33,117 [salt.state       :300 ][INFO    ][7300] The service maas-regiond is already running
2020-02-22 19:36:33,118 [salt.state       :1951][INFO    ][7300] Completed state [maas-regiond] at time 19:36:33.118230 duration_in_ms=104.614
2020-02-22 19:36:33,123 [salt.state       :1780][INFO    ][7300] Running state [bind9] at time 19:36:33.123744
2020-02-22 19:36:33,124 [salt.state       :1813][INFO    ][7300] Executing state service.running for [bind9]
2020-02-22 19:36:33,125 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7300] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2020-02-22 19:36:33,153 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7300] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2020-02-22 19:36:33,177 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7300] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2020-02-22 19:36:33,200 [salt.state       :300 ][INFO    ][7300] The service bind9 is already running
2020-02-22 19:36:33,201 [salt.state       :1951][INFO    ][7300] Completed state [bind9] at time 19:36:33.201425 duration_in_ms=77.68
2020-02-22 19:36:33,203 [salt.state       :1780][INFO    ][7300] Running state [maasng.wait_for_http_code] at time 19:36:33.203721
2020-02-22 19:36:33,207 [salt.state       :1813][INFO    ][7300] Executing state module.run for [maasng.wait_for_http_code]
2020-02-22 19:36:33,208 [salt.utils.decorators:613 ][WARNING ][7300] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 19:36:33,367 [salt.state       :300 ][INFO    ][7300] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2020-02-22 19:36:33,367 [salt.state       :1951][INFO    ][7300] Completed state [maasng.wait_for_http_code] at time 19:36:33.367874 duration_in_ms=164.153
2020-02-22 19:36:33,369 [salt.state       :1780][INFO    ][7300] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 19:36:33.369019
2020-02-22 19:36:33,369 [salt.state       :1813][INFO    ][7300] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2020-02-22 19:36:33,370 [salt.state       :300 ][INFO    ][7300] /var/lib/maas/.setup_admin exists
2020-02-22 19:36:33,370 [salt.state       :1951][INFO    ][7300] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 19:36:33.370687 duration_in_ms=1.667
2020-02-22 19:36:33,371 [salt.state       :1780][INFO    ][7300] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 19:36:33.371680
2020-02-22 19:36:33,372 [salt.state       :1813][INFO    ][7300] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-22 19:36:33,374 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7300] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-22 19:36:35,646 [salt.state       :300 ][INFO    ][7300] {'pid': 7346, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-22 19:36:35,647 [salt.state       :1951][INFO    ][7300] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 19:36:35.647038 duration_in_ms=2275.357
2020-02-22 19:36:35,654 [salt.state       :1780][INFO    ][7300] Running state [maas_region_boot_source_resources_mirror] at time 19:36:35.654299
2020-02-22 19:36:35,654 [salt.state       :1813][INFO    ][7300] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2020-02-22 19:36:35,715 [salt.state       :300 ][INFO    ][7300] {'changes': {}}
2020-02-22 19:36:35,715 [salt.state       :1951][INFO    ][7300] Completed state [maas_region_boot_source_resources_mirror] at time 19:36:35.715872 duration_in_ms=61.572
2020-02-22 19:36:35,718 [salt.state       :1780][INFO    ][7300] Running state [maasng.boot_resources_import] at time 19:36:35.718158
2020-02-22 19:36:35,718 [salt.state       :1813][INFO    ][7300] Executing state module.run for [maasng.boot_resources_import]
2020-02-22 19:36:35,719 [salt.utils.decorators:613 ][WARNING ][7300] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 19:36:35,783 [salt.loaded.ext.module.maasng:1600][INFO    ][7300] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2020-02-22 19:36:40,854 [salt.state       :300 ][INFO    ][7300] {'ret': True}
2020-02-22 19:36:40,854 [salt.state       :1951][INFO    ][7300] Completed state [maasng.boot_resources_import] at time 19:36:40.854849 duration_in_ms=5136.69
2020-02-22 19:36:40,855 [salt.state       :1780][INFO    ][7300] Running state [maas_region_boot_sources_selection_bionic] at time 19:36:40.855656
2020-02-22 19:36:40,856 [salt.state       :1813][INFO    ][7300] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_bionic]
2020-02-22 19:36:40,970 [salt.state       :300 ][INFO    ][7300] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2020-02-22 19:36:40,971 [salt.state       :1951][INFO    ][7300] Completed state [maas_region_boot_sources_selection_bionic] at time 19:36:40.971298 duration_in_ms=115.639
2020-02-22 19:36:40,973 [salt.state       :1780][INFO    ][7300] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 19:36:40.973643
2020-02-22 19:36:40,974 [salt.state       :1813][INFO    ][7300] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2020-02-22 19:36:40,975 [salt.utils.decorators:613 ][WARNING ][7300] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 19:36:40,975 [salt.loaded.ext.module.maasng:1771][INFO    ][7300] boot-sources sync initiated for ALL Rack's
2020-02-22 19:36:41,491 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222193641477704
2020-02-22 19:36:41,523 [salt.minion      :1432][INFO    ][7370] Starting a new job with PID 7370
2020-02-22 19:36:41,548 [salt.minion      :1711][INFO    ][7370] Returning information for job: 20200222193641477704
2020-02-22 19:36:41,761 [salt.state       :300 ][INFO    ][7300] {'ret': True}
2020-02-22 19:36:41,762 [salt.state       :1951][INFO    ][7300] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 19:36:41.762045 duration_in_ms=788.402
2020-02-22 19:36:41,765 [salt.state       :1780][INFO    ][7300] Running state [maas.process_maas_config] at time 19:36:41.764873
2020-02-22 19:36:41,765 [salt.state       :1813][INFO    ][7300] Executing state module.run for [maas.process_maas_config]
2020-02-22 19:36:41,766 [salt.utils.decorators:613 ][WARNING ][7300] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 19:36:41,767 [salt.loaded.ext.module.maas:92  ][INFO    ][7300] maasconfig name=enable_http_proxy value=True
2020-02-22 19:36:41,815 [salt.loaded.ext.module.maas:92  ][INFO    ][7300] maasconfig name=upstream_dns value=8.8.8.8
2020-02-22 19:36:41,860 [salt.loaded.ext.module.maas:92  ][INFO    ][7300] maasconfig name=commissioning_distro_series value=bionic
2020-02-22 19:36:41,921 [salt.loaded.ext.module.maas:92  ][INFO    ][7300] maasconfig name=default_osystem value=ubuntu
2020-02-22 19:36:43,225 [salt.loaded.ext.module.maas:92  ][INFO    ][7300] maasconfig name=active_discovery_interval value=600
2020-02-22 19:36:43,266 [salt.loaded.ext.module.maas:92  ][INFO    ][7300] maasconfig name=dnssec_validation value=no
2020-02-22 19:36:43,484 [salt.loaded.ext.module.maas:92  ][INFO    ][7300] maasconfig name=kernel_opts value=spectre_v2=off nopti kpti=off nospec_store_bypass_disable noibrs noibpb
2020-02-22 19:36:43,521 [salt.loaded.ext.module.maas:92  ][INFO    ][7300] maasconfig name=network_discovery value=enabled
2020-02-22 19:36:43,607 [salt.loaded.ext.module.maas:92  ][INFO    ][7300] maasconfig name=enable_third_party_drivers value=True
2020-02-22 19:36:43,636 [salt.loaded.ext.module.maas:92  ][INFO    ][7300] maasconfig name=default_storage_layout value=lvm
2020-02-22 19:36:43,673 [salt.loaded.ext.module.maas:92  ][INFO    ][7300] maasconfig name=maas_name value=mas01
2020-02-22 19:36:43,727 [salt.loaded.ext.module.maas:92  ][INFO    ][7300] maasconfig name=disk_erase_with_secure_erase value=False
2020-02-22 19:36:43,774 [salt.loaded.ext.module.maas:92  ][INFO    ][7300] maasconfig name=default_distro_series value=bionic
2020-02-22 19:36:43,818 [salt.loaded.ext.module.maas:92  ][INFO    ][7300] maasconfig name=ntp_external_only value=True
2020-02-22 19:36:43,855 [salt.loaded.ext.module.maas:92  ][INFO    ][7300] maasconfig name=default_min_hwe_kernel value=ga-18.04
2020-02-22 19:36:43,907 [salt.state       :300 ][INFO    ][7300] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'kernel_opts', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'maas_name', 'disk_erase_with_secure_erase', 'default_distro_series', 'ntp_external_only', 'default_min_hwe_kernel']}}
2020-02-22 19:36:43,907 [salt.state       :1951][INFO    ][7300] Completed state [maas.process_maas_config] at time 19:36:43.907634 duration_in_ms=2143.392
2020-02-22 19:36:43,910 [salt.state       :1780][INFO    ][7300] Running state [pxe_admin] at time 19:36:43.910557
2020-02-22 19:36:43,911 [salt.state       :1813][INFO    ][7300] Executing state maasng.fabric_present for [pxe_admin]
2020-02-22 19:36:43,953 [salt.loaded.ext.module.maasng:945 ][INFO    ][7300] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eb3pgt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'name': u'pxe_admin', u'id': 1}]
2020-02-22 19:36:44,008 [salt.loaded.ext.module.maasng:1008][WARNING ][7300] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2020-02-22 19:36:44,008 [salt.loaded.ext.module.maasng:1011][WARNING ][7300] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2020-02-22 19:36:44,049 [salt.state       :300 ][INFO    ][7300] {'new': 'Fabric  pxe_admin created', 'result': True}
2020-02-22 19:36:44,049 [salt.state       :1951][INFO    ][7300] Completed state [pxe_admin] at time 19:36:44.049758 duration_in_ms=139.2
2020-02-22 19:36:44,050 [salt.state       :1780][INFO    ][7300] Running state [vlan 0] at time 19:36:44.050321
2020-02-22 19:36:44,051 [salt.state       :1813][INFO    ][7300] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-02-22 19:36:44,093 [salt.loaded.ext.module.maasng:945 ][INFO    ][7300] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eb3pgt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'name': u'pxe_admin', u'id': 1}]
2020-02-22 19:36:44,178 [salt.loaded.ext.module.maasng:945 ][INFO    ][7300] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eb3pgt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'pxe_admin', u'id': 1}]
2020-02-22 19:36:44,394 [salt.loaded.ext.module.maasng:945 ][INFO    ][7300] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eb3pgt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'name': u'pxe_admin', u'id': 1}]
2020-02-22 19:36:44,467 [salt.state       :300 ][INFO    ][7300] {'new': 'Vlan untagged was updated'}
2020-02-22 19:36:44,467 [salt.state       :1951][INFO    ][7300] Completed state [vlan 0] at time 19:36:44.467605 duration_in_ms=417.283
2020-02-22 19:36:44,469 [salt.state       :1780][INFO    ][7300] Running state [192.168.11.0/24] at time 19:36:44.469795
2020-02-22 19:36:44,470 [salt.state       :1813][INFO    ][7300] Executing state maasng.subnet_present for [192.168.11.0/24]
2020-02-22 19:36:44,628 [salt.loaded.ext.module.maasng:945 ][INFO    ][7300] [{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'eb3pgt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-02-22 19:36:44,631 [salt.loaded.ext.module.maasng:1235][WARNING ][7300] Ignoring parameter vlan:0
2020-02-22 19:36:44,676 [salt.state       :300 ][INFO    ][7300] Subnet 192.168.11.0/24 has been updated for pxe_admin
2020-02-22 19:36:44,678 [salt.state       :1951][INFO    ][7300] Completed state [192.168.11.0/24] at time 19:36:44.677965 duration_in_ms=208.171
2020-02-22 19:36:44,679 [salt.state       :1780][INFO    ][7300] Running state [maas_create_iprange_1] at time 19:36:44.679050
2020-02-22 19:36:44,679 [salt.state       :1813][INFO    ][7300] Executing state maasng.iprange_present for [maas_create_iprange_1]
2020-02-22 19:36:44,713 [salt.state       :300 ][INFO    ][7300] Iprange maas_create_iprange_1 already exist.
2020-02-22 19:36:44,714 [salt.state       :1951][INFO    ][7300] Completed state [maas_create_iprange_1] at time 19:36:44.713914 duration_in_ms=34.864
2020-02-22 19:36:44,714 [salt.state       :1780][INFO    ][7300] Running state [vlan 0] at time 19:36:44.714398
2020-02-22 19:36:44,715 [salt.state       :1813][INFO    ][7300] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-02-22 19:36:44,758 [salt.loaded.ext.module.maasng:945 ][INFO    ][7300] [{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'eb3pgt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-02-22 19:36:44,861 [salt.loaded.ext.module.maasng:945 ][INFO    ][7300] [{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'eb3pgt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-02-22 19:36:45,057 [salt.loaded.ext.module.maasng:945 ][INFO    ][7300] [{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'eb3pgt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-02-22 19:36:45,139 [salt.state       :300 ][INFO    ][7300] {'new': 'Vlan untagged was updated'}
2020-02-22 19:36:45,139 [salt.state       :1951][INFO    ][7300] Completed state [vlan 0] at time 19:36:45.139618 duration_in_ms=425.219
2020-02-22 19:36:45,140 [salt.state       :1780][INFO    ][7300] Running state [opnfv] at time 19:36:45.140594
2020-02-22 19:36:45,141 [salt.state       :1813][INFO    ][7300] Executing state maasng.sshkey_present for [opnfv]
2020-02-22 19:36:45,177 [salt.loaded.ext.module.maasng:1903][INFO    ][7300] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2020-02-22 19:36:45,178 [salt.state       :300 ][INFO    ][7300] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2020-02-22 19:36:45,178 [salt.state       :1951][INFO    ][7300] Completed state [opnfv] at time 19:36:45.178511 duration_in_ms=37.916
2020-02-22 19:36:45,184 [salt.minion      :1711][INFO    ][7300] Returning information for job: 20200222193626432824
2020-02-22 19:36:46,327 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command state.apply with jid 20200222193646316724
2020-02-22 19:36:46,354 [salt.minion      :1432][INFO    ][7421] Starting a new job with PID 7421
2020-02-22 19:36:52,791 [salt.state       :915 ][INFO    ][7421] Loading fresh modules for state activity
2020-02-22 19:36:52,910 [salt.state       :1780][INFO    ][7421] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 19:36:52.910674
2020-02-22 19:36:52,911 [salt.state       :1813][INFO    ][7421] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-22 19:36:52,914 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7421] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-22 19:36:55,249 [salt.state       :300 ][INFO    ][7421] {'pid': 7428, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-22 19:36:55,250 [salt.state       :1951][INFO    ][7421] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 19:36:55.250455 duration_in_ms=2339.783
2020-02-22 19:36:55,254 [salt.state       :1780][INFO    ][7421] Running state [maas.process_machines] at time 19:36:55.254050
2020-02-22 19:36:55,254 [salt.state       :1813][INFO    ][7421] Executing state module.run for [maas.process_machines]
2020-02-22 19:36:55,255 [salt.utils.decorators:613 ][WARNING ][7421] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 19:36:55,907 [salt.loaded.ext.module.maas:412 ][WARNING ][7421] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-22 19:36:55,907 [salt.loaded.ext.module.maas:92  ][INFO    ][7421] 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=7k7h3r architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-22 19:36:56,623 [salt.loaded.ext.module.maas:412 ][WARNING ][7421] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-22 19:36:56,624 [salt.loaded.ext.module.maas:92  ][INFO    ][7421] 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=qgs64b architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-22 19:36:57,367 [salt.loaded.ext.module.maas:412 ][WARNING ][7421] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-22 19:36:57,368 [salt.loaded.ext.module.maas:92  ][INFO    ][7421] 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=7f38de architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-22 19:36:58,125 [salt.loaded.ext.module.maas:412 ][WARNING ][7421] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-22 19:36:58,126 [salt.loaded.ext.module.maas:92  ][INFO    ][7421] 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=gb6tfx architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-22 19:36:58,851 [salt.loaded.ext.module.maas:412 ][WARNING ][7421] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-22 19:36:58,852 [salt.loaded.ext.module.maas:92  ][INFO    ][7421] 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=e4qd7k architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-22 19:36:59,587 [salt.state       :300 ][INFO    ][7421] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2020-02-22 19:36:59,587 [salt.state       :1951][INFO    ][7421] Completed state [maas.process_machines] at time 19:36:59.587556 duration_in_ms=4333.506
2020-02-22 19:36:59,592 [salt.minion      :1711][INFO    ][7421] Returning information for job: 20200222193646316724
2020-02-22 19:37:34,287 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command state.apply with jid 20200222193734271358
2020-02-22 19:37:34,315 [salt.minion      :1432][INFO    ][7473] Starting a new job with PID 7473
2020-02-22 19:37:40,802 [salt.state       :915 ][INFO    ][7473] Loading fresh modules for state activity
2020-02-22 19:37:40,921 [salt.state       :1780][INFO    ][7473] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 19:37:40.921731
2020-02-22 19:37:40,922 [salt.state       :1813][INFO    ][7473] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-22 19:37:40,924 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7473] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-22 19:37:43,115 [salt.state       :300 ][INFO    ][7473] {'pid': 7482, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-22 19:37:43,117 [salt.state       :1951][INFO    ][7473] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 19:37:43.117540 duration_in_ms=2195.809
2020-02-22 19:37:43,121 [salt.state       :1780][INFO    ][7473] Running state [maas.wait_for_machine_status] at time 19:37:43.120958
2020-02-22 19:37:43,121 [salt.state       :1813][INFO    ][7473] Executing state module.run for [maas.wait_for_machine_status]
2020-02-22 19:37:43,122 [salt.utils.decorators:613 ][WARNING ][7473] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 19:37:46,644 [salt.state       :300 ][INFO    ][7473] {'ret': True}
2020-02-22 19:37:46,645 [salt.state       :1951][INFO    ][7473] Completed state [maas.wait_for_machine_status] at time 19:37:46.645365 duration_in_ms=3524.407
2020-02-22 19:37:46,651 [salt.minion      :1711][INFO    ][7473] Returning information for job: 20200222193734271358
2020-02-22 19:37:47,603 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command state.apply with jid 20200222193747595130
2020-02-22 19:37:47,631 [salt.minion      :1432][INFO    ][7501] Starting a new job with PID 7501
2020-02-22 19:37:48,593 [salt.state       :915 ][INFO    ][7501] Loading fresh modules for state activity
2020-02-22 19:37:48,757 [salt.state       :1780][INFO    ][7501] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 19:37:48.757683
2020-02-22 19:37:48,758 [salt.state       :1813][INFO    ][7501] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-22 19:37:48,759 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7501] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-22 19:37:50,879 [salt.state       :300 ][INFO    ][7501] {'pid': 7508, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-22 19:37:50,882 [salt.state       :1951][INFO    ][7501] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 19:37:50.882157 duration_in_ms=2124.471
2020-02-22 19:37:50,885 [salt.state       :1780][INFO    ][7501] Running state [maas_machines_storage_cmp002_lvm] at time 19:37:50.885089
2020-02-22 19:37:50,885 [salt.state       :1813][INFO    ][7501] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2020-02-22 19:37:52,123 [salt.loaded.ext.module.maasng:610 ][INFO    ][7501] 7k7h3r
2020-02-22 19:37:52,124 [salt.loaded.ext.module.maasng:626 ][INFO    ][7501] sda
2020-02-22 19:37:52,751 [salt.loaded.ext.module.maasng:361 ][INFO    ][7501] 7k7h3r
2020-02-22 19:37:52,822 [salt.loaded.ext.module.maasng:367 ][INFO    ][7501] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'7k7h3r', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'partitions': [{u'uuid': u'd04ae566-927d-465c-b30b-c3bdf9a499ae', u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/blockdevices/1/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7k7h3r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'82f31cdb-48fd-456d-bf1b-9800d36a613e', 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'size': 800097042432, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/blockdevices/13/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'uuid': u'2d110813-76ad-4674-bc8e-5197a0acd1e1', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'7k7h3r', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'3b3e6c14-b427-4fec-85ff-aadd9138b8cc', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 13, u'partitions': []}]
2020-02-22 19:37:52,822 [salt.loaded.ext.module.maasng:632 ][INFO    ][7501] vgroot
2020-02-22 19:37:52,822 [salt.loaded.ext.module.maasng:635 ][INFO    ][7501] lvroot
2020-02-22 19:37:52,823 [salt.loaded.ext.module.maasng:639 ][INFO    ][7501] 107374182400
2020-02-22 19:37:53,408 [salt.loaded.ext.module.maasng:645 ][INFO    ][7501] {u'hwe_kernel': None, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'eb3pgt', 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': 64, 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'eb3pgt', 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'effective_mtu': 1500, u'id': 4, u'discovered': [], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'7k7h3r', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/interfaces/4/'}, 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'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/7k7h3r/', 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'commissioning_status': 2, u'testing_status': 2, u'system_id': u'7k7h3r', 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'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/blockdevices/15/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'15a0547f-f58c-4680-91f3-65c40bf06afd', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'7k7h3r', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'd84ff82b-d83a-4581-ba00-a302022e4137', u'fstype': u'ext4'}, 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'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'7k7h3r', u'id': 10}], u'special_filesystems': [], u'current_commissioning_result_id': 19, u'node_type_name': u'Machine', u'current_testing_result_id': 20, u'cpu_test_status': -1, u'pool': {u'id': 0, u'resource_uri': u'/MAAS/api/2.0/resourcepool/0/', u'name': u'default', u'description': u'Default pool'}, u'storage_test_status': 2, u'other_test_status': -1, u'locked': False, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'path': u'/dev/disk/by-dname/sda', 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'7k7h3r', u'partition_table_type': u'MBR', u'filesystem': None, 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'285b81a7-ae32-496c-a273-cee43e7e5cd8', u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/blockdevices/1/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7k7h3r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'eb4c5183-e36e-4ed5-905a-902e62da0fba', 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'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'ip_addresses': [], u'min_hwe_kernel': u'ga-18.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'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'eb3pgt', 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': 64, 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'eb3pgt', 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'effective_mtu': 1500, u'id': 4, u'discovered': [], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'7k7h3r', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/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'effective_mtu': 1500, u'id': 11, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'7k7h3r', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/interfaces/11/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eb3pgt', 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': 65, 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'eb3pgt', 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'effective_mtu': 1500, u'id': 12, u'discovered': [], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'7k7h3r', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/interfaces/12/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 13, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'7k7h3r', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/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'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'effective_mtu': 1500, u'id': 14, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'7k7h3r', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/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'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'effective_mtu': 1500, u'id': 15, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'7k7h3r', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/interfaces/15/'}], u'address_ttl': None, u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'285b81a7-ae32-496c-a273-cee43e7e5cd8', u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/blockdevices/1/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7k7h3r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'eb4c5183-e36e-4ed5-905a-902e62da0fba', 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'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'7k7h3r', u'partition_table_type': u'MBR', u'filesystem': None, 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/7k7h3r/blockdevices/15/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'15a0547f-f58c-4680-91f3-65c40bf06afd', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'7k7h3r', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'd84ff82b-d83a-4581-ba00-a302022e4137', u'fstype': u'ext4'}, 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'distro_series': u'', u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'path': u'/dev/disk/by-dname/sda', 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'7k7h3r', u'partition_table_type': u'MBR', u'filesystem': None, 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'285b81a7-ae32-496c-a273-cee43e7e5cd8', u'resource_uri': u'/MAAS/api/2.0/nodes/7k7h3r/blockdevices/1/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7k7h3r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'eb4c5183-e36e-4ed5-905a-902e62da0fba', 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-02-22 19:37:53,410 [salt.state       :300 ][INFO    ][7501] {'new': {'storage_layout': 'lvm'}}
2020-02-22 19:37:53,410 [salt.state       :1951][INFO    ][7501] Completed state [maas_machines_storage_cmp002_lvm] at time 19:37:53.410266 duration_in_ms=2525.177
2020-02-22 19:37:53,410 [salt.state       :1780][INFO    ][7501] Running state [maas_machines_storage_cmp001_lvm] at time 19:37:53.410685
2020-02-22 19:37:53,411 [salt.state       :1813][INFO    ][7501] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2020-02-22 19:37:54,619 [salt.loaded.ext.module.maasng:610 ][INFO    ][7501] qgs64b
2020-02-22 19:37:54,619 [salt.loaded.ext.module.maasng:626 ][INFO    ][7501] sda
2020-02-22 19:37:55,233 [salt.loaded.ext.module.maasng:361 ][INFO    ][7501] qgs64b
2020-02-22 19:37:55,302 [salt.loaded.ext.module.maasng:367 ][INFO    ][7501] [{u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'9a76dc2e-bce0-4a72-95ed-022060512e9e', u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/2/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qgs64b', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd2464b8d-fd1c-4a2d-b685-be4c3240b757', 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'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'qgs64b', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/14/', u'uuid': u'b7183989-a40b-45bf-a5cf-e68a029ef63a', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'qgs64b', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'169857a4-4395-4b84-8396-8eb6f503bb72', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 14, u'serial': None, u'size': 800097042432}]
2020-02-22 19:37:55,302 [salt.loaded.ext.module.maasng:632 ][INFO    ][7501] vgroot
2020-02-22 19:37:55,302 [salt.loaded.ext.module.maasng:635 ][INFO    ][7501] lvroot
2020-02-22 19:37:55,303 [salt.loaded.ext.module.maasng:639 ][INFO    ][7501] 107374182400
2020-02-22 19:37:55,861 [salt.loaded.ext.module.maasng:645 ][INFO    ][7501] {u'hwe_kernel': None, u'testing_status_name': u'Passed', u'ip_addresses': [], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', 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'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'qgs64b', 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'partitions': [{u'uuid': u'30efdfec-61e4-4b31-9940-99ca4549726e', u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/2/partition/11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qgs64b', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'4b1f4589-239b-4f16-9557-6d8469f42228', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 11, u'size': 800101236736}], u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qgs64b', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'30efdfec-61e4-4b31-9940-99ca4549726e', u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/2/partition/11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qgs64b', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'4b1f4589-239b-4f16-9557-6d8469f42228', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 11, u'size': 800101236736}]}, {u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/16/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'49212a93-b65a-4c0b-9c19-e8a4994ca71a', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qgs64b', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'014edb7c-3983-425e-8c01-419845049e39', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'block_size': 4096, u'type': u'virtual', u'id': 16, u'serial': None, u'partitions': []}], 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/qgs64b/', u'hardware_info': {u'mainboard_firmware_version': u'I31', u'mainboard_product': u'Unknown', u'system_vendor': u'HP', u'cpu_model': u'Intel(R) Xeon(R) CPU E5-2680 v2', u'mainboard_firmware_date': u'06/01/2015', u'mainboard_vendor': u'Unknown', u'system_serial': u'USE410T2XY', u'system_version': u'Unknown', u'system_product': u'ProLiant BL460c Gen8 (735151-B21)'}, u'hostname': u'cmp001', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'qgs64b', 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'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eb3pgt', 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': 71, 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'eb3pgt', 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'effective_mtu': 1500, u'id': 5, u'discovered': [], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'qgs64b', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/interfaces/5/'}, u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'qgs64b', u'id': 11}], u'special_filesystems': [], u'current_commissioning_result_id': 21, u'node_type_name': u'Machine', u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eb3pgt', 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': 71, 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'eb3pgt', 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'effective_mtu': 1500, u'id': 5, u'discovered': [], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'qgs64b', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/interfaces/5/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 16, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'qgs64b', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/interfaces/16/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'eb3pgt', 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': 72, 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'eb3pgt', 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'effective_mtu': 1500, u'id': 17, u'discovered': [], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'qgs64b', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/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'effective_mtu': 1500, u'id': 18, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'qgs64b', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/interfaces/18/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 73, 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'effective_mtu': 1500, u'id': 19, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'qgs64b', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/interfaces/19/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 74, 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'effective_mtu': 1500, u'id': 20, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'qgs64b', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/interfaces/20/'}], u'current_testing_result_id': 22, u'cpu_test_status': -1, u'pool': {u'id': 0, u'resource_uri': u'/MAAS/api/2.0/resourcepool/0/', u'name': u'default', u'description': u'Default pool'}, u'bcaches': [], u'locked': False, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'qgs64b', 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'partitions': [{u'uuid': u'30efdfec-61e4-4b31-9940-99ca4549726e', u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/2/partition/11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qgs64b', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'4b1f4589-239b-4f16-9557-6d8469f42228', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 11, u'size': 800101236736}], u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'49212a93-b65a-4c0b-9c19-e8a4994ca71a', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/qgs64b/blockdevices/16/', u'system_id': u'qgs64b', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'014edb7c-3983-425e-8c01-419845049e39', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'partitions': [], u'type': u'virtual', u'id': 16, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2020-02-22 19:37:55,863 [salt.state       :300 ][INFO    ][7501] {'new': {'storage_layout': 'lvm'}}
2020-02-22 19:37:55,864 [salt.state       :1951][INFO    ][7501] Completed state [maas_machines_storage_cmp001_lvm] at time 19:37:55.863935 duration_in_ms=2453.248
2020-02-22 19:37:55,867 [salt.minion      :1711][INFO    ][7501] Returning information for job: 20200222193747595130
2020-02-22 19:37:56,887 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command state.apply with jid 20200222193756824445
2020-02-22 19:37:56,910 [salt.minion      :1432][INFO    ][7536] Starting a new job with PID 7536
2020-02-22 19:38:03,221 [salt.state       :915 ][INFO    ][7536] Loading fresh modules for state activity
2020-02-22 19:38:03,338 [salt.state       :1780][INFO    ][7536] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 19:38:03.338346
2020-02-22 19:38:03,338 [salt.state       :1813][INFO    ][7536] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-22 19:38:03,342 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7536] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-22 19:38:05,655 [salt.state       :300 ][INFO    ][7536] {'pid': 7543, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-22 19:38:05,656 [salt.state       :1951][INFO    ][7536] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 19:38:05.656695 duration_in_ms=2318.349
2020-02-22 19:38:05,659 [salt.state       :1780][INFO    ][7536] Running state [maas.deploy_machines] at time 19:38:05.659348
2020-02-22 19:38:05,659 [salt.state       :1813][INFO    ][7536] Executing state module.run for [maas.deploy_machines]
2020-02-22 19:38:05,661 [salt.utils.decorators:613 ][WARNING ][7536] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 19:38:06,309 [salt.loaded.ext.module.maas:684 ][INFO    ][7536] deploymachines hwe_kernel=ga-18.04 system_id=7k7h3r distro_series=bionic
2020-02-22 19:38:08,395 [salt.loaded.ext.module.maas:684 ][INFO    ][7536] deploymachines hwe_kernel=ga-18.04 system_id=qgs64b distro_series=bionic
2020-02-22 19:38:10,305 [salt.state       :300 ][INFO    ][7536] {'ret': {'updated': ['kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': ['cmp002', 'cmp001']}}
2020-02-22 19:38:10,306 [salt.state       :1951][INFO    ][7536] Completed state [maas.deploy_machines] at time 19:38:10.306076 duration_in_ms=4646.727
2020-02-22 19:38:10,313 [salt.minion      :1711][INFO    ][7536] Returning information for job: 20200222193756824445
2020-02-22 19:38:11,243 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command state.apply with jid 20200222193811234107
2020-02-22 19:38:11,266 [salt.minion      :1432][INFO    ][7565] Starting a new job with PID 7565
2020-02-22 19:38:12,226 [salt.state       :915 ][INFO    ][7565] Loading fresh modules for state activity
2020-02-22 19:38:12,339 [salt.state       :1780][INFO    ][7565] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 19:38:12.339472
2020-02-22 19:38:12,339 [salt.state       :1813][INFO    ][7565] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-22 19:38:12,341 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7565] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-22 19:38:14,682 [salt.state       :300 ][INFO    ][7565] {'pid': 7572, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-22 19:38:14,683 [salt.state       :1951][INFO    ][7565] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 19:38:14.683476 duration_in_ms=2344.003
2020-02-22 19:38:14,685 [salt.state       :1780][INFO    ][7565] Running state [maas.wait_for_machine_status] at time 19:38:14.685615
2020-02-22 19:38:14,686 [salt.state       :1813][INFO    ][7565] Executing state module.run for [maas.wait_for_machine_status]
2020-02-22 19:38:14,686 [salt.utils.decorators:613 ][WARNING ][7565] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 19:38:17,804 [salt.loaded.ext.module.maas:1024][INFO    ][7565] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2246.90281296s left)
2020-02-22 19:38:26,298 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222193826283563
2020-02-22 19:38:26,325 [salt.minion      :1432][INFO    ][7605] Starting a new job with PID 7605
2020-02-22 19:38:26,356 [salt.minion      :1711][INFO    ][7605] Returning information for job: 20200222193826283563
2020-02-22 19:38:50,858 [salt.loaded.ext.module.maas:1024][INFO    ][7565] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2213.84908485s left)
2020-02-22 19:38:56,405 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222193856390401
2020-02-22 19:38:56,427 [salt.minion      :1432][INFO    ][7643] Starting a new job with PID 7643
2020-02-22 19:38:56,456 [salt.minion      :1711][INFO    ][7643] Returning information for job: 20200222193856390401
2020-02-22 19:39:24,105 [salt.loaded.ext.module.maas:1024][INFO    ][7565] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2180.60170388s left)
2020-02-22 19:39:26,479 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222193926466339
2020-02-22 19:39:26,507 [salt.minion      :1432][INFO    ][7676] Starting a new job with PID 7676
2020-02-22 19:39:26,537 [salt.minion      :1711][INFO    ][7676] Returning information for job: 20200222193926466339
2020-02-22 19:39:56,556 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222193956547760
2020-02-22 19:39:56,575 [salt.minion      :1432][INFO    ][7703] Starting a new job with PID 7703
2020-02-22 19:39:56,608 [salt.minion      :1711][INFO    ][7703] Returning information for job: 20200222193956547760
2020-02-22 19:39:57,228 [salt.loaded.ext.module.maas:1024][INFO    ][7565] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2147.47919989s left)
2020-02-22 19:40:26,635 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222194026619711
2020-02-22 19:40:26,662 [salt.minion      :1432][INFO    ][7729] Starting a new job with PID 7729
2020-02-22 19:40:26,684 [salt.minion      :1711][INFO    ][7729] Returning information for job: 20200222194026619711
2020-02-22 19:40:30,471 [salt.loaded.ext.module.maas:1024][INFO    ][7565] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2114.23618388s left)
2020-02-22 19:40:56,718 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222194056708677
2020-02-22 19:40:56,746 [salt.minion      :1432][INFO    ][7768] Starting a new job with PID 7768
2020-02-22 19:40:56,771 [salt.minion      :1711][INFO    ][7768] Returning information for job: 20200222194056708677
2020-02-22 19:41:03,659 [salt.loaded.ext.module.maas:1024][INFO    ][7565] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2081.04752493s left)
2020-02-22 19:41:26,814 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222194126794903
2020-02-22 19:41:26,841 [salt.minion      :1432][INFO    ][7826] Starting a new job with PID 7826
2020-02-22 19:41:26,866 [salt.minion      :1711][INFO    ][7826] Returning information for job: 20200222194126794903
2020-02-22 19:41:37,594 [salt.loaded.ext.module.maas:1024][INFO    ][7565] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2047.11260295s left)
2020-02-22 19:41:56,921 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222194156911950
2020-02-22 19:41:56,949 [salt.minion      :1432][INFO    ][7880] Starting a new job with PID 7880
2020-02-22 19:41:56,975 [salt.minion      :1711][INFO    ][7880] Returning information for job: 20200222194156911950
2020-02-22 19:42:10,973 [salt.loaded.ext.module.maas:1024][INFO    ][7565] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2013.73380589s left)
2020-02-22 19:42:27,008 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222194226995362
2020-02-22 19:42:27,033 [salt.minion      :1432][INFO    ][7908] Starting a new job with PID 7908
2020-02-22 19:42:27,056 [salt.minion      :1711][INFO    ][7908] Returning information for job: 20200222194226995362
2020-02-22 19:42:44,283 [salt.loaded.ext.module.maas:1024][INFO    ][7565] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1980.42414594s left)
2020-02-22 19:42:57,090 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222194257078361
2020-02-22 19:42:57,114 [salt.minion      :1432][INFO    ][7935] Starting a new job with PID 7935
2020-02-22 19:42:57,140 [salt.minion      :1711][INFO    ][7935] Returning information for job: 20200222194257078361
2020-02-22 19:43:17,702 [salt.loaded.ext.module.maas:1024][INFO    ][7565] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1947.00455689s left)
2020-02-22 19:43:27,189 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222194327173822
2020-02-22 19:43:27,218 [salt.minion      :1432][INFO    ][7962] Starting a new job with PID 7962
2020-02-22 19:43:27,254 [salt.minion      :1711][INFO    ][7962] Returning information for job: 20200222194327173822
2020-02-22 19:43:51,115 [salt.loaded.ext.module.maas:1024][INFO    ][7565] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1913.5922718s left)
2020-02-22 19:43:57,312 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222194357302355
2020-02-22 19:43:57,343 [salt.minion      :1432][INFO    ][7992] Starting a new job with PID 7992
2020-02-22 19:43:57,379 [salt.minion      :1711][INFO    ][7992] Returning information for job: 20200222194357302355
2020-02-22 19:44:24,510 [salt.loaded.ext.module.maas:1024][INFO    ][7565] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1880.19672298s left)
2020-02-22 19:44:27,443 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222194427429590
2020-02-22 19:44:27,466 [salt.minion      :1432][INFO    ][8020] Starting a new job with PID 8020
2020-02-22 19:44:27,488 [salt.minion      :1711][INFO    ][8020] Returning information for job: 20200222194427429590
2020-02-22 19:44:57,551 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222194457542631
2020-02-22 19:44:57,572 [salt.minion      :1432][INFO    ][8048] Starting a new job with PID 8048
2020-02-22 19:44:57,599 [salt.minion      :1711][INFO    ][8048] Returning information for job: 20200222194457542631
2020-02-22 19:44:58,191 [salt.loaded.ext.module.maas:1024][INFO    ][7565] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1846.5162189s left)
2020-02-22 19:45:27,646 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222194527633691
2020-02-22 19:45:27,667 [salt.minion      :1432][INFO    ][8073] Starting a new job with PID 8073
2020-02-22 19:45:27,695 [salt.minion      :1711][INFO    ][8073] Returning information for job: 20200222194527633691
2020-02-22 19:45:31,555 [salt.loaded.ext.module.maas:1024][INFO    ][7565] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1813.15151596s left)
2020-02-22 19:45:57,762 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222194557752877
2020-02-22 19:45:57,788 [salt.minion      :1432][INFO    ][8100] Starting a new job with PID 8100
2020-02-22 19:45:57,811 [salt.minion      :1711][INFO    ][8100] Returning information for job: 20200222194557752877
2020-02-22 19:46:05,026 [salt.loaded.ext.module.maas:1024][INFO    ][7565] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1779.68130684s left)
2020-02-22 19:46:27,885 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222194627871639
2020-02-22 19:46:27,910 [salt.minion      :1432][INFO    ][8127] Starting a new job with PID 8127
2020-02-22 19:46:27,935 [salt.minion      :1711][INFO    ][8127] Returning information for job: 20200222194627871639
2020-02-22 19:46:38,694 [salt.loaded.ext.module.maas:1024][INFO    ][7565] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1746.01327682s left)
2020-02-22 19:46:58,014 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222194658006026
2020-02-22 19:46:58,042 [salt.minion      :1432][INFO    ][8155] Starting a new job with PID 8155
2020-02-22 19:46:58,073 [salt.minion      :1711][INFO    ][8155] Returning information for job: 20200222194658006026
2020-02-22 19:47:12,219 [salt.loaded.ext.module.maas:1024][INFO    ][7565] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1712.48833489s left)
2020-02-22 19:47:28,156 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222194728142668
2020-02-22 19:47:28,184 [salt.minion      :1432][INFO    ][8185] Starting a new job with PID 8185
2020-02-22 19:47:28,214 [salt.minion      :1711][INFO    ][8185] Returning information for job: 20200222194728142668
2020-02-22 19:47:45,716 [salt.loaded.ext.module.maas:1024][INFO    ][7565] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1678.99050689s left)
2020-02-22 19:47:58,305 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222194758286673
2020-02-22 19:47:58,331 [salt.minion      :1432][INFO    ][8222] Starting a new job with PID 8222
2020-02-22 19:47:58,364 [salt.minion      :1711][INFO    ][8222] Returning information for job: 20200222194758286673
2020-02-22 19:48:18,921 [salt.loaded.ext.module.maas:1024][INFO    ][7565] Waiting status:Deployed for machines:['cmp001']
sleep for:30s Timeout:2250s (1645.78549385s left)
2020-02-22 19:48:28,420 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222194828406227
2020-02-22 19:48:28,446 [salt.minion      :1432][INFO    ][8255] Starting a new job with PID 8255
2020-02-22 19:48:28,471 [salt.minion      :1711][INFO    ][8255] Returning information for job: 20200222194828406227
2020-02-22 19:48:52,410 [salt.loaded.ext.module.maas:1024][INFO    ][7565] Waiting status:Deployed for machines:['cmp001']
sleep for:30s Timeout:2250s (1612.29665089s left)
2020-02-22 19:48:58,575 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222194858561235
2020-02-22 19:48:58,594 [salt.minion      :1432][INFO    ][8303] Starting a new job with PID 8303
2020-02-22 19:48:58,618 [salt.minion      :1711][INFO    ][8303] Returning information for job: 20200222194858561235
2020-02-22 19:49:26,154 [salt.state       :300 ][INFO    ][7565] {'ret': True}
2020-02-22 19:49:26,155 [salt.state       :1951][INFO    ][7565] Completed state [maas.wait_for_machine_status] at time 19:49:26.154891 duration_in_ms=671469.271
2020-02-22 19:49:26,166 [salt.minion      :1711][INFO    ][7565] Returning information for job: 20200222193811234107
2020-02-22 19:50:02,908 [salt.utils.schedule:1377][INFO    ][3659] Running scheduled job: __mine_interval
2020-02-22 20:50:02,907 [salt.utils.schedule:1377][INFO    ][3659] Running scheduled job: __mine_interval
2020-02-22 21:27:57,906 [salt.minion      :1308][INFO    ][3659] User sudo_ubuntu Executing command cp.push_dir with jid 20200222212757896910
2020-02-22 21:27:57,931 [salt.minion      :1432][INFO    ][12985] Starting a new job with PID 12985
