2020-02-06 09:45:23,901 [salt.utils.decorators:613 ][WARNING ][1488] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-06 09:45:24,795 [salt.utils.decorators:613 ][WARNING ][1488] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-06 09:45:33,706 [salt.loaded.int.states.file:2298][WARNING ][1667] 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-06 09:45:40,892 [salt.state       :2022][WARNING ][1882] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-02-06 09:45:41,603 [salt.utils.decorators:613 ][WARNING ][1882] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-06 09:45:53,782 [salt.utils.decorators:613 ][WARNING ][1882] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-06 09:46:07,800 [salt.utils.decorators:613 ][WARNING ][1882] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-06 09:46:08,502 [salt.utils.decorators:613 ][WARNING ][1882] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-06 09:46:10,592 [salt.loaded.ext.module.maasng:1008][WARNING ][1882] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2020-02-06 09:46:10,593 [salt.loaded.ext.module.maasng:1011][WARNING ][1882] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2020-02-06 09:46:11,239 [salt.loaded.ext.module.maasng:1235][WARNING ][1882] Ignoring parameter vlan:0
2020-02-06 09:46:21,781 [salt.utils.decorators:613 ][WARNING ][3010] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-06 09:46:21,824 [salt.loaded.ext.module.maas:412 ][WARNING ][3010] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-06 09:46:22,672 [salt.loaded.ext.module.maas:412 ][WARNING ][3010] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-06 09:46:23,576 [salt.loaded.ext.module.maas:412 ][WARNING ][3010] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-06 09:46:24,388 [salt.loaded.ext.module.maas:412 ][WARNING ][3010] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-06 09:46:25,137 [salt.loaded.ext.module.maas:412 ][WARNING ][3010] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-06 09:46:30,304 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3305] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2020-02-06 09:46:30,346 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3305] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2020-02-06 09:46:30,396 [salt.utils.parsers:1051][WARNING ][154] Minion received a SIGTERM. Exiting.
2020-02-06 09:46:31,471 [salt.cli.daemons :293 ][INFO    ][3358] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2020-02-06 09:46:31,664 [salt.cli.daemons :82  ][INFO    ][3358] Starting up the Salt Minion
2020-02-06 09:46:31,664 [salt.utils.event :1017][INFO    ][3358] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2020-02-06 09:46:32,956 [salt.minion      :976 ][INFO    ][3358] Creating minion process manager
2020-02-06 09:46:34,908 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3358] Executing command ['date', '+%z'] in directory '/root'
2020-02-06 09:46:34,934 [salt.utils.schedule:568 ][INFO    ][3358] Updating job settings for scheduled job: __mine_interval
2020-02-06 09:46:34,936 [salt.minion      :1108][INFO    ][3358] Added mine.update to scheduler
2020-02-06 09:46:34,943 [salt.minion      :1975][INFO    ][3358] Minion is starting as user 'root'
2020-02-06 09:46:34,967 [salt.minion      :2336][INFO    ][3358] Minion is ready to receive requests!
2020-02-06 09:46:57,410 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command state.apply with jid 20200206094657395425
2020-02-06 09:46:57,438 [salt.minion      :1432][INFO    ][3467] Starting a new job with PID 3467
2020-02-06 09:47:03,574 [salt.state       :915 ][INFO    ][3467] Loading fresh modules for state activity
2020-02-06 09:47:03,640 [salt.fileclient  :1219][INFO    ][3467] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2020-02-06 09:47:03,692 [salt.state       :1780][INFO    ][3467] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:47:03.692155
2020-02-06 09:47:03,692 [salt.state       :1813][INFO    ][3467] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-06 09:47:03,694 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3467] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-06 09:47:05,750 [salt.state       :300 ][INFO    ][3467] {'pid': 3474, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-06 09:47:05,751 [salt.state       :1951][INFO    ][3467] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:47:05.751298 duration_in_ms=2059.142
2020-02-06 09:47:05,755 [salt.state       :1780][INFO    ][3467] Running state [maas.wait_for_machine_status] at time 09:47:05.755737
2020-02-06 09:47:05,756 [salt.state       :1813][INFO    ][3467] Executing state module.run for [maas.wait_for_machine_status]
2020-02-06 09:47:05,756 [salt.utils.decorators:613 ][WARNING ][3467] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-06 09:47:06,722 [salt.loaded.ext.module.maas:1024][INFO    ][3467] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.04695821s left)
2020-02-06 09:47:12,541 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206094712525915
2020-02-06 09:47:12,567 [salt.minion      :1432][INFO    ][3490] Starting a new job with PID 3490
2020-02-06 09:47:12,589 [salt.minion      :1711][INFO    ][3490] Returning information for job: 20200206094712525915
2020-02-06 09:47:37,651 [salt.loaded.ext.module.maas:1024][INFO    ][3467] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.11751413s left)
2020-02-06 09:47:42,617 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206094742608345
2020-02-06 09:47:42,639 [salt.minion      :1432][INFO    ][3508] Starting a new job with PID 3508
2020-02-06 09:47:42,659 [salt.minion      :1711][INFO    ][3508] Returning information for job: 20200206094742608345
2020-02-06 09:48:08,655 [salt.loaded.ext.module.maas:1024][INFO    ][3467] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.11429405s left)
2020-02-06 09:48:12,695 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206094812675911
2020-02-06 09:48:12,723 [salt.minion      :1432][INFO    ][3541] Starting a new job with PID 3541
2020-02-06 09:48:12,748 [salt.minion      :1711][INFO    ][3541] Returning information for job: 20200206094812675911
2020-02-06 09:48:39,615 [salt.loaded.ext.module.maas:1024][INFO    ][3467] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.15346313s left)
2020-02-06 09:48:42,781 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206094842767281
2020-02-06 09:48:42,806 [salt.minion      :1432][INFO    ][3559] Starting a new job with PID 3559
2020-02-06 09:48:42,830 [salt.minion      :1711][INFO    ][3559] Returning information for job: 20200206094842767281
2020-02-06 09:49:10,804 [salt.loaded.ext.module.maas:1024][INFO    ][3467] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.96440721s left)
2020-02-06 09:49:12,855 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206094912839706
2020-02-06 09:49:12,884 [salt.minion      :1432][INFO    ][3605] Starting a new job with PID 3605
2020-02-06 09:49:12,904 [salt.minion      :1711][INFO    ][3605] Returning information for job: 20200206094912839706
2020-02-06 09:49:42,118 [salt.loaded.ext.module.maas:1024][INFO    ][3467] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.65062404s left)
2020-02-06 09:49:42,938 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206094942927169
2020-02-06 09:49:42,959 [salt.minion      :1432][INFO    ][3656] Starting a new job with PID 3656
2020-02-06 09:49:42,976 [salt.minion      :1711][INFO    ][3656] Returning information for job: 20200206094942927169
2020-02-06 09:50:13,046 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206095013024725
2020-02-06 09:50:13,070 [salt.minion      :1432][INFO    ][3708] Starting a new job with PID 3708
2020-02-06 09:50:13,096 [salt.minion      :1711][INFO    ][3708] Returning information for job: 20200206095013024725
2020-02-06 09:50:13,683 [salt.loaded.ext.module.maas:1024][INFO    ][3467] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.08543921s left)
2020-02-06 09:50:43,154 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206095043140965
2020-02-06 09:50:43,174 [salt.minion      :1432][INFO    ][3759] Starting a new job with PID 3759
2020-02-06 09:50:43,200 [salt.minion      :1711][INFO    ][3759] Returning information for job: 20200206095043140965
2020-02-06 09:50:45,036 [salt.loaded.ext.module.maas:1024][INFO    ][3467] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1280.73268604s left)
2020-02-06 09:51:13,260 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206095113249960
2020-02-06 09:51:13,285 [salt.minion      :1432][INFO    ][3838] Starting a new job with PID 3838
2020-02-06 09:51:13,306 [salt.minion      :1711][INFO    ][3838] Returning information for job: 20200206095113249960
2020-02-06 09:51:16,975 [salt.loaded.ext.module.maas:1024][INFO    ][3467] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1248.79347301s left)
2020-02-06 09:51:43,361 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206095143353916
2020-02-06 09:51:43,380 [salt.minion      :1432][INFO    ][3890] Starting a new job with PID 3890
2020-02-06 09:51:43,402 [salt.minion      :1711][INFO    ][3890] Returning information for job: 20200206095143353916
2020-02-06 09:51:49,290 [salt.loaded.ext.module.maas:1024][INFO    ][3467] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1216.47874403s left)
2020-02-06 09:52:13,459 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206095213442828
2020-02-06 09:52:13,485 [salt.minion      :1432][INFO    ][3942] Starting a new job with PID 3942
2020-02-06 09:52:13,505 [salt.minion      :1711][INFO    ][3942] Returning information for job: 20200206095213442828
2020-02-06 09:52:21,682 [salt.loaded.ext.module.maas:1024][INFO    ][3467] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.0871892s left)
2020-02-06 09:52:43,568 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206095243554195
2020-02-06 09:52:43,593 [salt.minion      :1432][INFO    ][3974] Starting a new job with PID 3974
2020-02-06 09:52:43,614 [salt.minion      :1711][INFO    ][3974] Returning information for job: 20200206095243554195
2020-02-06 09:52:54,181 [salt.loaded.ext.module.maas:1024][INFO    ][3467] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1151.58822918s left)
2020-02-06 09:53:13,682 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206095313670929
2020-02-06 09:53:13,705 [salt.minion      :1432][INFO    ][4065] Starting a new job with PID 4065
2020-02-06 09:53:13,727 [salt.minion      :1711][INFO    ][4065] Returning information for job: 20200206095313670929
2020-02-06 09:53:27,463 [salt.loaded.ext.module.maas:1024][INFO    ][3467] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1118.30618215s left)
2020-02-06 09:53:43,812 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206095343799147
2020-02-06 09:53:43,840 [salt.minion      :1432][INFO    ][4091] Starting a new job with PID 4091
2020-02-06 09:53:43,859 [salt.minion      :1711][INFO    ][4091] Returning information for job: 20200206095343799147
2020-02-06 09:54:00,769 [salt.state       :300 ][INFO    ][3467] {'ret': True}
2020-02-06 09:54:00,769 [salt.state       :1951][INFO    ][3467] Completed state [maas.wait_for_machine_status] at time 09:54:00.769780 duration_in_ms=415014.044
2020-02-06 09:54:00,775 [salt.minion      :1711][INFO    ][3467] Returning information for job: 20200206094657395425
2020-02-06 09:54:01,667 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command state.apply with jid 20200206095401655441
2020-02-06 09:54:01,690 [salt.minion      :1432][INFO    ][4114] Starting a new job with PID 4114
2020-02-06 09:54:07,858 [salt.state       :915 ][INFO    ][4114] Loading fresh modules for state activity
2020-02-06 09:54:07,927 [salt.fileclient  :1219][INFO    ][4114] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2020-02-06 09:54:08,017 [salt.state       :1780][INFO    ][4114] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:54:08.017755
2020-02-06 09:54:08,018 [salt.state       :1813][INFO    ][4114] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-06 09:54:08,019 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4114] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-06 09:54:09,931 [salt.state       :300 ][INFO    ][4114] {'pid': 4127, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-06 09:54:09,932 [salt.state       :1951][INFO    ][4114] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:54:09.932389 duration_in_ms=1914.63
2020-02-06 09:54:09,936 [salt.state       :1780][INFO    ][4114] Running state [maas_machines_storage_cmp002_lvm] at time 09:54:09.936426
2020-02-06 09:54:09,937 [salt.state       :1813][INFO    ][4114] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2020-02-06 09:54:11,307 [salt.loaded.ext.module.maasng:610 ][INFO    ][4114] rfh3b6
2020-02-06 09:54:11,307 [salt.loaded.ext.module.maasng:626 ][INFO    ][4114] sda
2020-02-06 09:54:11,959 [salt.loaded.ext.module.maasng:361 ][INFO    ][4114] rfh3b6
2020-02-06 09:54:12,045 [salt.loaded.ext.module.maasng:367 ][INFO    ][4114] [{u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'a02aa23b-91da-4415-b172-abab377d4315', u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rfh3b6', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'f12e477e-52a9-45bc-a94f-b0383ea07541', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 800101236736}], u'filesystem': None, u'uuid': None, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'rfh3b6', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/3/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'bcb6aed1-d6a4-4733-acfb-63397e760ddb', u'fstype': u'ext4'}, u'uuid': u'561a10b8-5459-4fe9-b02a-2ffe136391be', u'id': 3, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rfh3b6', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 800097042432}]
2020-02-06 09:54:12,046 [salt.loaded.ext.module.maasng:632 ][INFO    ][4114] vgroot
2020-02-06 09:54:12,046 [salt.loaded.ext.module.maasng:635 ][INFO    ][4114] lvroot
2020-02-06 09:54:12,046 [salt.loaded.ext.module.maasng:639 ][INFO    ][4114] 107374182400
2020-02-06 09:54:12,636 [salt.loaded.ext.module.maasng:645 ][INFO    ][4114] {u'node_type_name': u'Machine', 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'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', 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'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/rfh3b6/', u'hardware_info': {u'mainboard_firmware_version': u'I31', u'mainboard_product': u'Unknown', u'system_vendor': u'HP', u'cpu_model': u'Intel(R) Xeon(R) CPU E5-2680 v2', u'mainboard_firmware_date': u'12/20/2013', u'mainboard_vendor': u'Unknown', u'system_serial': u'USE410T2Y0', u'system_version': u'Unknown', u'system_product': u'ProLiant BL460c Gen8 (735151-B21)'}, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'rfh3b6', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/11/', u'available_size': 0, u'uuid': u'fc97ea28-ff24-4c75-9694-a7d91cb4300f', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'id': 11, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rfh3b6', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'd1a7bc68-00c3-4c66-a405-e6dc9fad95d8', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'name': u'vgroot-lvroot'}], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'62b28f7d-74f5-4181-b33f-65a9a347ea9d', u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rfh3b6', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'35e73bb4-c5e5-4d70-b61f-52a2d76cecea', 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'uuid': None, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'rfh3b6', 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'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/11/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'fc97ea28-ff24-4c75-9694-a7d91cb4300f', u'id': 11, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rfh3b6', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'd1a7bc68-00c3-4c66-a405-e6dc9fad95d8', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/1/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'62b28f7d-74f5-4181-b33f-65a9a347ea9d', u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rfh3b6', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'35e73bb4-c5e5-4d70-b61f-52a2d76cecea', 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'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'rfh3b6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'rfh3b6', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'commissioning_status_name': u'Passed', 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'architecture': u'amd64/generic', u'storage_test_status': 2, u'locked': False, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8hbfh', 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'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'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'id': 4, u'system_id': u'rfh3b6', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/interfaces/4/'}, u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8hbfh', 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'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'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'id': 4, u'system_id': u'rfh3b6', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/interfaces/4/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': None, u'vendor': u'HP', u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'id': 11, u'system_id': u'rfh3b6', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/interfaces/11/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', 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'w8hbfh', 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'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'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'id': 12, u'system_id': u'rfh3b6', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/interfaces/12/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': None, u'vendor': u'HP', u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'id': 13, u'system_id': u'rfh3b6', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/interfaces/13/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'id': 14, u'system_id': u'rfh3b6', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/interfaces/14/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'id': 15, u'system_id': u'rfh3b6', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/interfaces/15/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/1/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'62b28f7d-74f5-4181-b33f-65a9a347ea9d', u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rfh3b6', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'35e73bb4-c5e5-4d70-b61f-52a2d76cecea', 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'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'rfh3b6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}}
2020-02-06 09:54:12,639 [salt.state       :300 ][INFO    ][4114] {'new': {'storage_layout': 'lvm'}}
2020-02-06 09:54:12,639 [salt.state       :1951][INFO    ][4114] Completed state [maas_machines_storage_cmp002_lvm] at time 09:54:12.639447 duration_in_ms=2703.019
2020-02-06 09:54:12,640 [salt.state       :1780][INFO    ][4114] Running state [maas_machines_storage_cmp001_lvm] at time 09:54:12.640046
2020-02-06 09:54:12,640 [salt.state       :1813][INFO    ][4114] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2020-02-06 09:54:13,967 [salt.loaded.ext.module.maasng:610 ][INFO    ][4114] ypb3yf
2020-02-06 09:54:13,968 [salt.loaded.ext.module.maasng:626 ][INFO    ][4114] sda
2020-02-06 09:54:14,635 [salt.loaded.ext.module.maasng:361 ][INFO    ][4114] ypb3yf
2020-02-06 09:54:14,735 [salt.loaded.ext.module.maasng:367 ][INFO    ][4114] [{u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'd4efbb2d-7d09-40f6-860a-9bfca3f49d42', u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ypb3yf', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd9cbc942-6d4d-434b-8f6e-094c7104637b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'size': 800101236736}], u'filesystem': None, u'uuid': None, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ypb3yf', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/4/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'31e2e59b-9c14-4dd4-bf48-1b3ba2586baf', u'fstype': u'ext4'}, u'uuid': u'dcb21375-07af-46d9-a9a4-9cd7d7040a36', u'id': 4, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ypb3yf', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 800097042432}]
2020-02-06 09:54:14,735 [salt.loaded.ext.module.maasng:632 ][INFO    ][4114] vgroot
2020-02-06 09:54:14,736 [salt.loaded.ext.module.maasng:635 ][INFO    ][4114] lvroot
2020-02-06 09:54:14,736 [salt.loaded.ext.module.maasng:639 ][INFO    ][4114] 107374182400
2020-02-06 09:54:15,410 [salt.loaded.ext.module.maasng:645 ][INFO    ][4114] {u'node_type_name': u'Machine', u'hwe_kernel': None, u'swap_size': None, u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 28, 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'w8hbfh', 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'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'ypb3yf', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/interfaces/5/'}, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-18.04', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'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/ypb3yf/', u'hardware_info': {u'mainboard_firmware_version': u'I31', u'mainboard_product': u'Unknown', u'system_vendor': u'HP', u'cpu_model': u'Intel(R) Xeon(R) CPU E5-2680 v2', u'mainboard_firmware_date': u'06/01/2015', u'mainboard_vendor': u'Unknown', u'system_serial': u'USE410T2XY', u'system_version': u'Unknown', u'system_product': u'ProLiant BL460c Gen8 (735151-B21)'}, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'ypb3yf', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/2/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'0423be5f-dfbc-4cc7-902b-7d2b268efc6c', u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ypb3yf', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'f62b5170-7a7d-4a7e-818e-beb3d6b258b9', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ypb3yf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/12/', u'available_size': 0, u'uuid': u'6524da44-8d55-4139-9a5f-f95c31f4f2f5', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ypb3yf', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'0726d4a1-d0ab-4359-9509-caa891d87862', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'size': 107374182400}], 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'ypb3yf', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/2/', u'system_id': u'ypb3yf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'size': 800109715456, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'0423be5f-dfbc-4cc7-902b-7d2b268efc6c', u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ypb3yf', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'f62b5170-7a7d-4a7e-818e-beb3d6b258b9', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}]}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'pool': {u'id': 0, u'resource_uri': u'/MAAS/api/2.0/resourcepool/0/', u'name': u'default', u'description': u'Default pool'}, u'bcaches': [], u'locked': False, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/2/', u'system_id': u'ypb3yf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'size': 800109715456, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'0423be5f-dfbc-4cc7-902b-7d2b268efc6c', u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ypb3yf', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'f62b5170-7a7d-4a7e-818e-beb3d6b258b9', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'virtualblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': u'6524da44-8d55-4139-9a5f-f95c31f4f2f5', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/12/', u'system_id': u'ypb3yf', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'0726d4a1-d0ab-4359-9509-caa891d87862', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'size': 107374182400, u'type': u'virtual', u'id': 12, u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', 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'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 28, 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'w8hbfh', 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'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'ypb3yf', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/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'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'ypb3yf', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/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'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 29, 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'w8hbfh', 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'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.44'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'ypb3yf', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/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'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'ypb3yf', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/interfaces/18/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 30, 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'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'ypb3yf', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/interfaces/19/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f1', u'links': [{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': 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'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'ypb3yf', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2020-02-06 09:54:15,415 [salt.state       :300 ][INFO    ][4114] {'new': {'storage_layout': 'lvm'}}
2020-02-06 09:54:15,415 [salt.state       :1951][INFO    ][4114] Completed state [maas_machines_storage_cmp001_lvm] at time 09:54:15.415205 duration_in_ms=2775.159
2020-02-06 09:54:15,418 [salt.minion      :1711][INFO    ][4114] Returning information for job: 20200206095401655441
2020-02-06 09:54:16,207 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command state.apply with jid 20200206095416192300
2020-02-06 09:54:16,228 [salt.minion      :1432][INFO    ][4145] Starting a new job with PID 4145
2020-02-06 09:54:17,269 [salt.state       :915 ][INFO    ][4145] Loading fresh modules for state activity
2020-02-06 09:54:17,329 [salt.fileclient  :1219][INFO    ][4145] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2020-02-06 09:54:17,376 [salt.state       :1780][INFO    ][4145] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:54:17.376816
2020-02-06 09:54:17,377 [salt.state       :1813][INFO    ][4145] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-06 09:54:17,379 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4145] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-06 09:54:19,436 [salt.state       :300 ][INFO    ][4145] {'pid': 4152, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-06 09:54:19,437 [salt.state       :1951][INFO    ][4145] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:54:19.437756 duration_in_ms=2060.94
2020-02-06 09:54:19,441 [salt.state       :1780][INFO    ][4145] Running state [maas.deploy_machines] at time 09:54:19.440940
2020-02-06 09:54:19,441 [salt.state       :1813][INFO    ][4145] Executing state module.run for [maas.deploy_machines]
2020-02-06 09:54:19,442 [salt.utils.decorators:613 ][WARNING ][4145] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-06 09:54:20,235 [salt.loaded.ext.module.maas:684 ][INFO    ][4145] deploymachines hwe_kernel=ga-18.04 system_id=rfh3b6 distro_series=bionic
2020-02-06 09:54:22,166 [salt.loaded.ext.module.maas:684 ][INFO    ][4145] deploymachines hwe_kernel=ga-18.04 system_id=ypb3yf distro_series=bionic
2020-02-06 09:54:24,203 [salt.loaded.ext.module.maas:684 ][INFO    ][4145] deploymachines hwe_kernel=ga-18.04 system_id=x3bd8t distro_series=bionic
2020-02-06 09:54:26,175 [salt.loaded.ext.module.maas:684 ][INFO    ][4145] deploymachines hwe_kernel=ga-18.04 system_id=8sen7s distro_series=bionic
2020-02-06 09:54:28,124 [salt.loaded.ext.module.maas:684 ][INFO    ][4145] deploymachines hwe_kernel=ga-18.04 system_id=ryerhd distro_series=bionic
2020-02-06 09:54:30,044 [salt.state       :300 ][INFO    ][4145] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2020-02-06 09:54:30,045 [salt.state       :1951][INFO    ][4145] Completed state [maas.deploy_machines] at time 09:54:30.045023 duration_in_ms=10604.082
2020-02-06 09:54:30,048 [salt.minion      :1711][INFO    ][4145] Returning information for job: 20200206095416192300
2020-02-06 09:54:30,835 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command state.apply with jid 20200206095430822216
2020-02-06 09:54:30,856 [salt.minion      :1432][INFO    ][4189] Starting a new job with PID 4189
2020-02-06 09:54:37,040 [salt.state       :915 ][INFO    ][4189] Loading fresh modules for state activity
2020-02-06 09:54:37,096 [salt.fileclient  :1219][INFO    ][4189] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2020-02-06 09:54:37,146 [salt.state       :1780][INFO    ][4189] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:54:37.146358
2020-02-06 09:54:37,146 [salt.state       :1813][INFO    ][4189] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-06 09:54:37,148 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4189] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-06 09:54:39,018 [salt.state       :300 ][INFO    ][4189] {'pid': 4207, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-06 09:54:39,019 [salt.state       :1951][INFO    ][4189] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:54:39.019410 duration_in_ms=1873.051
2020-02-06 09:54:39,021 [salt.state       :1780][INFO    ][4189] Running state [maas.wait_for_machine_status] at time 09:54:39.021798
2020-02-06 09:54:39,022 [salt.state       :1813][INFO    ][4189] Executing state module.run for [maas.wait_for_machine_status]
2020-02-06 09:54:39,022 [salt.utils.decorators:613 ][WARNING ][4189] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-06 09:54:42,285 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.74816513s left)
2020-02-06 09:54:45,896 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206095445881166
2020-02-06 09:54:45,919 [salt.minion      :1432][INFO    ][4230] Starting a new job with PID 4230
2020-02-06 09:54:45,940 [salt.minion      :1711][INFO    ][4230] Returning information for job: 20200206095445881166
2020-02-06 09:55:15,670 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.36231208s left)
2020-02-06 09:55:15,969 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206095515952640
2020-02-06 09:55:15,994 [salt.minion      :1432][INFO    ][4255] Starting a new job with PID 4255
2020-02-06 09:55:16,016 [salt.minion      :1711][INFO    ][4255] Returning information for job: 20200206095515952640
2020-02-06 09:55:46,037 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206095546028193
2020-02-06 09:55:46,059 [salt.minion      :1432][INFO    ][4290] Starting a new job with PID 4290
2020-02-06 09:55:46,080 [salt.minion      :1711][INFO    ][4290] Returning information for job: 20200206095546028193
2020-02-06 09:55:49,104 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.9288702s left)
2020-02-06 09:56:16,119 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206095616104293
2020-02-06 09:56:16,144 [salt.minion      :1432][INFO    ][4324] Starting a new job with PID 4324
2020-02-06 09:56:16,162 [salt.minion      :1711][INFO    ][4324] Returning information for job: 20200206095616104293
2020-02-06 09:56:22,644 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.38889813s left)
2020-02-06 09:56:46,189 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206095646178602
2020-02-06 09:56:46,215 [salt.minion      :1432][INFO    ][4355] Starting a new job with PID 4355
2020-02-06 09:56:46,238 [salt.minion      :1711][INFO    ][4355] Returning information for job: 20200206095646178602
2020-02-06 09:56:56,130 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.90239501s left)
2020-02-06 09:57:16,271 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206095716262075
2020-02-06 09:57:16,295 [salt.minion      :1432][INFO    ][4391] Starting a new job with PID 4391
2020-02-06 09:57:16,311 [salt.minion      :1711][INFO    ][4391] Returning information for job: 20200206095716262075
2020-02-06 09:57:29,536 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.49685812s left)
2020-02-06 09:57:46,371 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206095746359053
2020-02-06 09:57:46,397 [salt.minion      :1432][INFO    ][4448] Starting a new job with PID 4448
2020-02-06 09:57:46,420 [salt.minion      :1711][INFO    ][4448] Returning information for job: 20200206095746359053
2020-02-06 09:58:03,631 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2045.40176702s left)
2020-02-06 09:58:16,467 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206095816453393
2020-02-06 09:58:16,489 [salt.minion      :1432][INFO    ][4501] Starting a new job with PID 4501
2020-02-06 09:58:16,509 [salt.minion      :1711][INFO    ][4501] Returning information for job: 20200206095816453393
2020-02-06 09:58:34,518 [salt.loaded.ext.module.maas:993 ][INFO    ][4189] Machine rfh3b6 mark broken
2020-02-06 09:58:46,539 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206095846527144
2020-02-06 09:58:46,560 [salt.minion      :1432][INFO    ][4560] Starting a new job with PID 4560
2020-02-06 09:58:46,579 [salt.minion      :1711][INFO    ][4560] Returning information for job: 20200206095846527144
2020-02-06 09:59:05,166 [salt.loaded.ext.module.maas:997 ][INFO    ][4189] Machine rfh3b6 mark fixed
2020-02-06 09:59:06,381 [salt.loaded.ext.module.maas:684 ][INFO    ][4189] deploymachines hwe_kernel=ga-18.04 system_id=rfh3b6 distro_series=bionic
2020-02-06 09:59:08,909 [salt.loaded.ext.module.maas:993 ][INFO    ][4189] Machine ypb3yf mark broken
2020-02-06 09:59:16,625 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206095916617239
2020-02-06 09:59:16,648 [salt.minion      :1432][INFO    ][4618] Starting a new job with PID 4618
2020-02-06 09:59:16,668 [salt.minion      :1711][INFO    ][4618] Returning information for job: 20200206095916617239
2020-02-06 09:59:39,466 [salt.loaded.ext.module.maas:997 ][INFO    ][4189] Machine ypb3yf mark fixed
2020-02-06 09:59:40,703 [salt.loaded.ext.module.maas:684 ][INFO    ][4189] deploymachines hwe_kernel=ga-18.04 system_id=ypb3yf distro_series=bionic
2020-02-06 09:59:44,943 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1944.08953714s left)
2020-02-06 09:59:46,727 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206095946716602
2020-02-06 09:59:46,754 [salt.minion      :1432][INFO    ][4710] Starting a new job with PID 4710
2020-02-06 09:59:46,775 [salt.minion      :1711][INFO    ][4710] Returning information for job: 20200206095946716602
2020-02-06 10:00:16,860 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206100016843620
2020-02-06 10:00:16,883 [salt.minion      :1432][INFO    ][4741] Starting a new job with PID 4741
2020-02-06 10:00:16,904 [salt.minion      :1711][INFO    ][4741] Returning information for job: 20200206100016843620
2020-02-06 10:00:18,355 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1910.67759705s left)
2020-02-06 10:00:46,979 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206100046967092
2020-02-06 10:00:47,012 [salt.minion      :1432][INFO    ][4768] Starting a new job with PID 4768
2020-02-06 10:00:47,034 [salt.minion      :1711][INFO    ][4768] Returning information for job: 20200206100046967092
2020-02-06 10:00:51,832 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1877.20086002s left)
2020-02-06 10:01:17,107 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206100117092569
2020-02-06 10:01:17,131 [salt.minion      :1432][INFO    ][4797] Starting a new job with PID 4797
2020-02-06 10:01:17,151 [salt.minion      :1711][INFO    ][4797] Returning information for job: 20200206100117092569
2020-02-06 10:01:25,190 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1843.84279203s left)
2020-02-06 10:01:47,230 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206100147215206
2020-02-06 10:01:47,252 [salt.minion      :1432][INFO    ][4833] Starting a new job with PID 4833
2020-02-06 10:01:47,271 [salt.minion      :1711][INFO    ][4833] Returning information for job: 20200206100147215206
2020-02-06 10:01:58,724 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1810.30904722s left)
2020-02-06 10:02:17,387 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206100217374683
2020-02-06 10:02:17,412 [salt.minion      :1432][INFO    ][4883] Starting a new job with PID 4883
2020-02-06 10:02:17,434 [salt.minion      :1711][INFO    ][4883] Returning information for job: 20200206100217374683
2020-02-06 10:02:32,183 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1776.8498702s left)
2020-02-06 10:02:47,507 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206100247494239
2020-02-06 10:02:47,531 [salt.minion      :1432][INFO    ][4925] Starting a new job with PID 4925
2020-02-06 10:02:47,554 [salt.minion      :1711][INFO    ][4925] Returning information for job: 20200206100247494239
2020-02-06 10:03:02,872 [salt.loaded.ext.module.maas:993 ][INFO    ][4189] Machine rfh3b6 mark broken
2020-02-06 10:03:17,652 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206100317640946
2020-02-06 10:03:17,673 [salt.minion      :1432][INFO    ][4973] Starting a new job with PID 4973
2020-02-06 10:03:17,710 [salt.minion      :1711][INFO    ][4973] Returning information for job: 20200206100317640946
2020-02-06 10:03:33,526 [salt.loaded.ext.module.maas:997 ][INFO    ][4189] Machine rfh3b6 mark fixed
2020-02-06 10:03:34,057 [salt.loaded.ext.module.maas:1001][INFO    ][4189] Machine rfh3b6 fio test
2020-02-06 10:03:38,232 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1710.80073905s left)
2020-02-06 10:03:47,822 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206100347810297
2020-02-06 10:03:47,848 [salt.minion      :1432][INFO    ][5022] Starting a new job with PID 5022
2020-02-06 10:03:47,869 [salt.minion      :1711][INFO    ][5022] Returning information for job: 20200206100347810297
2020-02-06 10:04:09,509 [salt.loaded.ext.module.maas:993 ][INFO    ][4189] Machine ypb3yf mark broken
2020-02-06 10:04:17,998 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206100417983294
2020-02-06 10:04:18,027 [salt.minion      :1432][INFO    ][5055] Starting a new job with PID 5055
2020-02-06 10:04:18,049 [salt.minion      :1711][INFO    ][5055] Returning information for job: 20200206100417983294
2020-02-06 10:04:40,185 [salt.loaded.ext.module.maas:997 ][INFO    ][4189] Machine ypb3yf mark fixed
2020-02-06 10:04:40,752 [salt.loaded.ext.module.maas:1001][INFO    ][4189] Machine ypb3yf fio test
2020-02-06 10:04:43,886 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1645.14673114s left)
2020-02-06 10:04:48,172 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206100448161209
2020-02-06 10:04:48,193 [salt.minion      :1432][INFO    ][5093] Starting a new job with PID 5093
2020-02-06 10:04:48,215 [salt.minion      :1711][INFO    ][5093] Returning information for job: 20200206100448161209
2020-02-06 10:05:16,958 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1612.0747261s left)
2020-02-06 10:05:18,348 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206100518336976
2020-02-06 10:05:18,376 [salt.minion      :1432][INFO    ][5119] Starting a new job with PID 5119
2020-02-06 10:05:18,399 [salt.minion      :1711][INFO    ][5119] Returning information for job: 20200206100518336976
2020-02-06 10:05:48,518 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206100548510799
2020-02-06 10:05:48,532 [salt.minion      :1432][INFO    ][5161] Starting a new job with PID 5161
2020-02-06 10:05:48,553 [salt.minion      :1711][INFO    ][5161] Returning information for job: 20200206100548510799
2020-02-06 10:05:49,632 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1579.40160322s left)
2020-02-06 10:06:18,679 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206100618664977
2020-02-06 10:06:18,696 [salt.minion      :1432][INFO    ][5197] Starting a new job with PID 5197
2020-02-06 10:06:18,718 [salt.minion      :1711][INFO    ][5197] Returning information for job: 20200206100618664977
2020-02-06 10:06:22,528 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1546.50516701s left)
2020-02-06 10:06:48,836 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206100648827918
2020-02-06 10:06:48,863 [salt.minion      :1432][INFO    ][5242] Starting a new job with PID 5242
2020-02-06 10:06:48,886 [salt.minion      :1711][INFO    ][5242] Returning information for job: 20200206100648827918
2020-02-06 10:06:55,546 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1513.48717117s left)
2020-02-06 10:07:19,008 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206100718996259
2020-02-06 10:07:19,036 [salt.minion      :1432][INFO    ][5264] Starting a new job with PID 5264
2020-02-06 10:07:19,058 [salt.minion      :1711][INFO    ][5264] Returning information for job: 20200206100718996259
2020-02-06 10:07:28,456 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1480.57690501s left)
2020-02-06 10:07:49,152 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206100749130917
2020-02-06 10:07:49,179 [salt.minion      :1432][INFO    ][5322] Starting a new job with PID 5322
2020-02-06 10:07:49,201 [salt.minion      :1711][INFO    ][5322] Returning information for job: 20200206100749130917
2020-02-06 10:08:01,519 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1447.51392007s left)
2020-02-06 10:08:19,211 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206100819200546
2020-02-06 10:08:19,240 [salt.minion      :1432][INFO    ][5373] Starting a new job with PID 5373
2020-02-06 10:08:19,262 [salt.minion      :1711][INFO    ][5373] Returning information for job: 20200206100819200546
2020-02-06 10:08:35,102 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm03']
sleep for:30s Timeout:2250s (1413.93025208s left)
2020-02-06 10:08:49,319 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206100849308399
2020-02-06 10:08:49,347 [salt.minion      :1432][INFO    ][5442] Starting a new job with PID 5442
2020-02-06 10:08:49,365 [salt.minion      :1711][INFO    ][5442] Returning information for job: 20200206100849308399
2020-02-06 10:09:08,249 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1380.78423119s left)
2020-02-06 10:09:19,343 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206100919333674
2020-02-06 10:09:19,372 [salt.minion      :1432][INFO    ][5468] Starting a new job with PID 5468
2020-02-06 10:09:19,393 [salt.minion      :1711][INFO    ][5468] Returning information for job: 20200206100919333674
2020-02-06 10:09:41,378 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1347.65491319s left)
2020-02-06 10:09:49,403 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206100949389844
2020-02-06 10:09:49,430 [salt.minion      :1432][INFO    ][5526] Starting a new job with PID 5526
2020-02-06 10:09:49,453 [salt.minion      :1711][INFO    ][5526] Returning information for job: 20200206100949389844
2020-02-06 10:10:14,994 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1314.03852606s left)
2020-02-06 10:10:19,452 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206101019436716
2020-02-06 10:10:19,479 [salt.minion      :1432][INFO    ][5547] Starting a new job with PID 5547
2020-02-06 10:10:19,503 [salt.minion      :1711][INFO    ][5547] Returning information for job: 20200206101019436716
2020-02-06 10:10:48,536 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1280.4972012s left)
2020-02-06 10:10:49,511 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206101049497615
2020-02-06 10:10:49,534 [salt.minion      :1432][INFO    ][5581] Starting a new job with PID 5581
2020-02-06 10:10:49,555 [salt.minion      :1711][INFO    ][5581] Returning information for job: 20200206101049497615
2020-02-06 10:11:19,549 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206101119538138
2020-02-06 10:11:19,571 [salt.minion      :1432][INFO    ][5604] Starting a new job with PID 5604
2020-02-06 10:11:19,596 [salt.minion      :1711][INFO    ][5604] Returning information for job: 20200206101119538138
2020-02-06 10:11:21,877 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1247.15529919s left)
2020-02-06 10:11:49,634 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206101149621060
2020-02-06 10:11:49,663 [salt.minion      :1432][INFO    ][5636] Starting a new job with PID 5636
2020-02-06 10:11:49,687 [salt.minion      :1711][INFO    ][5636] Returning information for job: 20200206101149621060
2020-02-06 10:11:55,292 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1213.74037123s left)
2020-02-06 10:12:19,691 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206101219678341
2020-02-06 10:12:19,718 [salt.minion      :1432][INFO    ][5656] Starting a new job with PID 5656
2020-02-06 10:12:19,738 [salt.minion      :1711][INFO    ][5656] Returning information for job: 20200206101219678341
2020-02-06 10:12:28,592 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1180.44111514s left)
2020-02-06 10:12:49,788 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206101249774915
2020-02-06 10:12:49,814 [salt.minion      :1432][INFO    ][5695] Starting a new job with PID 5695
2020-02-06 10:12:49,835 [salt.minion      :1711][INFO    ][5695] Returning information for job: 20200206101249774915
2020-02-06 10:13:02,140 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1146.89309907s left)
2020-02-06 10:13:19,887 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206101319880156
2020-02-06 10:13:19,901 [salt.minion      :1432][INFO    ][5718] Starting a new job with PID 5718
2020-02-06 10:13:19,920 [salt.minion      :1711][INFO    ][5718] Returning information for job: 20200206101319880156
2020-02-06 10:13:35,637 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1113.39531016s left)
2020-02-06 10:13:49,964 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206101349951881
2020-02-06 10:13:49,993 [salt.minion      :1432][INFO    ][5753] Starting a new job with PID 5753
2020-02-06 10:13:50,014 [salt.minion      :1711][INFO    ][5753] Returning information for job: 20200206101349951881
2020-02-06 10:14:08,992 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1080.04053307s left)
2020-02-06 10:14:20,098 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206101420081190
2020-02-06 10:14:20,125 [salt.minion      :1432][INFO    ][5775] Starting a new job with PID 5775
2020-02-06 10:14:20,146 [salt.minion      :1711][INFO    ][5775] Returning information for job: 20200206101420081190
2020-02-06 10:14:42,310 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1046.72249603s left)
2020-02-06 10:14:50,204 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206101450188328
2020-02-06 10:14:50,230 [salt.minion      :1432][INFO    ][5807] Starting a new job with PID 5807
2020-02-06 10:14:50,251 [salt.minion      :1711][INFO    ][5807] Returning information for job: 20200206101450188328
2020-02-06 10:15:15,682 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1013.35046816s left)
2020-02-06 10:15:20,375 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206101520359765
2020-02-06 10:15:20,401 [salt.minion      :1432][INFO    ][5827] Starting a new job with PID 5827
2020-02-06 10:15:20,427 [salt.minion      :1711][INFO    ][5827] Returning information for job: 20200206101520359765
2020-02-06 10:15:49,188 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (979.844616175s left)
2020-02-06 10:15:50,568 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206101550556630
2020-02-06 10:15:50,596 [salt.minion      :1432][INFO    ][5872] Starting a new job with PID 5872
2020-02-06 10:15:50,616 [salt.minion      :1711][INFO    ][5872] Returning information for job: 20200206101550556630
2020-02-06 10:16:20,771 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206101620758548
2020-02-06 10:16:20,794 [salt.minion      :1432][INFO    ][5898] Starting a new job with PID 5898
2020-02-06 10:16:20,817 [salt.minion      :1711][INFO    ][5898] Returning information for job: 20200206101620758548
2020-02-06 10:16:22,537 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (946.49529314s left)
2020-02-06 10:16:50,928 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206101650920146
2020-02-06 10:16:50,955 [salt.minion      :1432][INFO    ][5936] Starting a new job with PID 5936
2020-02-06 10:16:50,975 [salt.minion      :1711][INFO    ][5936] Returning information for job: 20200206101650920146
2020-02-06 10:16:55,722 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (913.310556173s left)
2020-02-06 10:17:21,144 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206101721133492
2020-02-06 10:17:21,171 [salt.minion      :1432][INFO    ][5960] Starting a new job with PID 5960
2020-02-06 10:17:21,192 [salt.minion      :1711][INFO    ][5960] Returning information for job: 20200206101721133492
2020-02-06 10:17:29,006 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (880.02635622s left)
2020-02-06 10:17:51,334 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206101751320934
2020-02-06 10:17:51,361 [salt.minion      :1432][INFO    ][5997] Starting a new job with PID 5997
2020-02-06 10:17:51,380 [salt.minion      :1711][INFO    ][5997] Returning information for job: 20200206101751320934
2020-02-06 10:18:02,383 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (846.649313211s left)
2020-02-06 10:18:21,368 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206101821356163
2020-02-06 10:18:21,395 [salt.minion      :1432][INFO    ][6022] Starting a new job with PID 6022
2020-02-06 10:18:21,414 [salt.minion      :1711][INFO    ][6022] Returning information for job: 20200206101821356163
2020-02-06 10:18:35,521 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (813.512200117s left)
2020-02-06 10:18:51,592 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206101851578646
2020-02-06 10:18:51,620 [salt.minion      :1432][INFO    ][6057] Starting a new job with PID 6057
2020-02-06 10:18:51,638 [salt.minion      :1711][INFO    ][6057] Returning information for job: 20200206101851578646
2020-02-06 10:19:08,894 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (780.138730049s left)
2020-02-06 10:19:21,631 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206101921616103
2020-02-06 10:19:21,661 [salt.minion      :1432][INFO    ][6084] Starting a new job with PID 6084
2020-02-06 10:19:21,681 [salt.minion      :1711][INFO    ][6084] Returning information for job: 20200206101921616103
2020-02-06 10:19:41,937 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (747.095444202s left)
2020-02-06 10:19:51,679 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206101951663868
2020-02-06 10:19:51,699 [salt.minion      :1432][INFO    ][6118] Starting a new job with PID 6118
2020-02-06 10:19:51,717 [salt.minion      :1711][INFO    ][6118] Returning information for job: 20200206101951663868
2020-02-06 10:20:15,252 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (713.780717134s left)
2020-02-06 10:20:21,772 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206102021756119
2020-02-06 10:20:21,804 [salt.minion      :1432][INFO    ][6139] Starting a new job with PID 6139
2020-02-06 10:20:21,824 [salt.minion      :1711][INFO    ][6139] Returning information for job: 20200206102021756119
2020-02-06 10:20:48,247 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (680.785469055s left)
2020-02-06 10:20:51,911 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206102051901135
2020-02-06 10:20:51,932 [salt.minion      :1432][INFO    ][6172] Starting a new job with PID 6172
2020-02-06 10:20:51,952 [salt.minion      :1711][INFO    ][6172] Returning information for job: 20200206102051901135
2020-02-06 10:21:21,424 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (647.609459162s left)
2020-02-06 10:21:22,057 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206102122039281
2020-02-06 10:21:22,089 [salt.minion      :1432][INFO    ][6192] Starting a new job with PID 6192
2020-02-06 10:21:22,110 [salt.minion      :1711][INFO    ][6192] Returning information for job: 20200206102122039281
2020-02-06 10:21:52,199 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206102152188942
2020-02-06 10:21:52,222 [salt.minion      :1432][INFO    ][6227] Starting a new job with PID 6227
2020-02-06 10:21:52,243 [salt.minion      :1711][INFO    ][6227] Returning information for job: 20200206102152188942
2020-02-06 10:21:54,516 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (614.517193079s left)
2020-02-06 10:22:22,341 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206102222322942
2020-02-06 10:22:22,371 [salt.minion      :1432][INFO    ][6245] Starting a new job with PID 6245
2020-02-06 10:22:22,398 [salt.minion      :1711][INFO    ][6245] Returning information for job: 20200206102222322942
2020-02-06 10:22:27,700 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (581.333199024s left)
2020-02-06 10:22:52,558 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206102252546077
2020-02-06 10:22:52,581 [salt.minion      :1432][INFO    ][6278] Starting a new job with PID 6278
2020-02-06 10:22:52,612 [salt.minion      :1711][INFO    ][6278] Returning information for job: 20200206102252546077
2020-02-06 10:23:00,782 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (548.250326157s left)
2020-02-06 10:23:22,738 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206102322726784
2020-02-06 10:23:22,764 [salt.minion      :1432][INFO    ][6298] Starting a new job with PID 6298
2020-02-06 10:23:22,785 [salt.minion      :1711][INFO    ][6298] Returning information for job: 20200206102322726784
2020-02-06 10:23:34,108 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (514.924962044s left)
2020-02-06 10:23:52,967 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206102352955459
2020-02-06 10:23:52,993 [salt.minion      :1432][INFO    ][6334] Starting a new job with PID 6334
2020-02-06 10:23:53,014 [salt.minion      :1711][INFO    ][6334] Returning information for job: 20200206102352955459
2020-02-06 10:24:07,316 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (481.716628075s left)
2020-02-06 10:24:23,194 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206102423177875
2020-02-06 10:24:23,220 [salt.minion      :1432][INFO    ][6355] Starting a new job with PID 6355
2020-02-06 10:24:23,240 [salt.minion      :1711][INFO    ][6355] Returning information for job: 20200206102423177875
2020-02-06 10:24:40,708 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (448.324872017s left)
2020-02-06 10:24:53,230 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206102453214090
2020-02-06 10:24:53,256 [salt.minion      :1432][INFO    ][6390] Starting a new job with PID 6390
2020-02-06 10:24:53,276 [salt.minion      :1711][INFO    ][6390] Returning information for job: 20200206102453214090
2020-02-06 10:25:13,981 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (415.051409006s left)
2020-02-06 10:25:23,259 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206102523244814
2020-02-06 10:25:23,287 [salt.minion      :1432][INFO    ][6414] Starting a new job with PID 6414
2020-02-06 10:25:23,307 [salt.minion      :1711][INFO    ][6414] Returning information for job: 20200206102523244814
2020-02-06 10:25:47,087 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (381.945852041s left)
2020-02-06 10:25:53,366 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206102553347849
2020-02-06 10:25:53,395 [salt.minion      :1432][INFO    ][6461] Starting a new job with PID 6461
2020-02-06 10:25:53,415 [salt.minion      :1711][INFO    ][6461] Returning information for job: 20200206102553347849
2020-02-06 10:26:20,194 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (348.839168072s left)
2020-02-06 10:26:23,410 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206102623393142
2020-02-06 10:26:23,438 [salt.minion      :1432][INFO    ][6486] Starting a new job with PID 6486
2020-02-06 10:26:23,456 [salt.minion      :1711][INFO    ][6486] Returning information for job: 20200206102623393142
2020-02-06 10:26:53,331 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (315.702072144s left)
2020-02-06 10:26:53,457 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206102653442346
2020-02-06 10:26:53,485 [salt.minion      :1432][INFO    ][6520] Starting a new job with PID 6520
2020-02-06 10:26:53,512 [salt.minion      :1711][INFO    ][6520] Returning information for job: 20200206102653442346
2020-02-06 10:27:23,586 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206102723577739
2020-02-06 10:27:23,607 [salt.minion      :1432][INFO    ][6538] Starting a new job with PID 6538
2020-02-06 10:27:23,629 [salt.minion      :1711][INFO    ][6538] Returning information for job: 20200206102723577739
2020-02-06 10:27:26,375 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (282.657728195s left)
2020-02-06 10:27:53,726 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206102753718353
2020-02-06 10:27:53,752 [salt.minion      :1432][INFO    ][6571] Starting a new job with PID 6571
2020-02-06 10:27:53,771 [salt.minion      :1711][INFO    ][6571] Returning information for job: 20200206102753718353
2020-02-06 10:27:59,654 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (249.378504038s left)
2020-02-06 10:28:23,914 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206102823902058
2020-02-06 10:28:23,937 [salt.minion      :1432][INFO    ][6590] Starting a new job with PID 6590
2020-02-06 10:28:23,956 [salt.minion      :1711][INFO    ][6590] Returning information for job: 20200206102823902058
2020-02-06 10:28:32,664 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (216.368921995s left)
2020-02-06 10:28:54,100 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206102854083369
2020-02-06 10:28:54,127 [salt.minion      :1432][INFO    ][6623] Starting a new job with PID 6623
2020-02-06 10:28:54,148 [salt.minion      :1711][INFO    ][6623] Returning information for job: 20200206102854083369
2020-02-06 10:29:05,750 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (183.282556057s left)
2020-02-06 10:29:24,316 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206102924301678
2020-02-06 10:29:24,345 [salt.minion      :1432][INFO    ][6643] Starting a new job with PID 6643
2020-02-06 10:29:24,367 [salt.minion      :1711][INFO    ][6643] Returning information for job: 20200206102924301678
2020-02-06 10:29:38,775 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (150.257379055s left)
2020-02-06 10:29:54,383 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206102954367403
2020-02-06 10:29:54,410 [salt.minion      :1432][INFO    ][6680] Starting a new job with PID 6680
2020-02-06 10:29:54,434 [salt.minion      :1711][INFO    ][6680] Returning information for job: 20200206102954367403
2020-02-06 10:30:12,068 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (116.964435101s left)
2020-02-06 10:30:24,408 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206103024395431
2020-02-06 10:30:24,439 [salt.minion      :1432][INFO    ][6703] Starting a new job with PID 6703
2020-02-06 10:30:24,462 [salt.minion      :1711][INFO    ][6703] Returning information for job: 20200206103024395431
2020-02-06 10:30:45,197 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (83.8362081051s left)
2020-02-06 10:30:54,614 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206103054599611
2020-02-06 10:30:54,645 [salt.minion      :1432][INFO    ][6740] Starting a new job with PID 6740
2020-02-06 10:30:54,667 [salt.minion      :1711][INFO    ][6740] Returning information for job: 20200206103054599611
2020-02-06 10:31:18,487 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (50.5459561348s left)
2020-02-06 10:31:24,702 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206103124690020
2020-02-06 10:31:24,728 [salt.minion      :1432][INFO    ][6760] Starting a new job with PID 6760
2020-02-06 10:31:24,751 [salt.minion      :1711][INFO    ][6760] Returning information for job: 20200206103124690020
2020-02-06 10:31:51,715 [salt.loaded.ext.module.maas:1024][INFO    ][4189] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (17.3181421757s left)
2020-02-06 10:31:54,767 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206103154755174
2020-02-06 10:31:54,796 [salt.minion      :1432][INFO    ][6796] Starting a new job with PID 6796
2020-02-06 10:31:54,817 [salt.minion      :1711][INFO    ][6796] Returning information for job: 20200206103154755174
2020-02-06 10:32:24,924 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206103224914242
2020-02-06 10:32:24,948 [salt.minion      :1432][INFO    ][6817] Starting a new job with PID 6817
2020-02-06 10:32:24,967 [salt.minion      :1711][INFO    ][6817] Returning information for job: 20200206103224914242
2020-02-06 10:32:24,972 [salt.state       :302 ][ERROR   ][4189] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['cmp002', 'cmp001']not in Deployed state
2020-02-06 10:32:24,974 [salt.state       :1951][INFO    ][4189] Completed state [maas.wait_for_machine_status] at time 10:32:24.973901 duration_in_ms=2265952.096
2020-02-06 10:32:24,983 [salt.minion      :1711][INFO    ][4189] Returning information for job: 20200206095430822216
2020-02-06 10:32:36,124 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command pillar.get with jid 20200206103236108338
2020-02-06 10:32:36,152 [salt.minion      :1432][INFO    ][6833] Starting a new job with PID 6833
2020-02-06 10:32:36,161 [salt.minion      :1711][INFO    ][6833] Returning information for job: 20200206103236108338
2020-02-06 10:32:36,943 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command service.status with jid 20200206103236929034
2020-02-06 10:32:36,968 [salt.minion      :1432][INFO    ][6838] Starting a new job with PID 6838
2020-02-06 10:32:37,532 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6838] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2020-02-06 10:32:37,577 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6838] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2020-02-06 10:32:37,606 [salt.minion      :1711][INFO    ][6838] Returning information for job: 20200206103236929034
2020-02-06 10:32:38,375 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command state.apply with jid 20200206103238361849
2020-02-06 10:32:38,404 [salt.minion      :1432][INFO    ][6849] Starting a new job with PID 6849
2020-02-06 10:32:44,532 [salt.state       :915 ][INFO    ][6849] Loading fresh modules for state activity
2020-02-06 10:32:45,125 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6849] Executing command 'salt-minion --version' in directory '/root'
2020-02-06 10:32:45,467 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6849] Executing command 'salt-minion --version' in directory '/root'
2020-02-06 10:32:46,518 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6849] Executing command 'salt-minion --version' in directory '/root'
2020-02-06 10:32:46,827 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6849] Executing command 'salt-minion --version' in directory '/root'
2020-02-06 10:32:48,751 [salt.state       :1780][INFO    ][6849] Running state [salt-minion] at time 10:32:48.751332
2020-02-06 10:32:48,751 [salt.state       :1813][INFO    ][6849] Executing state pkg.installed for [salt-minion]
2020-02-06 10:32:48,752 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6849] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-06 10:32:48,863 [salt.state       :300 ][INFO    ][6849] All specified packages are already installed
2020-02-06 10:32:48,864 [salt.state       :1951][INFO    ][6849] Completed state [salt-minion] at time 10:32:48.863921 duration_in_ms=112.59
2020-02-06 10:32:48,864 [salt.state       :1780][INFO    ][6849] Running state [salt_minion_dependency_packages] at time 10:32:48.864277
2020-02-06 10:32:48,865 [salt.state       :1813][INFO    ][6849] Executing state pkg.installed for [salt_minion_dependency_packages]
2020-02-06 10:32:48,878 [salt.state       :300 ][INFO    ][6849] All specified packages are already installed
2020-02-06 10:32:48,878 [salt.state       :1951][INFO    ][6849] Completed state [salt_minion_dependency_packages] at time 10:32:48.878389 duration_in_ms=14.111
2020-02-06 10:32:48,883 [salt.state       :1780][INFO    ][6849] Running state [/etc/salt/minion.d/minion.conf] at time 10:32:48.883584
2020-02-06 10:32:48,883 [salt.state       :1813][INFO    ][6849] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2020-02-06 10:32:49,163 [salt.state       :300 ][INFO    ][6849] File /etc/salt/minion.d/minion.conf is in the correct state
2020-02-06 10:32:49,163 [salt.state       :1951][INFO    ][6849] Completed state [/etc/salt/minion.d/minion.conf] at time 10:32:49.163615 duration_in_ms=280.031
2020-02-06 10:32:49,163 [salt.state       :1780][INFO    ][6849] Running state [python-netaddr] at time 10:32:49.163879
2020-02-06 10:32:49,164 [salt.state       :1813][INFO    ][6849] Executing state pkg.installed for [python-netaddr]
2020-02-06 10:32:49,172 [salt.state       :300 ][INFO    ][6849] All specified packages are already installed
2020-02-06 10:32:49,172 [salt.state       :1951][INFO    ][6849] Completed state [python-netaddr] at time 10:32:49.172728 duration_in_ms=8.849
2020-02-06 10:32:49,176 [salt.state       :1780][INFO    ][6849] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 10:32:49.176059
2020-02-06 10:32:49,176 [salt.state       :1813][INFO    ][6849] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2020-02-06 10:32:49,188 [salt.state       :300 ][INFO    ][6849] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2020-02-06 10:32:49,188 [salt.state       :1951][INFO    ][6849] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 10:32:49.188376 duration_in_ms=12.317
2020-02-06 10:32:49,189 [salt.state       :1780][INFO    ][6849] Running state [salt-minion] at time 10:32:49.189600
2020-02-06 10:32:49,189 [salt.state       :1813][INFO    ][6849] Executing state service.running for [salt-minion]
2020-02-06 10:32:49,190 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6849] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2020-02-06 10:32:49,237 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6849] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2020-02-06 10:32:49,259 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6849] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2020-02-06 10:32:49,285 [salt.state       :300 ][INFO    ][6849] The service salt-minion is already running
2020-02-06 10:32:49,286 [salt.state       :1951][INFO    ][6849] Completed state [salt-minion] at time 10:32:49.285954 duration_in_ms=96.353
2020-02-06 10:32:49,289 [salt.state       :1780][INFO    ][6849] Running state [/etc/salt/grains.d] at time 10:32:49.288934
2020-02-06 10:32:49,291 [salt.state       :1813][INFO    ][6849] Executing state file.directory for [/etc/salt/grains.d]
2020-02-06 10:32:49,293 [salt.state       :300 ][INFO    ][6849] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2020-02-06 10:32:49,294 [salt.state       :1951][INFO    ][6849] Completed state [/etc/salt/grains.d] at time 10:32:49.294100 duration_in_ms=5.165
2020-02-06 10:32:49,295 [salt.state       :1780][INFO    ][6849] Running state [/etc/salt/grains] at time 10:32:49.295444
2020-02-06 10:32:49,295 [salt.state       :1813][INFO    ][6849] Executing state file.managed for [/etc/salt/grains]
2020-02-06 10:32:49,296 [salt.state       :300 ][INFO    ][6849] File /etc/salt/grains exists with proper permissions. No changes made.
2020-02-06 10:32:49,297 [salt.state       :1951][INFO    ][6849] Completed state [/etc/salt/grains] at time 10:32:49.297225 duration_in_ms=1.781
2020-02-06 10:32:49,298 [salt.state       :1780][INFO    ][6849] Running state [/etc/salt/grains.d/placeholder] at time 10:32:49.297956
2020-02-06 10:32:49,298 [salt.state       :1813][INFO    ][6849] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2020-02-06 10:32:49,299 [salt.state       :300 ][INFO    ][6849] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2020-02-06 10:32:49,299 [salt.state       :1951][INFO    ][6849] Completed state [/etc/salt/grains.d/placeholder] at time 10:32:49.299697 duration_in_ms=1.741
2020-02-06 10:32:49,300 [salt.state       :1780][INFO    ][6849] Running state [/etc/salt/grains.d/sphinx] at time 10:32:49.300583
2020-02-06 10:32:49,301 [salt.state       :1813][INFO    ][6849] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2020-02-06 10:32:49,304 [salt.state       :300 ][INFO    ][6849] File /etc/salt/grains.d/sphinx is in the correct state
2020-02-06 10:32:49,304 [salt.state       :1951][INFO    ][6849] Completed state [/etc/salt/grains.d/sphinx] at time 10:32:49.304332 duration_in_ms=3.75
2020-02-06 10:32:49,306 [salt.state       :1780][INFO    ][6849] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 10:32:49.306631
2020-02-06 10:32:49,306 [salt.state       :1813][INFO    ][6849] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2020-02-06 10:32:49,307 [salt.state       :300 ][INFO    ][6849] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2020-02-06 10:32:49,307 [salt.state       :1951][INFO    ][6849] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 10:32:49.307410 duration_in_ms=0.779
2020-02-06 10:32:49,307 [salt.state       :1780][INFO    ][6849] Running state [/etc/salt/grains.d/dns_records] at time 10:32:49.307893
2020-02-06 10:32:49,308 [salt.state       :1813][INFO    ][6849] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2020-02-06 10:32:49,309 [salt.state       :300 ][INFO    ][6849] File /etc/salt/grains.d/dns_records is in the correct state
2020-02-06 10:32:49,309 [salt.state       :1951][INFO    ][6849] Completed state [/etc/salt/grains.d/dns_records] at time 10:32:49.309234 duration_in_ms=1.341
2020-02-06 10:32:49,310 [salt.state       :1780][INFO    ][6849] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 10:32:49.310152
2020-02-06 10:32:49,310 [salt.state       :1813][INFO    ][6849] 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-06 10:32:49,310 [salt.state       :300 ][INFO    ][6849] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2020-02-06 10:32:49,310 [salt.state       :1951][INFO    ][6849] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 10:32:49.310918 duration_in_ms=0.766
2020-02-06 10:32:49,311 [salt.state       :1780][INFO    ][6849] Running state [/etc/salt/grains.d/salt] at time 10:32:49.311400
2020-02-06 10:32:49,311 [salt.state       :1813][INFO    ][6849] Executing state file.managed for [/etc/salt/grains.d/salt]
2020-02-06 10:32:49,312 [salt.state       :300 ][INFO    ][6849] File /etc/salt/grains.d/salt is in the correct state
2020-02-06 10:32:49,312 [salt.state       :1951][INFO    ][6849] Completed state [/etc/salt/grains.d/salt] at time 10:32:49.312739 duration_in_ms=1.339
2020-02-06 10:32:49,314 [salt.state       :1780][INFO    ][6849] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 10:32:49.314799
2020-02-06 10:32:49,315 [salt.state       :1813][INFO    ][6849] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2020-02-06 10:32:49,315 [salt.state       :300 ][INFO    ][6849] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2020-02-06 10:32:49,315 [salt.state       :1951][INFO    ][6849] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 10:32:49.315550 duration_in_ms=0.752
2020-02-06 10:32:49,317 [salt.state       :1780][INFO    ][6849] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 10:32:49.317458
2020-02-06 10:32:49,317 [salt.state       :1813][INFO    ][6849] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2020-02-06 10:32:49,318 [salt.state       :300 ][INFO    ][6849] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2020-02-06 10:32:49,318 [salt.state       :1951][INFO    ][6849] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 10:32:49.318221 duration_in_ms=0.763
2020-02-06 10:32:49,318 [salt.state       :1780][INFO    ][6849] Running state [mine.update] at time 10:32:49.318874
2020-02-06 10:32:49,319 [salt.state       :1813][INFO    ][6849] Executing state module.wait for [mine.update]
2020-02-06 10:32:49,319 [salt.state       :300 ][INFO    ][6849] No changes made for mine.update
2020-02-06 10:32:49,319 [salt.state       :1951][INFO    ][6849] Completed state [mine.update] at time 10:32:49.319556 duration_in_ms=0.683
2020-02-06 10:32:49,319 [salt.state       :1780][INFO    ][6849] Running state [ca-certificates] at time 10:32:49.319791
2020-02-06 10:32:49,320 [salt.state       :1813][INFO    ][6849] Executing state pkg.installed for [ca-certificates]
2020-02-06 10:32:49,329 [salt.state       :300 ][INFO    ][6849] All specified packages are already installed
2020-02-06 10:32:49,329 [salt.state       :1951][INFO    ][6849] Completed state [ca-certificates] at time 10:32:49.329570 duration_in_ms=9.779
2020-02-06 10:32:49,330 [salt.state       :1780][INFO    ][6849] Running state [update-ca-certificates] at time 10:32:49.330249
2020-02-06 10:32:49,330 [salt.state       :1813][INFO    ][6849] Executing state cmd.wait for [update-ca-certificates]
2020-02-06 10:32:49,330 [salt.state       :300 ][INFO    ][6849] No changes made for update-ca-certificates
2020-02-06 10:32:49,331 [salt.state       :1951][INFO    ][6849] Completed state [update-ca-certificates] at time 10:32:49.330957 duration_in_ms=0.708
2020-02-06 10:32:49,331 [salt.state       :1780][INFO    ][6849] Running state [iptables] at time 10:32:49.331191
2020-02-06 10:32:49,331 [salt.state       :1813][INFO    ][6849] Executing state pkg.installed for [iptables]
2020-02-06 10:32:49,340 [salt.state       :300 ][INFO    ][6849] All specified packages are already installed
2020-02-06 10:32:49,340 [salt.state       :1951][INFO    ][6849] Completed state [iptables] at time 10:32:49.340742 duration_in_ms=9.551
2020-02-06 10:32:49,341 [salt.state       :1780][INFO    ][6849] Running state [iptables-persistent] at time 10:32:49.340983
2020-02-06 10:32:49,341 [salt.state       :1813][INFO    ][6849] Executing state pkg.installed for [iptables-persistent]
2020-02-06 10:32:49,350 [salt.state       :300 ][INFO    ][6849] All specified packages are already installed
2020-02-06 10:32:49,351 [salt.state       :1951][INFO    ][6849] Completed state [iptables-persistent] at time 10:32:49.350996 duration_in_ms=10.013
2020-02-06 10:32:49,352 [salt.state       :1780][INFO    ][6849] Running state [iptables_modules_v4_load] at time 10:32:49.352163
2020-02-06 10:32:49,352 [salt.state       :1813][INFO    ][6849] Executing state kmod.present for [iptables_modules_v4_load]
2020-02-06 10:32:49,352 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6849] Executing command 'lsmod' in directory '/root'
2020-02-06 10:32:49,378 [salt.state       :300 ][INFO    ][6849] Kernel modules iptable_filter, ip_tables are already present
2020-02-06 10:32:49,378 [salt.state       :1951][INFO    ][6849] Completed state [iptables_modules_v4_load] at time 10:32:49.378733 duration_in_ms=26.569
2020-02-06 10:32:49,380 [salt.state       :1780][INFO    ][6849] Running state [/etc/iptables/rules.v4] at time 10:32:49.379984
2020-02-06 10:32:49,380 [salt.state       :1813][INFO    ][6849] Executing state file.managed for [/etc/iptables/rules.v4]
2020-02-06 10:32:49,480 [salt.state       :300 ][INFO    ][6849] File /etc/iptables/rules.v4 is in the correct state
2020-02-06 10:32:49,480 [salt.state       :1951][INFO    ][6849] Completed state [/etc/iptables/rules.v4] at time 10:32:49.480927 duration_in_ms=100.943
2020-02-06 10:32:49,482 [salt.state       :1780][INFO    ][6849] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 10:32:49.482399
2020-02-06 10:32:49,482 [salt.state       :1813][INFO    ][6849] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2020-02-06 10:32:49,483 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6849] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2020-02-06 10:32:49,504 [salt.state       :300 ][INFO    ][6849] onlyif execution failed
2020-02-06 10:32:49,505 [salt.state       :1951][INFO    ][6849] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 10:32:49.504875 duration_in_ms=22.474
2020-02-06 10:32:49,508 [salt.state       :1780][INFO    ][6849] Running state [netfilter-persistent] at time 10:32:49.508224
2020-02-06 10:32:49,508 [salt.state       :1813][INFO    ][6849] Executing state service.running for [netfilter-persistent]
2020-02-06 10:32:49,511 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6849] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2020-02-06 10:32:49,539 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6849] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2020-02-06 10:32:49,563 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6849] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2020-02-06 10:32:49,588 [salt.state       :300 ][INFO    ][6849] The service netfilter-persistent is already running
2020-02-06 10:32:49,591 [salt.state       :1951][INFO    ][6849] Completed state [netfilter-persistent] at time 10:32:49.591409 duration_in_ms=83.186
2020-02-06 10:32:49,592 [salt.state       :1780][INFO    ][6849] Running state [iptables_extra.remove_stale_tables] at time 10:32:49.592827
2020-02-06 10:32:49,593 [salt.state       :1813][INFO    ][6849] Executing state module.wait for [iptables_extra.remove_stale_tables]
2020-02-06 10:32:49,593 [salt.state       :300 ][INFO    ][6849] No changes made for iptables_extra.remove_stale_tables
2020-02-06 10:32:49,594 [salt.state       :1951][INFO    ][6849] Completed state [iptables_extra.remove_stale_tables] at time 10:32:49.594151 duration_in_ms=1.324
2020-02-06 10:32:49,594 [salt.state       :1780][INFO    ][6849] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 10:32:49.594554
2020-02-06 10:32:49,595 [salt.state       :1813][INFO    ][6849] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2020-02-06 10:32:49,595 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6849] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2020-02-06 10:32:49,615 [salt.state       :300 ][INFO    ][6849] onlyif execution failed
2020-02-06 10:32:49,616 [salt.state       :1951][INFO    ][6849] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 10:32:49.616314 duration_in_ms=21.761
2020-02-06 10:32:49,617 [salt.state       :1780][INFO    ][6849] Running state [/etc/iptables/rules.v6] at time 10:32:49.617723
2020-02-06 10:32:49,618 [salt.state       :1813][INFO    ][6849] Executing state file.absent for [/etc/iptables/rules.v6]
2020-02-06 10:32:49,618 [salt.state       :300 ][INFO    ][6849] File /etc/iptables/rules.v6 is not present
2020-02-06 10:32:49,619 [salt.state       :1951][INFO    ][6849] Completed state [/etc/iptables/rules.v6] at time 10:32:49.619016 duration_in_ms=1.294
2020-02-06 10:32:49,620 [salt.state       :1780][INFO    ][6849] Running state [iptables_extra.flush_all] at time 10:32:49.619972
2020-02-06 10:32:49,620 [salt.state       :1813][INFO    ][6849] Executing state module.wait for [iptables_extra.flush_all]
2020-02-06 10:32:49,620 [salt.state       :300 ][INFO    ][6849] No changes made for iptables_extra.flush_all
2020-02-06 10:32:49,621 [salt.state       :1951][INFO    ][6849] Completed state [iptables_extra.flush_all] at time 10:32:49.620977 duration_in_ms=1.005
2020-02-06 10:32:49,627 [salt.minion      :1711][INFO    ][6849] Returning information for job: 20200206103238361849
2020-02-06 10:32:50,412 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command state.apply with jid 20200206103250395330
2020-02-06 10:32:50,439 [salt.minion      :1432][INFO    ][6944] Starting a new job with PID 6944
2020-02-06 10:32:51,395 [salt.state       :915 ][INFO    ][6944] Loading fresh modules for state activity
2020-02-06 10:32:52,294 [salt.state       :1780][INFO    ][6944] Running state [maas-rack-controller] at time 10:32:52.294879
2020-02-06 10:32:52,295 [salt.state       :1813][INFO    ][6944] Executing state pkg.installed for [maas-rack-controller]
2020-02-06 10:32:52,296 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6944] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-06 10:32:52,404 [salt.state       :300 ][INFO    ][6944] All specified packages are already installed
2020-02-06 10:32:52,404 [salt.state       :1951][INFO    ][6944] Completed state [maas-rack-controller] at time 10:32:52.404830 duration_in_ms=109.954
2020-02-06 10:32:52,405 [salt.state       :1780][INFO    ][6944] Running state [ipmitool] at time 10:32:52.405223
2020-02-06 10:32:52,405 [salt.state       :1813][INFO    ][6944] Executing state pkg.installed for [ipmitool]
2020-02-06 10:32:52,422 [salt.state       :300 ][INFO    ][6944] All specified packages are already installed
2020-02-06 10:32:52,422 [salt.state       :1951][INFO    ][6944] Completed state [ipmitool] at time 10:32:52.422406 duration_in_ms=17.172
2020-02-06 10:32:52,429 [salt.state       :1780][INFO    ][6944] Running state [/etc/maas/rackd.conf] at time 10:32:52.429255
2020-02-06 10:32:52,429 [salt.state       :1813][INFO    ][6944] Executing state file.line for [/etc/maas/rackd.conf]
2020-02-06 10:32:52,430 [salt.state       :300 ][INFO    ][6944] No changes needed to be made
2020-02-06 10:32:52,430 [salt.state       :1951][INFO    ][6944] Completed state [/etc/maas/rackd.conf] at time 10:32:52.430737 duration_in_ms=1.482
2020-02-06 10:32:52,431 [salt.state       :1780][INFO    ][6944] Running state [/etc/maas/rackd.conf] at time 10:32:52.430978
2020-02-06 10:32:52,431 [salt.state       :1813][INFO    ][6944] Executing state file.managed for [/etc/maas/rackd.conf]
2020-02-06 10:32:52,431 [salt.loaded.int.states.file:2298][WARNING ][6944] 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-06 10:32:52,432 [salt.state       :300 ][INFO    ][6944] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2020-02-06 10:32:52,432 [salt.state       :1951][INFO    ][6944] Completed state [/etc/maas/rackd.conf] at time 10:32:52.432275 duration_in_ms=1.297
2020-02-06 10:32:52,434 [salt.state       :1780][INFO    ][6944] Running state [maas-rackd] at time 10:32:52.434108
2020-02-06 10:32:52,434 [salt.state       :1813][INFO    ][6944] Executing state service.running for [maas-rackd]
2020-02-06 10:32:52,435 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6944] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2020-02-06 10:32:52,483 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6944] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2020-02-06 10:32:52,508 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6944] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2020-02-06 10:32:52,534 [salt.state       :300 ][INFO    ][6944] The service maas-rackd is already running
2020-02-06 10:32:52,534 [salt.state       :1951][INFO    ][6944] Completed state [maas-rackd] at time 10:32:52.534886 duration_in_ms=100.777
2020-02-06 10:32:52,537 [salt.minion      :1711][INFO    ][6944] Returning information for job: 20200206103250395330
2020-02-06 10:32:53,293 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command state.apply with jid 20200206103253278779
2020-02-06 10:32:53,325 [salt.minion      :1432][INFO    ][6968] Starting a new job with PID 6968
2020-02-06 10:32:54,344 [salt.state       :915 ][INFO    ][6968] Loading fresh modules for state activity
2020-02-06 10:32:55,406 [salt.state       :1780][INFO    ][6968] Running state [maas-region-controller] at time 10:32:55.406611
2020-02-06 10:32:55,407 [salt.state       :1813][INFO    ][6968] Executing state pkg.installed for [maas-region-controller]
2020-02-06 10:32:55,407 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6968] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-06 10:32:55,522 [salt.state       :300 ][INFO    ][6968] All specified packages are already installed
2020-02-06 10:32:55,522 [salt.state       :1951][INFO    ][6968] Completed state [maas-region-controller] at time 10:32:55.522754 duration_in_ms=116.146
2020-02-06 10:32:55,523 [salt.state       :1780][INFO    ][6968] Running state [python-oauth] at time 10:32:55.523081
2020-02-06 10:32:55,523 [salt.state       :1813][INFO    ][6968] Executing state pkg.installed for [python-oauth]
2020-02-06 10:32:55,534 [salt.state       :300 ][INFO    ][6968] All specified packages are already installed
2020-02-06 10:32:55,534 [salt.state       :1951][INFO    ][6968] Completed state [python-oauth] at time 10:32:55.534320 duration_in_ms=11.239
2020-02-06 10:32:55,537 [salt.state       :1780][INFO    ][6968] Running state [/etc/maas/regiond.conf] at time 10:32:55.537661
2020-02-06 10:32:55,537 [salt.state       :1813][INFO    ][6968] Executing state file.replace for [/etc/maas/regiond.conf]
2020-02-06 10:32:55,544 [salt.state       :300 ][INFO    ][6968] No changes needed to be made
2020-02-06 10:32:55,544 [salt.state       :1951][INFO    ][6968] Completed state [/etc/maas/regiond.conf] at time 10:32:55.544446 duration_in_ms=6.785
2020-02-06 10:32:55,544 [salt.state       :1780][INFO    ][6968] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 10:32:55.544919
2020-02-06 10:32:55,545 [salt.state       :1813][INFO    ][6968] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2020-02-06 10:32:55,612 [salt.state       :300 ][INFO    ][6968] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2020-02-06 10:32:55,612 [salt.state       :1951][INFO    ][6968] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 10:32:55.612823 duration_in_ms=67.903
2020-02-06 10:32:55,614 [salt.state       :1780][INFO    ][6968] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 10:32:55.613986
2020-02-06 10:32:55,614 [salt.state       :1813][INFO    ][6968] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-02-06 10:32:55,618 [salt.state       :300 ][INFO    ][6968] No changes needed to be made
2020-02-06 10:32:55,618 [salt.state       :1951][INFO    ][6968] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 10:32:55.618494 duration_in_ms=4.508
2020-02-06 10:32:55,619 [salt.state       :1780][INFO    ][6968] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 10:32:55.618966
2020-02-06 10:32:55,619 [salt.state       :1813][INFO    ][6968] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-02-06 10:32:55,622 [salt.state       :300 ][INFO    ][6968] No changes needed to be made
2020-02-06 10:32:55,622 [salt.state       :1951][INFO    ][6968] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 10:32:55.622843 duration_in_ms=3.877
2020-02-06 10:32:55,623 [salt.state       :1780][INFO    ][6968] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 10:32:55.623320
2020-02-06 10:32:55,623 [salt.state       :1813][INFO    ][6968] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2020-02-06 10:32:55,647 [salt.state       :300 ][INFO    ][6968] No changes needed to be made
2020-02-06 10:32:55,647 [salt.state       :1951][INFO    ][6968] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 10:32:55.647688 duration_in_ms=24.367
2020-02-06 10:32:55,647 [salt.state       :1780][INFO    ][6968] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 10:32:55.647939
2020-02-06 10:32:55,648 [salt.state       :1813][INFO    ][6968] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2020-02-06 10:32:55,667 [salt.state       :300 ][INFO    ][6968] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2020-02-06 10:32:55,667 [salt.state       :1951][INFO    ][6968] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 10:32:55.667534 duration_in_ms=19.593
2020-02-06 10:32:55,668 [salt.state       :1780][INFO    ][6968] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 10:32:55.668703
2020-02-06 10:32:55,669 [salt.state       :1813][INFO    ][6968] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2020-02-06 10:32:55,738 [salt.state       :300 ][INFO    ][6968] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2020-02-06 10:32:55,739 [salt.state       :1951][INFO    ][6968] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 10:32:55.739156 duration_in_ms=70.453
2020-02-06 10:32:55,740 [salt.state       :1780][INFO    ][6968] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 10:32:55.740202
2020-02-06 10:32:55,741 [salt.state       :1813][INFO    ][6968] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2020-02-06 10:32:55,815 [salt.state       :300 ][INFO    ][6968] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2020-02-06 10:32:55,815 [salt.state       :1951][INFO    ][6968] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 10:32:55.815738 duration_in_ms=75.536
2020-02-06 10:32:55,816 [salt.state       :1780][INFO    ][6968] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 10:32:55.816273
2020-02-06 10:32:55,816 [salt.state       :1813][INFO    ][6968] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2020-02-06 10:32:55,894 [salt.state       :300 ][INFO    ][6968] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2020-02-06 10:32:55,894 [salt.state       :1951][INFO    ][6968] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 10:32:55.894782 duration_in_ms=78.509
2020-02-06 10:32:55,895 [salt.state       :1780][INFO    ][6968] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 10:32:55.895261
2020-02-06 10:32:55,895 [salt.state       :1813][INFO    ][6968] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic]
2020-02-06 10:32:55,952 [salt.state       :300 ][INFO    ][6968] File /etc/maas/preseeds/curtin_userdata_amd64_generic_bionic is in the correct state
2020-02-06 10:32:55,952 [salt.state       :1951][INFO    ][6968] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 10:32:55.952447 duration_in_ms=57.186
2020-02-06 10:32:55,952 [salt.state       :1780][INFO    ][6968] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 10:32:55.952941
2020-02-06 10:32:55,953 [salt.state       :1813][INFO    ][6968] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic]
2020-02-06 10:32:56,030 [salt.state       :300 ][INFO    ][6968] File /etc/maas/preseeds/curtin_userdata_arm64_generic_bionic is in the correct state
2020-02-06 10:32:56,030 [salt.state       :1951][INFO    ][6968] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 10:32:56.030691 duration_in_ms=77.748
2020-02-06 10:32:56,031 [salt.state       :1780][INFO    ][6968] Running state [/root/.pgpass] at time 10:32:56.031250
2020-02-06 10:32:56,031 [salt.state       :1813][INFO    ][6968] Executing state file.managed for [/root/.pgpass]
2020-02-06 10:32:56,084 [salt.state       :300 ][INFO    ][6968] File /root/.pgpass is in the correct state
2020-02-06 10:32:56,084 [salt.state       :1951][INFO    ][6968] Completed state [/root/.pgpass] at time 10:32:56.084760 duration_in_ms=53.51
2020-02-06 10:32:56,092 [salt.state       :1780][INFO    ][6968] Running state [maas-region migrate --noinput] at time 10:32:56.092558
2020-02-06 10:32:56,093 [salt.state       :1813][INFO    ][6968] Executing state cmd.run for [maas-region migrate --noinput]
2020-02-06 10:32:56,094 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6968] Executing command 'maas-region migrate --noinput' in directory '/root'
2020-02-06 10:32:59,058 [salt.state       :300 ][INFO    ][6968] {'pid': 6999, '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-06 10:32:59,059 [salt.state       :1951][INFO    ][6968] Completed state [maas-region migrate --noinput] at time 10:32:59.059194 duration_in_ms=2966.635
2020-02-06 10:32:59,059 [salt.state       :2022][WARNING ][6968] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-02-06 10:32:59,063 [salt.state       :1780][INFO    ][6968] Running state [maas-regiond] at time 10:32:59.063042
2020-02-06 10:32:59,063 [salt.state       :1813][INFO    ][6968] Executing state service.running for [maas-regiond]
2020-02-06 10:32:59,065 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6968] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2020-02-06 10:32:59,122 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6968] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2020-02-06 10:32:59,143 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6968] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2020-02-06 10:32:59,170 [salt.state       :300 ][INFO    ][6968] The service maas-regiond is already running
2020-02-06 10:32:59,171 [salt.state       :1951][INFO    ][6968] Completed state [maas-regiond] at time 10:32:59.170942 duration_in_ms=107.899
2020-02-06 10:32:59,173 [salt.state       :1780][INFO    ][6968] Running state [bind9] at time 10:32:59.173475
2020-02-06 10:32:59,174 [salt.state       :1813][INFO    ][6968] Executing state service.running for [bind9]
2020-02-06 10:32:59,175 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6968] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2020-02-06 10:32:59,205 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6968] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2020-02-06 10:32:59,230 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6968] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2020-02-06 10:32:59,260 [salt.state       :300 ][INFO    ][6968] The service bind9 is already running
2020-02-06 10:32:59,264 [salt.state       :1951][INFO    ][6968] Completed state [bind9] at time 10:32:59.261250 duration_in_ms=87.774
2020-02-06 10:32:59,266 [salt.state       :1780][INFO    ][6968] Running state [maasng.wait_for_http_code] at time 10:32:59.266377
2020-02-06 10:32:59,267 [salt.state       :1813][INFO    ][6968] Executing state module.run for [maasng.wait_for_http_code]
2020-02-06 10:32:59,267 [salt.utils.decorators:613 ][WARNING ][6968] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-06 10:32:59,397 [salt.state       :300 ][INFO    ][6968] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2020-02-06 10:32:59,398 [salt.state       :1951][INFO    ][6968] Completed state [maasng.wait_for_http_code] at time 10:32:59.397941 duration_in_ms=131.564
2020-02-06 10:32:59,399 [salt.state       :1780][INFO    ][6968] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 10:32:59.399380
2020-02-06 10:32:59,399 [salt.state       :1813][INFO    ][6968] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2020-02-06 10:32:59,400 [salt.state       :300 ][INFO    ][6968] /var/lib/maas/.setup_admin exists
2020-02-06 10:32:59,401 [salt.state       :1951][INFO    ][6968] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 10:32:59.400999 duration_in_ms=1.619
2020-02-06 10:32:59,402 [salt.state       :1780][INFO    ][6968] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:32:59.402122
2020-02-06 10:32:59,402 [salt.state       :1813][INFO    ][6968] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-06 10:32:59,403 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6968] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-06 10:33:01,439 [salt.state       :300 ][INFO    ][6968] {'pid': 7013, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-06 10:33:01,440 [salt.state       :1951][INFO    ][6968] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:33:01.440488 duration_in_ms=2038.365
2020-02-06 10:33:01,451 [salt.state       :1780][INFO    ][6968] Running state [maas_region_boot_source_resources_mirror] at time 10:33:01.451697
2020-02-06 10:33:01,452 [salt.state       :1813][INFO    ][6968] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2020-02-06 10:33:01,517 [salt.state       :300 ][INFO    ][6968] {'changes': {}}
2020-02-06 10:33:01,518 [salt.state       :1951][INFO    ][6968] Completed state [maas_region_boot_source_resources_mirror] at time 10:33:01.518592 duration_in_ms=66.895
2020-02-06 10:33:01,519 [salt.state       :1780][INFO    ][6968] Running state [maasng.boot_resources_import] at time 10:33:01.519284
2020-02-06 10:33:01,519 [salt.state       :1813][INFO    ][6968] Executing state module.run for [maasng.boot_resources_import]
2020-02-06 10:33:01,519 [salt.utils.decorators:613 ][WARNING ][6968] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-06 10:33:01,600 [salt.loaded.ext.module.maasng:1600][INFO    ][6968] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2020-02-06 10:33:06,694 [salt.state       :300 ][INFO    ][6968] {'ret': True}
2020-02-06 10:33:06,694 [salt.state       :1951][INFO    ][6968] Completed state [maasng.boot_resources_import] at time 10:33:06.694582 duration_in_ms=5175.297
2020-02-06 10:33:06,695 [salt.state       :1780][INFO    ][6968] Running state [maas_region_boot_sources_selection_bionic] at time 10:33:06.695811
2020-02-06 10:33:06,696 [salt.state       :1813][INFO    ][6968] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_bionic]
2020-02-06 10:33:06,844 [salt.state       :300 ][INFO    ][6968] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2020-02-06 10:33:06,844 [salt.state       :1951][INFO    ][6968] Completed state [maas_region_boot_sources_selection_bionic] at time 10:33:06.844603 duration_in_ms=148.791
2020-02-06 10:33:06,847 [salt.state       :1780][INFO    ][6968] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 10:33:06.847077
2020-02-06 10:33:06,847 [salt.state       :1813][INFO    ][6968] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2020-02-06 10:33:06,848 [salt.utils.decorators:613 ][WARNING ][6968] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-06 10:33:06,848 [salt.loaded.ext.module.maasng:1771][INFO    ][6968] boot-sources sync initiated for ALL Rack's
2020-02-06 10:33:07,610 [salt.state       :300 ][INFO    ][6968] {'ret': True}
2020-02-06 10:33:07,611 [salt.state       :1951][INFO    ][6968] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 10:33:07.611028 duration_in_ms=763.951
2020-02-06 10:33:07,613 [salt.state       :1780][INFO    ][6968] Running state [maas.process_maas_config] at time 10:33:07.613179
2020-02-06 10:33:07,613 [salt.state       :1813][INFO    ][6968] Executing state module.run for [maas.process_maas_config]
2020-02-06 10:33:07,614 [salt.utils.decorators:613 ][WARNING ][6968] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-06 10:33:07,614 [salt.loaded.ext.module.maas:92  ][INFO    ][6968] maasconfig name=enable_http_proxy value=True
2020-02-06 10:33:07,653 [salt.loaded.ext.module.maas:92  ][INFO    ][6968] maasconfig name=upstream_dns value=8.8.8.8
2020-02-06 10:33:08,401 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206103308390196
2020-02-06 10:33:08,424 [salt.minion      :1432][INFO    ][7048] Starting a new job with PID 7048
2020-02-06 10:33:08,446 [salt.minion      :1711][INFO    ][7048] Returning information for job: 20200206103308390196
2020-02-06 10:33:08,975 [salt.loaded.ext.module.maas:92  ][INFO    ][6968] maasconfig name=commissioning_distro_series value=bionic
2020-02-06 10:33:09,195 [salt.loaded.ext.module.maas:92  ][INFO    ][6968] maasconfig name=default_osystem value=ubuntu
2020-02-06 10:33:09,239 [salt.loaded.ext.module.maas:92  ][INFO    ][6968] maasconfig name=active_discovery_interval value=600
2020-02-06 10:33:09,282 [salt.loaded.ext.module.maas:92  ][INFO    ][6968] maasconfig name=dnssec_validation value=no
2020-02-06 10:33:09,315 [salt.loaded.ext.module.maas:92  ][INFO    ][6968] maasconfig name=kernel_opts value=spectre_v2=off nopti kpti=off nospec_store_bypass_disable noibrs noibpb
2020-02-06 10:33:09,342 [salt.loaded.ext.module.maas:92  ][INFO    ][6968] maasconfig name=network_discovery value=enabled
2020-02-06 10:33:09,411 [salt.loaded.ext.module.maas:92  ][INFO    ][6968] maasconfig name=enable_third_party_drivers value=True
2020-02-06 10:33:09,440 [salt.loaded.ext.module.maas:92  ][INFO    ][6968] maasconfig name=default_storage_layout value=lvm
2020-02-06 10:33:09,675 [salt.loaded.ext.module.maas:92  ][INFO    ][6968] maasconfig name=maas_name value=mas01
2020-02-06 10:33:09,706 [salt.loaded.ext.module.maas:92  ][INFO    ][6968] maasconfig name=disk_erase_with_secure_erase value=False
2020-02-06 10:33:09,897 [salt.loaded.ext.module.maas:92  ][INFO    ][6968] maasconfig name=default_distro_series value=bionic
2020-02-06 10:33:09,946 [salt.loaded.ext.module.maas:92  ][INFO    ][6968] maasconfig name=ntp_external_only value=True
2020-02-06 10:33:10,005 [salt.loaded.ext.module.maas:92  ][INFO    ][6968] maasconfig name=default_min_hwe_kernel value=ga-18.04
2020-02-06 10:33:10,067 [salt.state       :300 ][INFO    ][6968] {'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-06 10:33:10,067 [salt.state       :1951][INFO    ][6968] Completed state [maas.process_maas_config] at time 10:33:10.067483 duration_in_ms=2454.304
2020-02-06 10:33:10,068 [salt.state       :1780][INFO    ][6968] Running state [pxe_admin] at time 10:33:10.068516
2020-02-06 10:33:10,069 [salt.state       :1813][INFO    ][6968] Executing state maasng.fabric_present for [pxe_admin]
2020-02-06 10:33:10,110 [salt.loaded.ext.module.maasng:945 ][INFO    ][6968] [{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'w8hbfh', 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-06 10:33:10,164 [salt.loaded.ext.module.maasng:1008][WARNING ][6968] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2020-02-06 10:33:10,164 [salt.loaded.ext.module.maasng:1011][WARNING ][6968] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2020-02-06 10:33:10,208 [salt.state       :300 ][INFO    ][6968] {'new': 'Fabric  pxe_admin created', 'result': True}
2020-02-06 10:33:10,209 [salt.state       :1951][INFO    ][6968] Completed state [pxe_admin] at time 10:33:10.209212 duration_in_ms=140.697
2020-02-06 10:33:10,210 [salt.state       :1780][INFO    ][6968] Running state [vlan 0] at time 10:33:10.210131
2020-02-06 10:33:10,210 [salt.state       :1813][INFO    ][6968] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-02-06 10:33:10,258 [salt.loaded.ext.module.maasng:945 ][INFO    ][6968] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'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'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'w8hbfh', 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-06 10:33:10,355 [salt.loaded.ext.module.maasng:945 ][INFO    ][6968] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'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'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'w8hbfh', 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-06 10:33:10,547 [salt.loaded.ext.module.maasng:945 ][INFO    ][6968] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8hbfh', 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-06 10:33:10,612 [salt.state       :300 ][INFO    ][6968] {'new': 'Vlan untagged was updated'}
2020-02-06 10:33:10,612 [salt.state       :1951][INFO    ][6968] Completed state [vlan 0] at time 10:33:10.612728 duration_in_ms=402.597
2020-02-06 10:33:10,615 [salt.state       :1780][INFO    ][6968] Running state [192.168.11.0/24] at time 10:33:10.615393
2020-02-06 10:33:10,616 [salt.state       :1813][INFO    ][6968] Executing state maasng.subnet_present for [192.168.11.0/24]
2020-02-06 10:33:10,780 [salt.loaded.ext.module.maasng:945 ][INFO    ][6968] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'w8hbfh', 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-06 10:33:10,780 [salt.loaded.ext.module.maasng:1235][WARNING ][6968] Ignoring parameter vlan:0
2020-02-06 10:33:10,822 [salt.state       :300 ][INFO    ][6968] Subnet 192.168.11.0/24 has been updated for pxe_admin
2020-02-06 10:33:10,822 [salt.state       :1951][INFO    ][6968] Completed state [192.168.11.0/24] at time 10:33:10.822688 duration_in_ms=207.294
2020-02-06 10:33:10,823 [salt.state       :1780][INFO    ][6968] Running state [maas_create_iprange_1] at time 10:33:10.823493
2020-02-06 10:33:10,823 [salt.state       :1813][INFO    ][6968] Executing state maasng.iprange_present for [maas_create_iprange_1]
2020-02-06 10:33:10,854 [salt.state       :300 ][INFO    ][6968] Iprange maas_create_iprange_1 already exist.
2020-02-06 10:33:10,854 [salt.state       :1951][INFO    ][6968] Completed state [maas_create_iprange_1] at time 10:33:10.854712 duration_in_ms=31.219
2020-02-06 10:33:10,855 [salt.state       :1780][INFO    ][6968] Running state [vlan 0] at time 10:33:10.854975
2020-02-06 10:33:10,855 [salt.state       :1813][INFO    ][6968] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-02-06 10:33:10,895 [salt.loaded.ext.module.maasng:945 ][INFO    ][6968] [{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'w8hbfh', 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-06 10:33:10,966 [salt.loaded.ext.module.maasng:945 ][INFO    ][6968] [{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'w8hbfh', 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-06 10:33:11,163 [salt.loaded.ext.module.maasng:945 ][INFO    ][6968] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'w8hbfh', 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-06 10:33:11,243 [salt.state       :300 ][INFO    ][6968] {'new': 'Vlan untagged was updated'}
2020-02-06 10:33:11,244 [salt.state       :1951][INFO    ][6968] Completed state [vlan 0] at time 10:33:11.244132 duration_in_ms=389.156
2020-02-06 10:33:11,245 [salt.state       :1780][INFO    ][6968] Running state [opnfv] at time 10:33:11.245035
2020-02-06 10:33:11,245 [salt.state       :1813][INFO    ][6968] Executing state maasng.sshkey_present for [opnfv]
2020-02-06 10:33:11,282 [salt.loaded.ext.module.maasng:1903][INFO    ][6968] [{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-06 10:33:11,282 [salt.state       :300 ][INFO    ][6968] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2020-02-06 10:33:11,283 [salt.state       :1951][INFO    ][6968] Completed state [opnfv] at time 10:33:11.282991 duration_in_ms=37.956
2020-02-06 10:33:11,289 [salt.minion      :1711][INFO    ][6968] Returning information for job: 20200206103253278779
2020-02-06 10:33:12,113 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command state.apply with jid 20200206103312097113
2020-02-06 10:33:12,143 [salt.minion      :1432][INFO    ][7082] Starting a new job with PID 7082
2020-02-06 10:33:18,355 [salt.state       :915 ][INFO    ][7082] Loading fresh modules for state activity
2020-02-06 10:33:18,462 [salt.state       :1780][INFO    ][7082] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:33:18.462262
2020-02-06 10:33:18,462 [salt.state       :1813][INFO    ][7082] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-06 10:33:18,464 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7082] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-06 10:33:20,362 [salt.state       :300 ][INFO    ][7082] {'pid': 7104, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-06 10:33:20,363 [salt.state       :1951][INFO    ][7082] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:33:20.363225 duration_in_ms=1900.968
2020-02-06 10:33:20,367 [salt.state       :1780][INFO    ][7082] Running state [maas.process_machines] at time 10:33:20.367129
2020-02-06 10:33:20,367 [salt.state       :1813][INFO    ][7082] Executing state module.run for [maas.process_machines]
2020-02-06 10:33:20,368 [salt.utils.decorators:613 ][WARNING ][7082] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-06 10:33:21,072 [salt.loaded.ext.module.maas:412 ][WARNING ][7082] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-06 10:33:21,074 [salt.loaded.ext.module.maas:92  ][INFO    ][7082] 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=rfh3b6 architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-06 10:33:21,775 [salt.loaded.ext.module.maas:412 ][WARNING ][7082] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-06 10:33:21,776 [salt.loaded.ext.module.maas:92  ][INFO    ][7082] 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=ypb3yf architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-06 10:33:22,448 [salt.loaded.ext.module.maas:412 ][WARNING ][7082] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-06 10:33:22,450 [salt.loaded.ext.module.maas:92  ][INFO    ][7082] 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=x3bd8t architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-06 10:33:23,220 [salt.loaded.ext.module.maas:412 ][WARNING ][7082] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-06 10:33:23,220 [salt.loaded.ext.module.maas:92  ][INFO    ][7082] 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=8sen7s architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-06 10:33:23,972 [salt.loaded.ext.module.maas:412 ][WARNING ][7082] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-06 10:33:23,974 [salt.loaded.ext.module.maas:92  ][INFO    ][7082] 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=ryerhd architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-06 10:33:24,776 [salt.state       :300 ][INFO    ][7082] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2020-02-06 10:33:24,776 [salt.state       :1951][INFO    ][7082] Completed state [maas.process_machines] at time 10:33:24.776790 duration_in_ms=4409.66
2020-02-06 10:33:24,781 [salt.minion      :1711][INFO    ][7082] Returning information for job: 20200206103312097113
2020-02-06 10:33:58,400 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command state.apply with jid 20200206103358384352
2020-02-06 10:33:58,428 [salt.minion      :1432][INFO    ][7144] Starting a new job with PID 7144
2020-02-06 10:34:04,576 [salt.state       :915 ][INFO    ][7144] Loading fresh modules for state activity
2020-02-06 10:34:04,679 [salt.state       :1780][INFO    ][7144] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:34:04.679507
2020-02-06 10:34:04,679 [salt.state       :1813][INFO    ][7144] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-06 10:34:04,681 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7144] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-06 10:34:06,789 [salt.state       :300 ][INFO    ][7144] {'pid': 7151, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-06 10:34:06,791 [salt.state       :1951][INFO    ][7144] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:34:06.790882 duration_in_ms=2111.375
2020-02-06 10:34:06,794 [salt.state       :1780][INFO    ][7144] Running state [maas.wait_for_machine_status] at time 10:34:06.794120
2020-02-06 10:34:06,794 [salt.state       :1813][INFO    ][7144] Executing state module.run for [maas.wait_for_machine_status]
2020-02-06 10:34:06,795 [salt.utils.decorators:613 ][WARNING ][7144] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-06 10:34:10,030 [salt.state       :300 ][INFO    ][7144] {'ret': True}
2020-02-06 10:34:10,030 [salt.state       :1951][INFO    ][7144] Completed state [maas.wait_for_machine_status] at time 10:34:10.030666 duration_in_ms=3236.545
2020-02-06 10:34:10,034 [salt.minion      :1711][INFO    ][7144] Returning information for job: 20200206103358384352
2020-02-06 10:34:10,808 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command state.apply with jid 20200206103410792356
2020-02-06 10:34:10,836 [salt.minion      :1432][INFO    ][7168] Starting a new job with PID 7168
2020-02-06 10:34:11,773 [salt.state       :915 ][INFO    ][7168] Loading fresh modules for state activity
2020-02-06 10:34:11,910 [salt.state       :1780][INFO    ][7168] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:34:11.910163
2020-02-06 10:34:11,910 [salt.state       :1813][INFO    ][7168] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-06 10:34:11,912 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7168] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-06 10:34:13,792 [salt.state       :300 ][INFO    ][7168] {'pid': 7175, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-06 10:34:13,794 [salt.state       :1951][INFO    ][7168] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:34:13.794170 duration_in_ms=1884.001
2020-02-06 10:34:13,798 [salt.state       :1780][INFO    ][7168] Running state [maas_machines_storage_cmp002_lvm] at time 10:34:13.798257
2020-02-06 10:34:13,798 [salt.state       :1813][INFO    ][7168] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2020-02-06 10:34:15,075 [salt.loaded.ext.module.maasng:610 ][INFO    ][7168] rfh3b6
2020-02-06 10:34:15,075 [salt.loaded.ext.module.maasng:626 ][INFO    ][7168] sda
2020-02-06 10:34:15,690 [salt.loaded.ext.module.maasng:361 ][INFO    ][7168] rfh3b6
2020-02-06 10:34:15,774 [salt.loaded.ext.module.maasng:367 ][INFO    ][7168] [{u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'1c2df7ba-930c-4694-9968-50ebbd60d4a0', u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/1/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rfh3b6', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'906a5c2f-4a66-475e-b3b6-ea24f8c20a10', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 8, u'size': 800101236736}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'rfh3b6', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/13/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'8b43fb70-0517-46c0-b0ad-b67f83564aed', u'fstype': u'ext4'}, u'uuid': u'bdc7f922-2106-4cbf-934c-cccae7506778', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rfh3b6', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 13, u'serial': None, u'size': 800097042432}]
2020-02-06 10:34:15,775 [salt.loaded.ext.module.maasng:632 ][INFO    ][7168] vgroot
2020-02-06 10:34:15,775 [salt.loaded.ext.module.maasng:635 ][INFO    ][7168] lvroot
2020-02-06 10:34:15,776 [salt.loaded.ext.module.maasng:639 ][INFO    ][7168] 107374182400
2020-02-06 10:34:16,397 [salt.loaded.ext.module.maasng:645 ][INFO    ][7168] {u'node_type_name': u'Machine', u'hwe_kernel': None, u'swap_size': None, u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 62, 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'w8hbfh', 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'children': [], u'discovered': [], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'rfh3b6', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/interfaces/4/'}, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-18.04', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'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/rfh3b6/', u'hardware_info': {u'mainboard_firmware_version': u'I31', u'mainboard_product': u'Unknown', u'system_vendor': u'HP', u'cpu_model': u'Intel(R) Xeon(R) CPU E5-2680 v2', u'mainboard_firmware_date': u'12/20/2013', u'mainboard_vendor': u'Unknown', u'system_serial': u'USE410T2Y0', u'system_version': u'Unknown', u'system_product': u'ProLiant BL460c Gen8 (735151-B21)'}, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'rfh3b6', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/1/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'aeb80667-cbe8-446d-ac2b-8b3b085463ae', u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/1/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rfh3b6', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'f02ab804-4d52-4b98-baba-7e2fa9f90c20', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 10, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'rfh3b6', 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'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/rfh3b6/blockdevices/15/', u'available_size': 0, u'uuid': u'7b9c5d20-e5d8-494a-a1e0-3bff0b4da20c', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rfh3b6', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'fd2bd9f7-95f7-4bea-8610-feda0330bf8e', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 15, u'serial': None, u'size': 107374182400}], 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'rfh3b6', u'id': 10}], u'special_filesystems': [], u'current_commissioning_result_id': 19, u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/1/', u'system_id': u'rfh3b6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'size': 800109715456, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'aeb80667-cbe8-446d-ac2b-8b3b085463ae', u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/1/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rfh3b6', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'f02ab804-4d52-4b98-baba-7e2fa9f90c20', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 10, u'size': 800101236736}]}, u'current_testing_result_id': 20, u'cpu_test_status': -1, u'pool': {u'id': 0, u'resource_uri': u'/MAAS/api/2.0/resourcepool/0/', u'name': u'default', u'description': u'Default pool'}, u'bcaches': [], u'locked': False, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/1/', u'system_id': u'rfh3b6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'size': 800109715456, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'aeb80667-cbe8-446d-ac2b-8b3b085463ae', u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/1/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rfh3b6', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'f02ab804-4d52-4b98-baba-7e2fa9f90c20', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 10, u'size': 800101236736}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'virtualblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': u'7b9c5d20-e5d8-494a-a1e0-3bff0b4da20c', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/blockdevices/15/', u'system_id': u'rfh3b6', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'fd2bd9f7-95f7-4bea-8610-feda0330bf8e', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'size': 107374182400, u'type': u'virtual', u'id': 15, u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', 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'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 62, 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'w8hbfh', 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'children': [], u'discovered': [], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'rfh3b6', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/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'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'rfh3b6', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/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'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 63, 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'w8hbfh', 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'children': [], u'discovered': [], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'rfh3b6', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/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'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'rfh3b6', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/interfaces/13/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 64, 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'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'rfh3b6', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/interfaces/14/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f1', u'links': [{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': 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'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'rfh3b6', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/rfh3b6/interfaces/15/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2020-02-06 10:34:16,400 [salt.state       :300 ][INFO    ][7168] {'new': {'storage_layout': 'lvm'}}
2020-02-06 10:34:16,400 [salt.state       :1951][INFO    ][7168] Completed state [maas_machines_storage_cmp002_lvm] at time 10:34:16.400793 duration_in_ms=2602.535
2020-02-06 10:34:16,401 [salt.state       :1780][INFO    ][7168] Running state [maas_machines_storage_cmp001_lvm] at time 10:34:16.401558
2020-02-06 10:34:16,402 [salt.state       :1813][INFO    ][7168] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2020-02-06 10:34:17,574 [salt.loaded.ext.module.maasng:610 ][INFO    ][7168] ypb3yf
2020-02-06 10:34:17,574 [salt.loaded.ext.module.maasng:626 ][INFO    ][7168] sda
2020-02-06 10:34:18,163 [salt.loaded.ext.module.maasng:361 ][INFO    ][7168] ypb3yf
2020-02-06 10:34:18,259 [salt.loaded.ext.module.maasng:367 ][INFO    ][7168] [{u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'db2a13e1-6075-40ef-be5c-b86133dd3303', u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/2/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ypb3yf', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e5cd378c-814b-44fe-a0d3-163cd6435697', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 9, u'size': 800101236736}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ypb3yf', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/14/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'fb0bb904-624a-48a7-8188-19953a4f07c6', u'fstype': u'ext4'}, u'uuid': u'c8a22a95-4cf5-4672-9ebb-da9f14587a0f', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ypb3yf', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 14, u'serial': None, u'size': 800097042432}]
2020-02-06 10:34:18,259 [salt.loaded.ext.module.maasng:632 ][INFO    ][7168] vgroot
2020-02-06 10:34:18,259 [salt.loaded.ext.module.maasng:635 ][INFO    ][7168] lvroot
2020-02-06 10:34:18,260 [salt.loaded.ext.module.maasng:639 ][INFO    ][7168] 107374182400
2020-02-06 10:34:18,881 [salt.loaded.ext.module.maasng:645 ][INFO    ][7168] {u'hwe_kernel': None, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'memory_test_status_name': u'Unknown', u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 69, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'parents': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'effective_mtu': 1500, u'id': 5, u'system_id': u'ypb3yf', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/interfaces/5/'}, u'fqdn': u'cmp001.maas', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ypb3yf', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'4ab75554-0f81-4d43-b25f-7f2b42ab3b6a', u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/2/partition/11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ypb3yf', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'b990ee5d-00d8-4677-bbf8-7e640484624b', 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'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'4ab75554-0f81-4d43-b25f-7f2b42ab3b6a', u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/2/partition/11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ypb3yf', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'b990ee5d-00d8-4677-bbf8-7e640484624b', 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'filesystem': None, u'uuid': None, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ypb3yf', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/16/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'149c9fce-6974-4dea-8d1c-99e9bd58375e', u'fstype': u'ext4'}, u'uuid': u'afc1be35-0e74-4e71-a0e7-fcd15948b86b', u'id': 16, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ypb3yf', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'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/ypb3yf/', u'hardware_info': {u'mainboard_firmware_version': u'I31', u'mainboard_product': u'Unknown', u'system_vendor': u'HP', u'cpu_model': u'Intel(R) Xeon(R) CPU E5-2680 v2', u'mainboard_firmware_date': u'06/01/2015', u'mainboard_vendor': u'Unknown', u'system_serial': u'USE410T2XY', u'system_version': u'Unknown', u'system_product': u'ProLiant BL460c Gen8 (735151-B21)'}, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'ypb3yf', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, 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'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/ypb3yf/blockdevices/16/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 16, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'149c9fce-6974-4dea-8d1c-99e9bd58375e', u'fstype': u'ext4'}, u'uuid': u'afc1be35-0e74-4e71-a0e7-fcd15948b86b', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ypb3yf', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'partitions': []}], u'min_hwe_kernel': u'ga-18.04', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ypb3yf', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'4ab75554-0f81-4d43-b25f-7f2b42ab3b6a', u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/blockdevices/2/partition/11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ypb3yf', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'b990ee5d-00d8-4677-bbf8-7e640484624b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 11, u'size': 800101236736}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ypb3yf', 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 69, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'parents': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'effective_mtu': 1500, u'id': 5, u'system_id': u'ypb3yf', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/interfaces/5/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'effective_mtu': 1500, u'id': 16, u'system_id': u'ypb3yf', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/interfaces/16/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 70, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'w8hbfh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'parents': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'effective_mtu': 1500, u'id': 17, u'system_id': u'ypb3yf', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/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'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'effective_mtu': 1500, u'id': 18, u'system_id': u'ypb3yf', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/interfaces/18/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 71, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'effective_mtu': 1500, u'id': 19, u'system_id': u'ypb3yf', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/interfaces/19/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f1', u'links': [{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'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'effective_mtu': 1500, u'id': 20, u'system_id': u'ypb3yf', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ypb3yf/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'storage_test_status': 2, u'locked': False, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2020-02-06 10:34:18,884 [salt.state       :300 ][INFO    ][7168] {'new': {'storage_layout': 'lvm'}}
2020-02-06 10:34:18,885 [salt.state       :1951][INFO    ][7168] Completed state [maas_machines_storage_cmp001_lvm] at time 10:34:18.885248 duration_in_ms=2483.689
2020-02-06 10:34:18,889 [salt.minion      :1711][INFO    ][7168] Returning information for job: 20200206103410792356
2020-02-06 10:34:19,658 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command state.apply with jid 20200206103419640318
2020-02-06 10:34:19,684 [salt.minion      :1432][INFO    ][7194] Starting a new job with PID 7194
2020-02-06 10:34:20,659 [salt.state       :915 ][INFO    ][7194] Loading fresh modules for state activity
2020-02-06 10:34:20,756 [salt.state       :1780][INFO    ][7194] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:34:20.756546
2020-02-06 10:34:20,756 [salt.state       :1813][INFO    ][7194] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-06 10:34:20,758 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7194] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-06 10:34:22,735 [salt.state       :300 ][INFO    ][7194] {'pid': 7201, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-06 10:34:22,736 [salt.state       :1951][INFO    ][7194] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:34:22.736242 duration_in_ms=1979.695
2020-02-06 10:34:22,739 [salt.state       :1780][INFO    ][7194] Running state [maas.deploy_machines] at time 10:34:22.739565
2020-02-06 10:34:22,740 [salt.state       :1813][INFO    ][7194] Executing state module.run for [maas.deploy_machines]
2020-02-06 10:34:22,741 [salt.utils.decorators:613 ][WARNING ][7194] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-06 10:34:23,366 [salt.loaded.ext.module.maas:684 ][INFO    ][7194] deploymachines hwe_kernel=ga-18.04 system_id=rfh3b6 distro_series=bionic
2020-02-06 10:34:25,300 [salt.loaded.ext.module.maas:684 ][INFO    ][7194] deploymachines hwe_kernel=ga-18.04 system_id=ypb3yf distro_series=bionic
2020-02-06 10:34:27,194 [salt.state       :300 ][INFO    ][7194] {'ret': {'updated': ['kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': ['cmp002', 'cmp001']}}
2020-02-06 10:34:27,195 [salt.state       :1951][INFO    ][7194] Completed state [maas.deploy_machines] at time 10:34:27.195410 duration_in_ms=4455.845
2020-02-06 10:34:27,203 [salt.minion      :1711][INFO    ][7194] Returning information for job: 20200206103419640318
2020-02-06 10:34:27,972 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command state.apply with jid 20200206103427959539
2020-02-06 10:34:27,997 [salt.minion      :1432][INFO    ][7221] Starting a new job with PID 7221
2020-02-06 10:34:34,123 [salt.state       :915 ][INFO    ][7221] Loading fresh modules for state activity
2020-02-06 10:34:34,228 [salt.state       :1780][INFO    ][7221] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:34:34.228507
2020-02-06 10:34:34,228 [salt.state       :1813][INFO    ][7221] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-06 10:34:34,231 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7221] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-06 10:34:36,207 [salt.state       :300 ][INFO    ][7221] {'pid': 7233, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-06 10:34:36,208 [salt.state       :1951][INFO    ][7221] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:34:36.208113 duration_in_ms=1979.605
2020-02-06 10:34:36,212 [salt.state       :1780][INFO    ][7221] Running state [maas.wait_for_machine_status] at time 10:34:36.212317
2020-02-06 10:34:36,213 [salt.state       :1813][INFO    ][7221] Executing state module.run for [maas.wait_for_machine_status]
2020-02-06 10:34:36,214 [salt.utils.decorators:613 ][WARNING ][7221] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-06 10:34:39,446 [salt.loaded.ext.module.maas:1024][INFO    ][7221] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2246.78029013s left)
2020-02-06 10:34:43,092 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206103443071977
2020-02-06 10:34:43,119 [salt.minion      :1432][INFO    ][7255] Starting a new job with PID 7255
2020-02-06 10:34:43,141 [salt.minion      :1711][INFO    ][7255] Returning information for job: 20200206103443071977
2020-02-06 10:35:12,672 [salt.loaded.ext.module.maas:1024][INFO    ][7221] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2213.55384707s left)
2020-02-06 10:35:13,199 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206103513141169
2020-02-06 10:35:13,223 [salt.minion      :1432][INFO    ][7292] Starting a new job with PID 7292
2020-02-06 10:35:13,244 [salt.minion      :1711][INFO    ][7292] Returning information for job: 20200206103513141169
2020-02-06 10:35:43,271 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206103543256205
2020-02-06 10:35:43,297 [salt.minion      :1432][INFO    ][7318] Starting a new job with PID 7318
2020-02-06 10:35:43,319 [salt.minion      :1711][INFO    ][7318] Returning information for job: 20200206103543256205
2020-02-06 10:35:45,794 [salt.loaded.ext.module.maas:1024][INFO    ][7221] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2180.43202806s left)
2020-02-06 10:36:13,348 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206103613334230
2020-02-06 10:36:13,376 [salt.minion      :1432][INFO    ][7360] Starting a new job with PID 7360
2020-02-06 10:36:13,399 [salt.minion      :1711][INFO    ][7360] Returning information for job: 20200206103613334230
2020-02-06 10:36:18,985 [salt.loaded.ext.module.maas:1024][INFO    ][7221] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2147.24166298s left)
2020-02-06 10:36:43,447 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206103643432972
2020-02-06 10:36:43,469 [salt.minion      :1432][INFO    ][7387] Starting a new job with PID 7387
2020-02-06 10:36:43,496 [salt.minion      :1711][INFO    ][7387] Returning information for job: 20200206103643432972
2020-02-06 10:36:52,101 [salt.loaded.ext.module.maas:1024][INFO    ][7221] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2114.12567401s left)
2020-02-06 10:37:13,523 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206103713507943
2020-02-06 10:37:13,550 [salt.minion      :1432][INFO    ][7427] Starting a new job with PID 7427
2020-02-06 10:37:13,568 [salt.minion      :1711][INFO    ][7427] Returning information for job: 20200206103713507943
2020-02-06 10:37:25,336 [salt.loaded.ext.module.maas:1024][INFO    ][7221] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2080.89074802s left)
2020-02-06 10:37:43,612 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206103743598915
2020-02-06 10:37:43,641 [salt.minion      :1432][INFO    ][7485] Starting a new job with PID 7485
2020-02-06 10:37:43,661 [salt.minion      :1711][INFO    ][7485] Returning information for job: 20200206103743598915
2020-02-06 10:37:58,795 [salt.loaded.ext.module.maas:1024][INFO    ][7221] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2047.43170404s left)
2020-02-06 10:38:13,707 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206103813694786
2020-02-06 10:38:13,736 [salt.minion      :1432][INFO    ][7542] Starting a new job with PID 7542
2020-02-06 10:38:13,755 [salt.minion      :1711][INFO    ][7542] Returning information for job: 20200206103813694786
2020-02-06 10:38:32,420 [salt.loaded.ext.module.maas:1024][INFO    ][7221] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2013.80644202s left)
2020-02-06 10:38:43,799 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206103843785519
2020-02-06 10:38:43,826 [salt.minion      :1432][INFO    ][7572] Starting a new job with PID 7572
2020-02-06 10:38:43,848 [salt.minion      :1711][INFO    ][7572] Returning information for job: 20200206103843785519
2020-02-06 10:39:05,823 [salt.loaded.ext.module.maas:1024][INFO    ][7221] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1980.4034791s left)
2020-02-06 10:39:13,883 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206103913868670
2020-02-06 10:39:13,906 [salt.minion      :1432][INFO    ][7599] Starting a new job with PID 7599
2020-02-06 10:39:13,924 [salt.minion      :1711][INFO    ][7599] Returning information for job: 20200206103913868670
2020-02-06 10:39:39,289 [salt.loaded.ext.module.maas:1024][INFO    ][7221] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1946.93697214s left)
2020-02-06 10:39:43,979 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206103943963278
2020-02-06 10:39:44,003 [salt.minion      :1432][INFO    ][7627] Starting a new job with PID 7627
2020-02-06 10:39:44,024 [salt.minion      :1711][INFO    ][7627] Returning information for job: 20200206103943963278
2020-02-06 10:40:12,824 [salt.loaded.ext.module.maas:1024][INFO    ][7221] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1913.40273094s left)
2020-02-06 10:40:14,084 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206104014067529
2020-02-06 10:40:14,105 [salt.minion      :1432][INFO    ][7655] Starting a new job with PID 7655
2020-02-06 10:40:14,131 [salt.minion      :1711][INFO    ][7655] Returning information for job: 20200206104014067529
2020-02-06 10:40:44,184 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206104044173002
2020-02-06 10:40:44,208 [salt.minion      :1432][INFO    ][7682] Starting a new job with PID 7682
2020-02-06 10:40:44,231 [salt.minion      :1711][INFO    ][7682] Returning information for job: 20200206104044173002
2020-02-06 10:40:46,567 [salt.loaded.ext.module.maas:1024][INFO    ][7221] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1879.65956306s left)
2020-02-06 10:41:14,320 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206104114303051
2020-02-06 10:41:14,347 [salt.minion      :1432][INFO    ][7709] Starting a new job with PID 7709
2020-02-06 10:41:14,372 [salt.minion      :1711][INFO    ][7709] Returning information for job: 20200206104114303051
2020-02-06 10:41:19,936 [salt.loaded.ext.module.maas:1024][INFO    ][7221] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1846.29073s left)
2020-02-06 10:41:44,434 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206104144421185
2020-02-06 10:41:44,463 [salt.minion      :1432][INFO    ][7737] Starting a new job with PID 7737
2020-02-06 10:41:44,485 [salt.minion      :1711][INFO    ][7737] Returning information for job: 20200206104144421185
2020-02-06 10:41:53,387 [salt.loaded.ext.module.maas:1024][INFO    ][7221] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1812.83934808s left)
2020-02-06 10:42:14,567 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206104214552131
2020-02-06 10:42:14,594 [salt.minion      :1432][INFO    ][7763] Starting a new job with PID 7763
2020-02-06 10:42:14,622 [salt.minion      :1711][INFO    ][7763] Returning information for job: 20200206104214552131
2020-02-06 10:42:26,815 [salt.loaded.ext.module.maas:1024][INFO    ][7221] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1779.411587s left)
2020-02-06 10:42:44,704 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206104244688818
2020-02-06 10:42:44,730 [salt.minion      :1432][INFO    ][7793] Starting a new job with PID 7793
2020-02-06 10:42:44,756 [salt.minion      :1711][INFO    ][7793] Returning information for job: 20200206104244688818
2020-02-06 10:43:00,123 [salt.loaded.ext.module.maas:1024][INFO    ][7221] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1746.10286403s left)
2020-02-06 10:43:14,854 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206104314840788
2020-02-06 10:43:14,878 [salt.minion      :1432][INFO    ][7818] Starting a new job with PID 7818
2020-02-06 10:43:14,904 [salt.minion      :1711][INFO    ][7818] Returning information for job: 20200206104314840788
2020-02-06 10:43:33,491 [salt.loaded.ext.module.maas:1024][INFO    ][7221] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1712.73575306s left)
2020-02-06 10:43:44,999 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206104344984178
2020-02-06 10:43:45,026 [salt.minion      :1432][INFO    ][7847] Starting a new job with PID 7847
2020-02-06 10:43:45,056 [salt.minion      :1711][INFO    ][7847] Returning information for job: 20200206104344984178
2020-02-06 10:44:06,948 [salt.loaded.ext.module.maas:1024][INFO    ][7221] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1679.27815914s left)
2020-02-06 10:44:15,176 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206104415162748
2020-02-06 10:44:15,202 [salt.minion      :1432][INFO    ][7884] Starting a new job with PID 7884
2020-02-06 10:44:15,226 [salt.minion      :1711][INFO    ][7884] Returning information for job: 20200206104415162748
2020-02-06 10:44:40,446 [salt.loaded.ext.module.maas:1024][INFO    ][7221] Waiting status:Deployed for machines:['cmp001']
sleep for:30s Timeout:2250s (1645.77999401s left)
2020-02-06 10:44:45,400 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206104445391599
2020-02-06 10:44:45,425 [salt.minion      :1432][INFO    ][7920] Starting a new job with PID 7920
2020-02-06 10:44:45,451 [salt.minion      :1711][INFO    ][7920] Returning information for job: 20200206104445391599
2020-02-06 10:45:14,044 [salt.loaded.ext.module.maas:1024][INFO    ][7221] Waiting status:Deployed for machines:['cmp001']
sleep for:30s Timeout:2250s (1612.18221712s left)
2020-02-06 10:45:15,564 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206104515555191
2020-02-06 10:45:15,590 [salt.minion      :1432][INFO    ][7953] Starting a new job with PID 7953
2020-02-06 10:45:15,610 [salt.minion      :1711][INFO    ][7953] Returning information for job: 20200206104515555191
2020-02-06 10:45:45,647 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command saltutil.find_job with jid 20200206104545635941
2020-02-06 10:45:45,670 [salt.minion      :1432][INFO    ][7991] Starting a new job with PID 7991
2020-02-06 10:45:45,692 [salt.minion      :1711][INFO    ][7991] Returning information for job: 20200206104545635941
2020-02-06 10:45:50,169 [salt.state       :300 ][INFO    ][7221] {'ret': True}
2020-02-06 10:45:50,170 [salt.state       :1951][INFO    ][7221] Completed state [maas.wait_for_machine_status] at time 10:45:50.170059 duration_in_ms=673957.741
2020-02-06 10:45:50,173 [salt.minion      :1711][INFO    ][7221] Returning information for job: 20200206103427959539
2020-02-06 10:46:35,971 [salt.utils.schedule:1377][INFO    ][3358] Running scheduled job: __mine_interval
2020-02-06 11:46:35,971 [salt.utils.schedule:1377][INFO    ][3358] Running scheduled job: __mine_interval
2020-02-06 12:29:59,701 [salt.minion      :1308][INFO    ][3358] User sudo_ubuntu Executing command cp.push_dir with jid 20200206122959692166
2020-02-06 12:29:59,733 [salt.minion      :1432][INFO    ][12912] Starting a new job with PID 12912
