2020-02-22 00:21:20,199 [salt.utils.decorators:613 ][WARNING ][1503] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 00:21:21,139 [salt.utils.decorators:613 ][WARNING ][1503] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 00:21:30,291 [salt.loaded.int.states.file:2298][WARNING ][1682] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2020-02-22 00:21:37,558 [salt.state       :2022][WARNING ][1888] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-02-22 00:21:38,256 [salt.utils.decorators:613 ][WARNING ][1888] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 00:21:49,290 [salt.utils.decorators:613 ][WARNING ][1888] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 00:22:07,362 [salt.utils.decorators:613 ][WARNING ][1888] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 00:22:08,409 [salt.utils.decorators:613 ][WARNING ][1888] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 00:22:10,692 [salt.loaded.ext.module.maasng:1008][WARNING ][1888] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2020-02-22 00:22:10,694 [salt.loaded.ext.module.maasng:1011][WARNING ][1888] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2020-02-22 00:22:11,230 [salt.loaded.ext.module.maasng:1235][WARNING ][1888] Ignoring parameter vlan:0
2020-02-22 00:22:14,615 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3015] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2020-02-22 00:22:14,654 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3015] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2020-02-22 00:22:14,697 [salt.utils.parsers:1051][WARNING ][154] Minion received a SIGTERM. Exiting.
2020-02-22 00:22:15,761 [salt.cli.daemons :293 ][INFO    ][3088] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2020-02-22 00:22:15,947 [salt.cli.daemons :82  ][INFO    ][3088] Starting up the Salt Minion
2020-02-22 00:22:15,947 [salt.utils.event :1017][INFO    ][3088] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2020-02-22 00:22:17,175 [salt.minion      :976 ][INFO    ][3088] Creating minion process manager
2020-02-22 00:22:19,010 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3088] Executing command ['date', '+%z'] in directory '/root'
2020-02-22 00:22:19,035 [salt.utils.schedule:568 ][INFO    ][3088] Updating job settings for scheduled job: __mine_interval
2020-02-22 00:22:19,037 [salt.minion      :1108][INFO    ][3088] Added mine.update to scheduler
2020-02-22 00:22:19,042 [salt.minion      :1975][INFO    ][3088] Minion is starting as user 'root'
2020-02-22 00:22:19,061 [salt.minion      :2336][INFO    ][3088] Minion is ready to receive requests!
2020-02-22 00:22:21,496 [salt.utils.decorators:613 ][WARNING ][3031] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 00:22:21,537 [salt.loaded.ext.module.maas:412 ][WARNING ][3031] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-22 00:22:22,373 [salt.loaded.ext.module.maas:412 ][WARNING ][3031] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-22 00:22:23,301 [salt.loaded.ext.module.maas:412 ][WARNING ][3031] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-22 00:22:24,143 [salt.loaded.ext.module.maas:412 ][WARNING ][3031] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-22 00:22:24,979 [salt.loaded.ext.module.maas:412 ][WARNING ][3031] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-22 00:22:57,311 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command state.apply with jid 20200222002257297174
2020-02-22 00:22:57,339 [salt.minion      :1432][INFO    ][3250] Starting a new job with PID 3250
2020-02-22 00:23:03,834 [salt.state       :915 ][INFO    ][3250] Loading fresh modules for state activity
2020-02-22 00:23:03,895 [salt.fileclient  :1219][INFO    ][3250] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2020-02-22 00:23:03,951 [salt.state       :1780][INFO    ][3250] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:23:03.951461
2020-02-22 00:23:03,951 [salt.state       :1813][INFO    ][3250] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-22 00:23:03,955 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3250] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-22 00:23:05,994 [salt.state       :300 ][INFO    ][3250] {'pid': 3264, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-22 00:23:05,995 [salt.state       :1951][INFO    ][3250] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:23:05.994936 duration_in_ms=2043.475
2020-02-22 00:23:05,997 [salt.state       :1780][INFO    ][3250] Running state [maas.wait_for_machine_status] at time 00:23:05.997039
2020-02-22 00:23:05,997 [salt.state       :1813][INFO    ][3250] Executing state module.run for [maas.wait_for_machine_status]
2020-02-22 00:23:05,997 [salt.utils.decorators:613 ][WARNING ][3250] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 00:23:06,955 [salt.loaded.ext.module.maas:1024][INFO    ][3250] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.05106688s left)
2020-02-22 00:23:12,367 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222002312351655
2020-02-22 00:23:12,394 [salt.minion      :1432][INFO    ][3279] Starting a new job with PID 3279
2020-02-22 00:23:12,417 [salt.minion      :1711][INFO    ][3279] Returning information for job: 20200222002312351655
2020-02-22 00:23:37,978 [salt.loaded.ext.module.maas:1024][INFO    ][3250] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.0279758s left)
2020-02-22 00:23:42,453 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222002342430498
2020-02-22 00:23:42,477 [salt.minion      :1432][INFO    ][3306] Starting a new job with PID 3306
2020-02-22 00:23:42,502 [salt.minion      :1711][INFO    ][3306] Returning information for job: 20200222002342430498
2020-02-22 00:24:09,071 [salt.loaded.ext.module.maas:1024][INFO    ][3250] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.93472481s left)
2020-02-22 00:24:12,520 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222002412505077
2020-02-22 00:24:12,547 [salt.minion      :1432][INFO    ][3331] Starting a new job with PID 3331
2020-02-22 00:24:12,567 [salt.minion      :1711][INFO    ][3331] Returning information for job: 20200222002412505077
2020-02-22 00:24:39,987 [salt.loaded.ext.module.maas:1024][INFO    ][3250] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.01886892s left)
2020-02-22 00:24:42,583 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222002442571391
2020-02-22 00:24:42,605 [salt.minion      :1432][INFO    ][3359] Starting a new job with PID 3359
2020-02-22 00:24:42,627 [salt.minion      :1711][INFO    ][3359] Returning information for job: 20200222002442571391
2020-02-22 00:25:11,173 [salt.loaded.ext.module.maas:1024][INFO    ][3250] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.83441186s left)
2020-02-22 00:25:12,654 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222002512638890
2020-02-22 00:25:12,677 [salt.minion      :1432][INFO    ][3396] Starting a new job with PID 3396
2020-02-22 00:25:12,699 [salt.minion      :1711][INFO    ][3396] Returning information for job: 20200222002512638890
2020-02-22 00:25:42,731 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222002542717123
2020-02-22 00:25:42,753 [salt.minion      :1432][INFO    ][3455] Starting a new job with PID 3455
2020-02-22 00:25:42,762 [salt.loaded.ext.module.maas:1024][INFO    ][3250] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.24382877s left)
2020-02-22 00:25:42,775 [salt.minion      :1711][INFO    ][3455] Returning information for job: 20200222002542717123
2020-02-22 00:26:12,822 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222002612812017
2020-02-22 00:26:12,843 [salt.minion      :1432][INFO    ][3492] Starting a new job with PID 3492
2020-02-22 00:26:12,866 [salt.minion      :1711][INFO    ][3492] Returning information for job: 20200222002612812017
2020-02-22 00:26:14,183 [salt.loaded.ext.module.maas:1024][INFO    ][3250] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1311.82335591s left)
2020-02-22 00:26:42,906 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222002642893827
2020-02-22 00:26:42,928 [salt.minion      :1432][INFO    ][3551] Starting a new job with PID 3551
2020-02-22 00:26:42,947 [salt.minion      :1711][INFO    ][3551] Returning information for job: 20200222002642893827
2020-02-22 00:26:45,704 [salt.loaded.ext.module.maas:1024][INFO    ][3250] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1280.30250382s left)
2020-02-22 00:27:12,984 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222002712970315
2020-02-22 00:27:13,014 [salt.minion      :1432][INFO    ][3625] Starting a new job with PID 3625
2020-02-22 00:27:13,036 [salt.minion      :1711][INFO    ][3625] Returning information for job: 20200222002712970315
2020-02-22 00:27:17,726 [salt.loaded.ext.module.maas:1024][INFO    ][3250] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1248.28043199s left)
2020-02-22 00:27:43,110 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222002743098239
2020-02-22 00:27:43,131 [salt.minion      :1432][INFO    ][3680] Starting a new job with PID 3680
2020-02-22 00:27:43,151 [salt.minion      :1711][INFO    ][3680] Returning information for job: 20200222002743098239
2020-02-22 00:27:50,157 [salt.loaded.ext.module.maas:1024][INFO    ][3250] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1215.849401s left)
2020-02-22 00:28:13,246 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222002813231069
2020-02-22 00:28:13,275 [salt.minion      :1432][INFO    ][3731] Starting a new job with PID 3731
2020-02-22 00:28:13,296 [salt.minion      :1711][INFO    ][3731] Returning information for job: 20200222002813231069
2020-02-22 00:28:22,762 [salt.loaded.ext.module.maas:1024][INFO    ][3250] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1183.24412799s left)
2020-02-22 00:28:43,359 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222002843349505
2020-02-22 00:28:43,382 [salt.minion      :1432][INFO    ][3764] Starting a new job with PID 3764
2020-02-22 00:28:43,401 [salt.minion      :1711][INFO    ][3764] Returning information for job: 20200222002843349505
2020-02-22 00:28:55,230 [salt.loaded.ext.module.maas:1024][INFO    ][3250] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1150.7763648s left)
2020-02-22 00:29:13,463 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222002913451175
2020-02-22 00:29:13,491 [salt.minion      :1432][INFO    ][3802] Starting a new job with PID 3802
2020-02-22 00:29:13,515 [salt.minion      :1711][INFO    ][3802] Returning information for job: 20200222002913451175
2020-02-22 00:29:28,526 [salt.loaded.ext.module.maas:1024][INFO    ][3250] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1117.48008299s left)
2020-02-22 00:29:43,578 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222002943566981
2020-02-22 00:29:43,594 [salt.minion      :1432][INFO    ][3865] Starting a new job with PID 3865
2020-02-22 00:29:43,616 [salt.minion      :1711][INFO    ][3865] Returning information for job: 20200222002943566981
2020-02-22 00:30:01,955 [salt.state       :300 ][INFO    ][3250] {'ret': True}
2020-02-22 00:30:01,956 [salt.state       :1951][INFO    ][3250] Completed state [maas.wait_for_machine_status] at time 00:30:01.956000 duration_in_ms=415958.958
2020-02-22 00:30:01,961 [salt.minion      :1711][INFO    ][3250] Returning information for job: 20200222002257297174
2020-02-22 00:30:02,755 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command state.apply with jid 20200222003002742255
2020-02-22 00:30:02,779 [salt.minion      :1432][INFO    ][3891] Starting a new job with PID 3891
2020-02-22 00:30:09,138 [salt.state       :915 ][INFO    ][3891] Loading fresh modules for state activity
2020-02-22 00:30:09,197 [salt.fileclient  :1219][INFO    ][3891] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2020-02-22 00:30:09,299 [salt.state       :1780][INFO    ][3891] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:30:09.299017
2020-02-22 00:30:09,299 [salt.state       :1813][INFO    ][3891] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-22 00:30:09,301 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3891] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-22 00:30:11,410 [salt.state       :300 ][INFO    ][3891] {'pid': 3902, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-22 00:30:11,412 [salt.state       :1951][INFO    ][3891] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:30:11.411930 duration_in_ms=2112.908
2020-02-22 00:30:11,417 [salt.state       :1780][INFO    ][3891] Running state [maas_machines_storage_cmp002_lvm] at time 00:30:11.417180
2020-02-22 00:30:11,417 [salt.state       :1813][INFO    ][3891] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2020-02-22 00:30:12,752 [salt.loaded.ext.module.maasng:610 ][INFO    ][3891] stg4ws
2020-02-22 00:30:12,753 [salt.loaded.ext.module.maasng:626 ][INFO    ][3891] sda
2020-02-22 00:30:13,409 [salt.loaded.ext.module.maasng:361 ][INFO    ][3891] stg4ws
2020-02-22 00:30:13,502 [salt.loaded.ext.module.maasng:367 ][INFO    ][3891] [{u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/1/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'4b42d7a0-d6c7-4f18-bc09-0a6c5f3e4ab2', u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'stg4ws', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'9e5edcc3-b60d-4e40-bb90-322f3fa20bd1', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'stg4ws', 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'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/3/', u'available_size': 0, u'uuid': u'059464e8-312e-4634-b622-cd41575fe23e', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'stg4ws', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'da1ef65d-6466-4bd4-91ef-d5c96d8d8b62', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 3, u'serial': None, u'size': 800097042432}]
2020-02-22 00:30:13,502 [salt.loaded.ext.module.maasng:632 ][INFO    ][3891] vgroot
2020-02-22 00:30:13,502 [salt.loaded.ext.module.maasng:635 ][INFO    ][3891] lvroot
2020-02-22 00:30:13,503 [salt.loaded.ext.module.maasng:639 ][INFO    ][3891] 107374182400
2020-02-22 00:30:14,146 [salt.loaded.ext.module.maasng:645 ][INFO    ][3891] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': None, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'b441dd42-92eb-4a47-b151-ed47aaeed202', u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'stg4ws', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'18d5d2a2-fdb8-4b62-b012-c9b0e04cb915', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'name': u'sda', u'system_id': u'stg4ws', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/11/', u'uuid': u'50c73b3c-3ec5-47b2-9bf0-2e25524a2567', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'a241e1bf-f4d0-4698-9005-6a61708f8dbf', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'stg4ws', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, 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/stg4ws/', 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'address_ttl': None, u'system_id': u'stg4ws', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'min_hwe_kernel': u'ga-18.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'b441dd42-92eb-4a47-b151-ed47aaeed202', u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'stg4ws', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'18d5d2a2-fdb8-4b62-b012-c9b0e04cb915', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'stg4ws', 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'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'id': 1, 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'volume_groups': [{u'__incomplete__': True, u'system_id': u'stg4ws', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'b441dd42-92eb-4a47-b151-ed47aaeed202', u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'stg4ws', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'18d5d2a2-fdb8-4b62-b012-c9b0e04cb915', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'stg4ws', 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'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}, u'interface_set': [{u'children': [], u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'system_id': u'stg4ws', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/interfaces/4/'}, {u'children': [], u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'effective_mtu': 1500, u'system_id': u'stg4ws', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/interfaces/11/'}, {u'children': [], u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'effective_mtu': 1500, u'system_id': u'stg4ws', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/interfaces/12/'}, {u'children': [], u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'effective_mtu': 1500, u'system_id': u'stg4ws', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/interfaces/13/'}, {u'children': [], u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'effective_mtu': 1500, u'system_id': u'stg4ws', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/interfaces/14/'}, {u'children': [], u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'effective_mtu': 1500, u'system_id': u'stg4ws', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/interfaces/15/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'pool': {u'id': 0, u'resource_uri': u'/MAAS/api/2.0/resourcepool/0/', u'name': u'default', u'description': u'Default pool'}, u'storage_test_status': 2, u'locked': False, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/11/', u'uuid': u'50c73b3c-3ec5-47b2-9bf0-2e25524a2567', 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'a241e1bf-f4d0-4698-9005-6a61708f8dbf', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'stg4ws', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'name': u'vgroot-lvroot'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'children': [], u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'system_id': u'stg4ws', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/interfaces/4/'}, u'current_commissioning_result_id': 2, u'commissioning_status_name': u'Passed', u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2020-02-22 00:30:14,149 [salt.state       :300 ][INFO    ][3891] {'new': {'storage_layout': 'lvm'}}
2020-02-22 00:30:14,149 [salt.state       :1951][INFO    ][3891] Completed state [maas_machines_storage_cmp002_lvm] at time 00:30:14.149699 duration_in_ms=2732.519
2020-02-22 00:30:14,150 [salt.state       :1780][INFO    ][3891] Running state [maas_machines_storage_cmp001_lvm] at time 00:30:14.150365
2020-02-22 00:30:14,150 [salt.state       :1813][INFO    ][3891] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2020-02-22 00:30:15,609 [salt.loaded.ext.module.maasng:610 ][INFO    ][3891] k7e64f
2020-02-22 00:30:15,610 [salt.loaded.ext.module.maasng:626 ][INFO    ][3891] sda
2020-02-22 00:30:16,281 [salt.loaded.ext.module.maasng:361 ][INFO    ][3891] k7e64f
2020-02-22 00:30:16,366 [salt.loaded.ext.module.maasng:367 ][INFO    ][3891] [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'265bba3d-73b7-4806-a16b-d11072e70d0d', u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'k7e64f', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0edecdb6-5a2b-4e8d-aa6d-b4cd134d543f', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'device_id': 2}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'k7e64f', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/blockdevices/4/', u'uuid': u'0b3a632c-e6dd-4ab0-8a52-237c213295a3', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'k7e64f', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'8bbf7c7b-a8ea-43b7-9dc2-3d58c6699d80', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 4, u'size': 800097042432}]
2020-02-22 00:30:16,366 [salt.loaded.ext.module.maasng:632 ][INFO    ][3891] vgroot
2020-02-22 00:30:16,366 [salt.loaded.ext.module.maasng:635 ][INFO    ][3891] lvroot
2020-02-22 00:30:16,366 [salt.loaded.ext.module.maasng:639 ][INFO    ][3891] 107374182400
2020-02-22 00:30:17,016 [salt.loaded.ext.module.maasng:645 ][INFO    ][3891] {u'node_type_name': u'Machine', u'hwe_kernel': None, u'testing_status_name': u'Passed', 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'status_action': u'', u'tag_names': [], u'swap_size': None, u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'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/k7e64f/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'k7e64f', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'firmware_version': u'5.22', u'size': 800109715456, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'3974773d-206b-43f1-b6bb-a65a834b22ab', u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'k7e64f', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'32ae46f2-602e-4703-9567-8a5201f57c47', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}]}, u'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/k7e64f/', 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'k7e64f', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'uuid': u'25f0165e-100c-4fdf-8f87-366917f4ebdc', 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/k7e64f/blockdevices/12/', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'k7e64f', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'a9165583-862d-44fe-aaf1-e26f9bdbdcd0', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 12, u'size': 107374182400}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'3974773d-206b-43f1-b6bb-a65a834b22ab', u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'k7e64f', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'32ae46f2-602e-4703-9567-8a5201f57c47', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'k7e64f', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/blockdevices/12/', u'uuid': u'25f0165e-100c-4fdf-8f87-366917f4ebdc', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'k7e64f', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'a9165583-862d-44fe-aaf1-e26f9bdbdcd0', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'k7e64f', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 28, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'k7e64f', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/interfaces/5/'}, u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 28, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'k7e64f', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/interfaces/5/'}, {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'children': [], u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'k7e64f', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/interfaces/16/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 29, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.44'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'k7e64f', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/interfaces/17/'}, {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'children': [], u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'k7e64f', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/interfaces/18/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'fabric': u'fabric-0', 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'name': u'untagged'}, u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 30, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'k7e64f', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/interfaces/19/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'fabric': u'fabric-0', 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'name': u'untagged'}, u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 31, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'k7e64f', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/interfaces/20/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'pool': {u'id': 0, u'resource_uri': u'/MAAS/api/2.0/resourcepool/0/', u'name': u'default', u'description': u'Default pool'}, u'architecture': u'amd64/generic', u'bcaches': [], u'locked': False, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'k7e64f', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'firmware_version': u'5.22', u'size': 800109715456, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'3974773d-206b-43f1-b6bb-a65a834b22ab', u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'k7e64f', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'32ae46f2-602e-4703-9567-8a5201f57c47', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 4, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2020-02-22 00:30:17,018 [salt.state       :300 ][INFO    ][3891] {'new': {'storage_layout': 'lvm'}}
2020-02-22 00:30:17,018 [salt.state       :1951][INFO    ][3891] Completed state [maas_machines_storage_cmp001_lvm] at time 00:30:17.018841 duration_in_ms=2868.475
2020-02-22 00:30:17,022 [salt.minion      :1711][INFO    ][3891] Returning information for job: 20200222003002742255
2020-02-22 00:30:17,877 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command state.apply with jid 20200222003017862607
2020-02-22 00:30:17,903 [salt.minion      :1432][INFO    ][3919] Starting a new job with PID 3919
2020-02-22 00:30:18,962 [salt.state       :915 ][INFO    ][3919] Loading fresh modules for state activity
2020-02-22 00:30:19,023 [salt.fileclient  :1219][INFO    ][3919] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2020-02-22 00:30:19,070 [salt.state       :1780][INFO    ][3919] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:30:19.070373
2020-02-22 00:30:19,070 [salt.state       :1813][INFO    ][3919] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-22 00:30:19,074 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3919] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-22 00:30:21,302 [salt.state       :300 ][INFO    ][3919] {'pid': 3926, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-22 00:30:21,304 [salt.state       :1951][INFO    ][3919] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:30:21.304079 duration_in_ms=2233.705
2020-02-22 00:30:21,307 [salt.state       :1780][INFO    ][3919] Running state [maas.deploy_machines] at time 00:30:21.307755
2020-02-22 00:30:21,308 [salt.state       :1813][INFO    ][3919] Executing state module.run for [maas.deploy_machines]
2020-02-22 00:30:21,309 [salt.utils.decorators:613 ][WARNING ][3919] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 00:30:21,977 [salt.loaded.ext.module.maas:684 ][INFO    ][3919] deploymachines hwe_kernel=ga-18.04 system_id=stg4ws distro_series=bionic
2020-02-22 00:30:23,995 [salt.loaded.ext.module.maas:684 ][INFO    ][3919] deploymachines hwe_kernel=ga-18.04 system_id=k7e64f distro_series=bionic
2020-02-22 00:30:25,989 [salt.loaded.ext.module.maas:684 ][INFO    ][3919] deploymachines hwe_kernel=ga-18.04 system_id=6dwcrb distro_series=bionic
2020-02-22 00:30:27,946 [salt.loaded.ext.module.maas:684 ][INFO    ][3919] deploymachines hwe_kernel=ga-18.04 system_id=67s83w distro_series=bionic
2020-02-22 00:30:29,924 [salt.loaded.ext.module.maas:684 ][INFO    ][3919] deploymachines hwe_kernel=ga-18.04 system_id=wcfdpt distro_series=bionic
2020-02-22 00:30:31,902 [salt.state       :300 ][INFO    ][3919] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2020-02-22 00:30:31,902 [salt.state       :1951][INFO    ][3919] Completed state [maas.deploy_machines] at time 00:30:31.902726 duration_in_ms=10594.97
2020-02-22 00:30:31,906 [salt.minion      :1711][INFO    ][3919] Returning information for job: 20200222003017862607
2020-02-22 00:30:32,745 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command state.apply with jid 20200222003032731665
2020-02-22 00:30:32,768 [salt.minion      :1432][INFO    ][3973] Starting a new job with PID 3973
2020-02-22 00:30:39,164 [salt.state       :915 ][INFO    ][3973] Loading fresh modules for state activity
2020-02-22 00:30:39,226 [salt.fileclient  :1219][INFO    ][3973] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2020-02-22 00:30:39,271 [salt.state       :1780][INFO    ][3973] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:30:39.271710
2020-02-22 00:30:39,272 [salt.state       :1813][INFO    ][3973] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-22 00:30:39,274 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3973] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-22 00:30:41,446 [salt.state       :300 ][INFO    ][3973] {'pid': 3988, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-22 00:30:41,447 [salt.state       :1951][INFO    ][3973] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:30:41.447011 duration_in_ms=2175.302
2020-02-22 00:30:41,450 [salt.state       :1780][INFO    ][3973] Running state [maas.wait_for_machine_status] at time 00:30:41.450411
2020-02-22 00:30:41,450 [salt.state       :1813][INFO    ][3973] Executing state module.run for [maas.wait_for_machine_status]
2020-02-22 00:30:41,451 [salt.utils.decorators:613 ][WARNING ][3973] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 00:30:44,783 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.67917395s left)
2020-02-22 00:30:47,799 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222003047784051
2020-02-22 00:30:47,827 [salt.minion      :1432][INFO    ][4020] Starting a new job with PID 4020
2020-02-22 00:30:47,849 [salt.minion      :1711][INFO    ][4020] Returning information for job: 20200222003047784051
2020-02-22 00:31:17,867 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222003117857709
2020-02-22 00:31:17,888 [salt.minion      :1432][INFO    ][4038] Starting a new job with PID 4038
2020-02-22 00:31:17,910 [salt.minion      :1711][INFO    ][4038] Returning information for job: 20200222003117857709
2020-02-22 00:31:18,161 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.30092287s left)
2020-02-22 00:31:47,946 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222003147934630
2020-02-22 00:31:47,973 [salt.minion      :1432][INFO    ][4083] Starting a new job with PID 4083
2020-02-22 00:31:47,995 [salt.minion      :1711][INFO    ][4083] Returning information for job: 20200222003147934630
2020-02-22 00:31:51,598 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.86426497s left)
2020-02-22 00:32:18,019 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222003218010455
2020-02-22 00:32:18,045 [salt.minion      :1432][INFO    ][4108] Starting a new job with PID 4108
2020-02-22 00:32:18,067 [salt.minion      :1711][INFO    ][4108] Returning information for job: 20200222003218010455
2020-02-22 00:32:25,253 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.20880294s left)
2020-02-22 00:32:48,091 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222003248080025
2020-02-22 00:32:48,117 [salt.minion      :1432][INFO    ][4146] Starting a new job with PID 4146
2020-02-22 00:32:48,139 [salt.minion      :1711][INFO    ][4146] Returning information for job: 20200222003248080025
2020-02-22 00:32:58,913 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.54891896s left)
2020-02-22 00:33:18,165 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222003318153393
2020-02-22 00:33:18,191 [salt.minion      :1432][INFO    ][4178] Starting a new job with PID 4178
2020-02-22 00:33:18,215 [salt.minion      :1711][INFO    ][4178] Returning information for job: 20200222003318153393
2020-02-22 00:33:32,275 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.18681097s left)
2020-02-22 00:33:48,250 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222003348237495
2020-02-22 00:33:48,279 [salt.minion      :1432][INFO    ][4242] Starting a new job with PID 4242
2020-02-22 00:33:48,298 [salt.minion      :1711][INFO    ][4242] Returning information for job: 20200222003348237495
2020-02-22 00:34:05,732 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2045.72960496s left)
2020-02-22 00:34:18,355 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222003418341564
2020-02-22 00:34:18,379 [salt.minion      :1432][INFO    ][4288] Starting a new job with PID 4288
2020-02-22 00:34:18,402 [salt.minion      :1711][INFO    ][4288] Returning information for job: 20200222003418341564
2020-02-22 00:34:36,462 [salt.loaded.ext.module.maas:993 ][INFO    ][3973] Machine stg4ws mark broken
2020-02-22 00:34:48,448 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222003448433678
2020-02-22 00:34:48,471 [salt.minion      :1432][INFO    ][4354] Starting a new job with PID 4354
2020-02-22 00:34:48,490 [salt.minion      :1711][INFO    ][4354] Returning information for job: 20200222003448433678
2020-02-22 00:35:07,105 [salt.loaded.ext.module.maas:997 ][INFO    ][3973] Machine stg4ws mark fixed
2020-02-22 00:35:08,258 [salt.loaded.ext.module.maas:684 ][INFO    ][3973] deploymachines hwe_kernel=ga-18.04 system_id=stg4ws distro_series=bionic
2020-02-22 00:35:10,780 [salt.loaded.ext.module.maas:993 ][INFO    ][3973] Machine k7e64f mark broken
2020-02-22 00:35:18,502 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222003518494738
2020-02-22 00:35:18,520 [salt.minion      :1432][INFO    ][4401] Starting a new job with PID 4401
2020-02-22 00:35:18,539 [salt.minion      :1711][INFO    ][4401] Returning information for job: 20200222003518494738
2020-02-22 00:35:41,361 [salt.loaded.ext.module.maas:997 ][INFO    ][3973] Machine k7e64f mark fixed
2020-02-22 00:35:43,090 [salt.loaded.ext.module.maas:684 ][INFO    ][3973] deploymachines hwe_kernel=ga-18.04 system_id=k7e64f distro_series=bionic
2020-02-22 00:35:47,073 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1944.38877296s left)
2020-02-22 00:35:48,606 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222003548591350
2020-02-22 00:35:48,640 [salt.minion      :1432][INFO    ][4498] Starting a new job with PID 4498
2020-02-22 00:35:48,669 [salt.minion      :1711][INFO    ][4498] Returning information for job: 20200222003548591350
2020-02-22 00:36:18,737 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222003618723817
2020-02-22 00:36:18,761 [salt.minion      :1432][INFO    ][4529] Starting a new job with PID 4529
2020-02-22 00:36:18,785 [salt.minion      :1711][INFO    ][4529] Returning information for job: 20200222003618723817
2020-02-22 00:36:20,487 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1910.97548509s left)
2020-02-22 00:36:48,846 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222003648830765
2020-02-22 00:36:48,863 [salt.minion      :1432][INFO    ][4564] Starting a new job with PID 4564
2020-02-22 00:36:48,890 [salt.minion      :1711][INFO    ][4564] Returning information for job: 20200222003648830765
2020-02-22 00:36:54,023 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1877.43937993s left)
2020-02-22 00:37:18,946 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222003718933503
2020-02-22 00:37:18,976 [salt.minion      :1432][INFO    ][4586] Starting a new job with PID 4586
2020-02-22 00:37:19,009 [salt.minion      :1711][INFO    ][4586] Returning information for job: 20200222003718933503
2020-02-22 00:37:27,302 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1844.16004705s left)
2020-02-22 00:37:49,063 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222003749051718
2020-02-22 00:37:49,087 [salt.minion      :1432][INFO    ][4627] Starting a new job with PID 4627
2020-02-22 00:37:49,109 [salt.minion      :1711][INFO    ][4627] Returning information for job: 20200222003749051718
2020-02-22 00:38:00,749 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1810.71318293s left)
2020-02-22 00:38:19,189 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222003819179076
2020-02-22 00:38:19,208 [salt.minion      :1432][INFO    ][4673] Starting a new job with PID 4673
2020-02-22 00:38:19,230 [salt.minion      :1711][INFO    ][4673] Returning information for job: 20200222003819179076
2020-02-22 00:38:34,154 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1777.30848694s left)
2020-02-22 00:38:49,299 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222003849290097
2020-02-22 00:38:49,318 [salt.minion      :1432][INFO    ][4716] Starting a new job with PID 4716
2020-02-22 00:38:49,344 [salt.minion      :1711][INFO    ][4716] Returning information for job: 20200222003849290097
2020-02-22 00:39:04,838 [salt.loaded.ext.module.maas:993 ][INFO    ][3973] Machine stg4ws mark broken
2020-02-22 00:39:19,425 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222003919415214
2020-02-22 00:39:19,443 [salt.minion      :1432][INFO    ][4755] Starting a new job with PID 4755
2020-02-22 00:39:19,462 [salt.minion      :1711][INFO    ][4755] Returning information for job: 20200222003919415214
2020-02-22 00:39:35,492 [salt.loaded.ext.module.maas:997 ][INFO    ][3973] Machine stg4ws mark fixed
2020-02-22 00:39:35,995 [salt.loaded.ext.module.maas:1001][INFO    ][3973] Machine stg4ws fio test
2020-02-22 00:39:40,209 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1711.25328207s left)
2020-02-22 00:39:49,538 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222003949529716
2020-02-22 00:39:49,558 [salt.minion      :1432][INFO    ][4806] Starting a new job with PID 4806
2020-02-22 00:39:49,582 [salt.minion      :1711][INFO    ][4806] Returning information for job: 20200222003949529716
2020-02-22 00:40:13,630 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1677.83223891s left)
2020-02-22 00:40:19,671 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222004019659274
2020-02-22 00:40:19,692 [salt.minion      :1432][INFO    ][4842] Starting a new job with PID 4842
2020-02-22 00:40:19,715 [salt.minion      :1711][INFO    ][4842] Returning information for job: 20200222004019659274
2020-02-22 00:40:44,907 [salt.loaded.ext.module.maas:993 ][INFO    ][3973] Machine k7e64f mark broken
2020-02-22 00:40:49,830 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222004049814805
2020-02-22 00:40:49,851 [salt.minion      :1432][INFO    ][4884] Starting a new job with PID 4884
2020-02-22 00:40:49,871 [salt.minion      :1711][INFO    ][4884] Returning information for job: 20200222004049814805
2020-02-22 00:41:15,532 [salt.loaded.ext.module.maas:997 ][INFO    ][3973] Machine k7e64f mark fixed
2020-02-22 00:41:16,031 [salt.loaded.ext.module.maas:1001][INFO    ][3973] Machine k7e64f fio test
2020-02-22 00:41:19,113 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1612.34909701s left)
2020-02-22 00:41:19,982 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222004119964334
2020-02-22 00:41:20,001 [salt.minion      :1432][INFO    ][4908] Starting a new job with PID 4908
2020-02-22 00:41:20,022 [salt.minion      :1711][INFO    ][4908] Returning information for job: 20200222004119964334
2020-02-22 00:41:50,130 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222004150119422
2020-02-22 00:41:50,147 [salt.minion      :1432][INFO    ][4958] Starting a new job with PID 4958
2020-02-22 00:41:50,168 [salt.minion      :1711][INFO    ][4958] Returning information for job: 20200222004150119422
2020-02-22 00:41:52,118 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1579.34443092s left)
2020-02-22 00:42:20,287 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222004220277412
2020-02-22 00:42:20,303 [salt.minion      :1432][INFO    ][4991] Starting a new job with PID 4991
2020-02-22 00:42:20,325 [salt.minion      :1711][INFO    ][4991] Returning information for job: 20200222004220277412
2020-02-22 00:42:24,966 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1546.49632788s left)
2020-02-22 00:42:50,459 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222004250451540
2020-02-22 00:42:50,477 [salt.minion      :1432][INFO    ][5039] Starting a new job with PID 5039
2020-02-22 00:42:50,497 [salt.minion      :1711][INFO    ][5039] Returning information for job: 20200222004250451540
2020-02-22 00:42:58,039 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1513.42299104s left)
2020-02-22 00:43:20,623 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222004320614798
2020-02-22 00:43:20,646 [salt.minion      :1432][INFO    ][5060] Starting a new job with PID 5060
2020-02-22 00:43:20,667 [salt.minion      :1711][INFO    ][5060] Returning information for job: 20200222004320614798
2020-02-22 00:43:31,094 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1480.36818194s left)
2020-02-22 00:43:50,802 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222004350788053
2020-02-22 00:43:50,829 [salt.minion      :1432][INFO    ][5111] Starting a new job with PID 5111
2020-02-22 00:43:50,851 [salt.minion      :1711][INFO    ][5111] Returning information for job: 20200222004350788053
2020-02-22 00:44:04,121 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1447.34124994s left)
2020-02-22 00:44:20,957 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222004420945259
2020-02-22 00:44:20,974 [salt.minion      :1432][INFO    ][5160] Starting a new job with PID 5160
2020-02-22 00:44:20,995 [salt.minion      :1711][INFO    ][5160] Returning information for job: 20200222004420945259
2020-02-22 00:44:37,384 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1414.07844806s left)
2020-02-22 00:44:51,055 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222004451044030
2020-02-22 00:44:51,072 [salt.minion      :1432][INFO    ][5243] Starting a new job with PID 5243
2020-02-22 00:44:51,100 [salt.minion      :1711][INFO    ][5243] Returning information for job: 20200222004451044030
2020-02-22 00:45:10,509 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1380.952847s left)
2020-02-22 00:45:21,235 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222004521222168
2020-02-22 00:45:21,263 [salt.minion      :1432][INFO    ][5266] Starting a new job with PID 5266
2020-02-22 00:45:21,285 [salt.minion      :1711][INFO    ][5266] Returning information for job: 20200222004521222168
2020-02-22 00:45:44,279 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1347.18319988s left)
2020-02-22 00:45:51,430 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222004551422434
2020-02-22 00:45:51,454 [salt.minion      :1432][INFO    ][5309] Starting a new job with PID 5309
2020-02-22 00:45:51,478 [salt.minion      :1711][INFO    ][5309] Returning information for job: 20200222004551422434
2020-02-22 00:46:17,542 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1313.91983986s left)
2020-02-22 00:46:21,627 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222004621613778
2020-02-22 00:46:21,649 [salt.minion      :1432][INFO    ][5328] Starting a new job with PID 5328
2020-02-22 00:46:21,670 [salt.minion      :1711][INFO    ][5328] Returning information for job: 20200222004621613778
2020-02-22 00:46:51,110 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1280.3521049s left)
2020-02-22 00:46:51,844 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222004651832605
2020-02-22 00:46:51,869 [salt.minion      :1432][INFO    ][5385] Starting a new job with PID 5385
2020-02-22 00:46:51,887 [salt.minion      :1711][INFO    ][5385] Returning information for job: 20200222004651832605
2020-02-22 00:47:21,863 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222004721853040
2020-02-22 00:47:21,882 [salt.minion      :1432][INFO    ][5403] Starting a new job with PID 5403
2020-02-22 00:47:21,902 [salt.minion      :1711][INFO    ][5403] Returning information for job: 20200222004721853040
2020-02-22 00:47:24,593 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1246.86890006s left)
2020-02-22 00:47:51,914 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222004751897237
2020-02-22 00:47:51,936 [salt.minion      :1432][INFO    ][5439] Starting a new job with PID 5439
2020-02-22 00:47:51,959 [salt.minion      :1711][INFO    ][5439] Returning information for job: 20200222004751897237
2020-02-22 00:47:58,148 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1213.31363392s left)
2020-02-22 00:48:21,962 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222004821946482
2020-02-22 00:48:21,981 [salt.minion      :1432][INFO    ][5459] Starting a new job with PID 5459
2020-02-22 00:48:22,002 [salt.minion      :1711][INFO    ][5459] Returning information for job: 20200222004821946482
2020-02-22 00:48:31,626 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1179.83583903s left)
2020-02-22 00:48:51,981 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222004851972165
2020-02-22 00:48:52,004 [salt.minion      :1432][INFO    ][5495] Starting a new job with PID 5495
2020-02-22 00:48:52,026 [salt.minion      :1711][INFO    ][5495] Returning information for job: 20200222004851972165
2020-02-22 00:49:05,133 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1146.32855988s left)
2020-02-22 00:49:22,023 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222004922007573
2020-02-22 00:49:22,049 [salt.minion      :1432][INFO    ][5519] Starting a new job with PID 5519
2020-02-22 00:49:22,070 [salt.minion      :1711][INFO    ][5519] Returning information for job: 20200222004922007573
2020-02-22 00:49:38,492 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1112.9696939s left)
2020-02-22 00:49:52,103 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222004952093899
2020-02-22 00:49:52,128 [salt.minion      :1432][INFO    ][5555] Starting a new job with PID 5555
2020-02-22 00:49:52,148 [salt.minion      :1711][INFO    ][5555] Returning information for job: 20200222004952093899
2020-02-22 00:50:11,955 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1079.50717902s left)
2020-02-22 00:50:22,214 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222005022201301
2020-02-22 00:50:22,233 [salt.minion      :1432][INFO    ][5577] Starting a new job with PID 5577
2020-02-22 00:50:22,255 [salt.minion      :1711][INFO    ][5577] Returning information for job: 20200222005022201301
2020-02-22 00:50:45,318 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1046.14386392s left)
2020-02-22 00:50:52,307 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222005052297555
2020-02-22 00:50:52,329 [salt.minion      :1432][INFO    ][5612] Starting a new job with PID 5612
2020-02-22 00:50:52,350 [salt.minion      :1711][INFO    ][5612] Returning information for job: 20200222005052297555
2020-02-22 00:51:18,702 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1012.76039386s left)
2020-02-22 00:51:22,410 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222005122395414
2020-02-22 00:51:22,443 [salt.minion      :1432][INFO    ][5633] Starting a new job with PID 5633
2020-02-22 00:51:22,465 [salt.minion      :1711][INFO    ][5633] Returning information for job: 20200222005122395414
2020-02-22 00:51:52,052 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (979.409631968s left)
2020-02-22 00:51:52,557 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222005152546072
2020-02-22 00:51:52,577 [salt.minion      :1432][INFO    ][5682] Starting a new job with PID 5682
2020-02-22 00:51:52,599 [salt.minion      :1711][INFO    ][5682] Returning information for job: 20200222005152546072
2020-02-22 00:52:22,695 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222005222681277
2020-02-22 00:52:22,715 [salt.minion      :1432][INFO    ][5704] Starting a new job with PID 5704
2020-02-22 00:52:22,739 [salt.minion      :1711][INFO    ][5704] Returning information for job: 20200222005222681277
2020-02-22 00:52:25,481 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (945.980942965s left)
2020-02-22 00:52:52,844 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222005252835258
2020-02-22 00:52:52,869 [salt.minion      :1432][INFO    ][5743] Starting a new job with PID 5743
2020-02-22 00:52:52,895 [salt.minion      :1711][INFO    ][5743] Returning information for job: 20200222005252835258
2020-02-22 00:52:58,810 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (912.652245998s left)
2020-02-22 00:53:23,037 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222005323018762
2020-02-22 00:53:23,066 [salt.minion      :1432][INFO    ][5761] Starting a new job with PID 5761
2020-02-22 00:53:23,100 [salt.minion      :1711][INFO    ][5761] Returning information for job: 20200222005323018762
2020-02-22 00:53:32,282 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (879.180008888s left)
2020-02-22 00:53:53,215 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222005353202169
2020-02-22 00:53:53,242 [salt.minion      :1432][INFO    ][5800] Starting a new job with PID 5800
2020-02-22 00:53:53,277 [salt.minion      :1711][INFO    ][5800] Returning information for job: 20200222005353202169
2020-02-22 00:54:05,746 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (845.716312885s left)
2020-02-22 00:54:23,420 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222005423409648
2020-02-22 00:54:23,451 [salt.minion      :1432][INFO    ][5823] Starting a new job with PID 5823
2020-02-22 00:54:23,480 [salt.minion      :1711][INFO    ][5823] Returning information for job: 20200222005423409648
2020-02-22 00:54:39,032 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (812.430419922s left)
2020-02-22 00:54:53,622 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222005453613177
2020-02-22 00:54:53,644 [salt.minion      :1432][INFO    ][5861] Starting a new job with PID 5861
2020-02-22 00:54:53,683 [salt.minion      :1711][INFO    ][5861] Returning information for job: 20200222005453613177
2020-02-22 00:55:12,333 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (779.128968s left)
2020-02-22 00:55:23,843 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222005523834091
2020-02-22 00:55:23,866 [salt.minion      :1432][INFO    ][5883] Starting a new job with PID 5883
2020-02-22 00:55:23,907 [salt.minion      :1711][INFO    ][5883] Returning information for job: 20200222005523834091
2020-02-22 00:55:45,527 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (745.935443878s left)
2020-02-22 00:55:53,862 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222005553851837
2020-02-22 00:55:53,891 [salt.minion      :1432][INFO    ][5921] Starting a new job with PID 5921
2020-02-22 00:55:53,915 [salt.minion      :1711][INFO    ][5921] Returning information for job: 20200222005553851837
2020-02-22 00:56:18,701 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (712.760648012s left)
2020-02-22 00:56:23,897 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222005623886210
2020-02-22 00:56:23,925 [salt.minion      :1432][INFO    ][5943] Starting a new job with PID 5943
2020-02-22 00:56:23,954 [salt.minion      :1711][INFO    ][5943] Returning information for job: 20200222005623886210
2020-02-22 00:56:51,717 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (679.744663s left)
2020-02-22 00:56:53,979 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222005653961874
2020-02-22 00:56:54,006 [salt.minion      :1432][INFO    ][5977] Starting a new job with PID 5977
2020-02-22 00:56:54,032 [salt.minion      :1711][INFO    ][5977] Returning information for job: 20200222005653961874
2020-02-22 00:57:24,058 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222005724045875
2020-02-22 00:57:24,081 [salt.minion      :1432][INFO    ][5999] Starting a new job with PID 5999
2020-02-22 00:57:24,113 [salt.minion      :1711][INFO    ][5999] Returning information for job: 20200222005724045875
2020-02-22 00:57:24,906 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (646.556473017s left)
2020-02-22 00:57:54,162 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222005754152308
2020-02-22 00:57:54,183 [salt.minion      :1432][INFO    ][6033] Starting a new job with PID 6033
2020-02-22 00:57:54,212 [salt.minion      :1711][INFO    ][6033] Returning information for job: 20200222005754152308
2020-02-22 00:57:58,203 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (613.259275913s left)
2020-02-22 00:58:24,265 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222005824253631
2020-02-22 00:58:24,285 [salt.minion      :1432][INFO    ][6053] Starting a new job with PID 6053
2020-02-22 00:58:24,317 [salt.minion      :1711][INFO    ][6053] Returning information for job: 20200222005824253631
2020-02-22 00:58:31,363 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (580.099524975s left)
2020-02-22 00:58:54,393 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222005854377752
2020-02-22 00:58:54,423 [salt.minion      :1432][INFO    ][6087] Starting a new job with PID 6087
2020-02-22 00:58:54,450 [salt.minion      :1711][INFO    ][6087] Returning information for job: 20200222005854377752
2020-02-22 00:59:04,515 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (546.947229862s left)
2020-02-22 00:59:24,510 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222005924498247
2020-02-22 00:59:24,538 [salt.minion      :1432][INFO    ][6107] Starting a new job with PID 6107
2020-02-22 00:59:24,570 [salt.minion      :1711][INFO    ][6107] Returning information for job: 20200222005924498247
2020-02-22 00:59:37,775 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (513.686951876s left)
2020-02-22 00:59:54,702 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222005954689253
2020-02-22 00:59:54,729 [salt.minion      :1432][INFO    ][6140] Starting a new job with PID 6140
2020-02-22 00:59:54,754 [salt.minion      :1711][INFO    ][6140] Returning information for job: 20200222005954689253
2020-02-22 01:00:11,015 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (480.447179079s left)
2020-02-22 01:00:24,866 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222010024850542
2020-02-22 01:00:24,890 [salt.minion      :1432][INFO    ][6161] Starting a new job with PID 6161
2020-02-22 01:00:24,920 [salt.minion      :1711][INFO    ][6161] Returning information for job: 20200222010024850542
2020-02-22 01:00:44,234 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (447.227673054s left)
2020-02-22 01:00:55,072 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222010055057860
2020-02-22 01:00:55,099 [salt.minion      :1432][INFO    ][6197] Starting a new job with PID 6197
2020-02-22 01:00:55,125 [salt.minion      :1711][INFO    ][6197] Returning information for job: 20200222010055057860
2020-02-22 01:01:17,417 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (414.045511961s left)
2020-02-22 01:01:25,250 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222010125240719
2020-02-22 01:01:25,271 [salt.minion      :1432][INFO    ][6218] Starting a new job with PID 6218
2020-02-22 01:01:25,312 [salt.minion      :1711][INFO    ][6218] Returning information for job: 20200222010125240719
2020-02-22 01:01:50,726 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (380.735877037s left)
2020-02-22 01:01:55,274 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222010155260132
2020-02-22 01:01:55,301 [salt.minion      :1432][INFO    ][6269] Starting a new job with PID 6269
2020-02-22 01:01:55,325 [salt.minion      :1711][INFO    ][6269] Returning information for job: 20200222010155260132
2020-02-22 01:02:23,803 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (347.658750057s left)
2020-02-22 01:02:25,500 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222010225490509
2020-02-22 01:02:25,522 [salt.minion      :1432][INFO    ][6292] Starting a new job with PID 6292
2020-02-22 01:02:25,553 [salt.minion      :1711][INFO    ][6292] Returning information for job: 20200222010225490509
2020-02-22 01:02:55,558 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222010255545355
2020-02-22 01:02:55,581 [salt.minion      :1432][INFO    ][6326] Starting a new job with PID 6326
2020-02-22 01:02:55,607 [salt.minion      :1711][INFO    ][6326] Returning information for job: 20200222010255545355
2020-02-22 01:02:56,958 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (314.503850937s left)
2020-02-22 01:03:25,610 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222010325597743
2020-02-22 01:03:25,631 [salt.minion      :1432][INFO    ][6345] Starting a new job with PID 6345
2020-02-22 01:03:25,665 [salt.minion      :1711][INFO    ][6345] Returning information for job: 20200222010325597743
2020-02-22 01:03:30,022 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (281.439785004s left)
2020-02-22 01:03:55,667 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222010355659430
2020-02-22 01:03:55,691 [salt.minion      :1432][INFO    ][6379] Starting a new job with PID 6379
2020-02-22 01:03:55,726 [salt.minion      :1711][INFO    ][6379] Returning information for job: 20200222010355659430
2020-02-22 01:04:03,182 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (248.279746056s left)
2020-02-22 01:04:25,761 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222010425751335
2020-02-22 01:04:25,783 [salt.minion      :1432][INFO    ][6401] Starting a new job with PID 6401
2020-02-22 01:04:25,810 [salt.minion      :1711][INFO    ][6401] Returning information for job: 20200222010425751335
2020-02-22 01:04:36,758 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (214.704043865s left)
2020-02-22 01:04:55,880 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222010455872896
2020-02-22 01:04:55,901 [salt.minion      :1432][INFO    ][6434] Starting a new job with PID 6434
2020-02-22 01:04:55,931 [salt.minion      :1711][INFO    ][6434] Returning information for job: 20200222010455872896
2020-02-22 01:05:09,939 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (181.523072004s left)
2020-02-22 01:05:26,019 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222010526009391
2020-02-22 01:05:26,043 [salt.minion      :1432][INFO    ][6456] Starting a new job with PID 6456
2020-02-22 01:05:26,070 [salt.minion      :1711][INFO    ][6456] Returning information for job: 20200222010526009391
2020-02-22 01:05:43,058 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (148.403897047s left)
2020-02-22 01:05:56,186 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222010556173456
2020-02-22 01:05:56,207 [salt.minion      :1432][INFO    ][6491] Starting a new job with PID 6491
2020-02-22 01:05:56,243 [salt.minion      :1711][INFO    ][6491] Returning information for job: 20200222010556173456
2020-02-22 01:06:16,255 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (115.206959963s left)
2020-02-22 01:06:26,402 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222010626392618
2020-02-22 01:06:26,427 [salt.minion      :1432][INFO    ][6513] Starting a new job with PID 6513
2020-02-22 01:06:26,454 [salt.minion      :1711][INFO    ][6513] Returning information for job: 20200222010626392618
2020-02-22 01:06:49,775 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (81.6872210503s left)
2020-02-22 01:06:56,586 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222010656573198
2020-02-22 01:06:56,610 [salt.minion      :1432][INFO    ][6551] Starting a new job with PID 6551
2020-02-22 01:06:56,635 [salt.minion      :1711][INFO    ][6551] Returning information for job: 20200222010656573198
2020-02-22 01:07:22,856 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (48.6063189507s left)
2020-02-22 01:07:26,806 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222010726795915
2020-02-22 01:07:26,835 [salt.minion      :1432][INFO    ][6570] Starting a new job with PID 6570
2020-02-22 01:07:26,866 [salt.minion      :1711][INFO    ][6570] Returning information for job: 20200222010726795915
2020-02-22 01:07:55,958 [salt.loaded.ext.module.maas:1024][INFO    ][3973] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (15.5045149326s left)
2020-02-22 01:07:57,031 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222010757016996
2020-02-22 01:07:57,057 [salt.minion      :1432][INFO    ][6604] Starting a new job with PID 6604
2020-02-22 01:07:57,089 [salt.minion      :1711][INFO    ][6604] Returning information for job: 20200222010757016996
2020-02-22 01:08:27,053 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222010827042513
2020-02-22 01:08:27,070 [salt.minion      :1432][INFO    ][6623] Starting a new job with PID 6623
2020-02-22 01:08:27,098 [salt.minion      :1711][INFO    ][6623] Returning information for job: 20200222010827042513
2020-02-22 01:08:29,179 [salt.state       :302 ][ERROR   ][3973] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['cmp002', 'cmp001']not in Deployed state
2020-02-22 01:08:29,180 [salt.state       :1951][INFO    ][3973] Completed state [maas.wait_for_machine_status] at time 01:08:29.179933 duration_in_ms=2267729.52
2020-02-22 01:08:29,191 [salt.minion      :1711][INFO    ][3973] Returning information for job: 20200222003032731665
2020-02-22 01:08:40,515 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command pillar.get with jid 20200222010840506523
2020-02-22 01:08:40,531 [salt.minion      :1432][INFO    ][6651] Starting a new job with PID 6651
2020-02-22 01:08:40,540 [salt.minion      :1711][INFO    ][6651] Returning information for job: 20200222010840506523
2020-02-22 01:08:41,487 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command service.status with jid 20200222010841475878
2020-02-22 01:08:41,511 [salt.minion      :1432][INFO    ][6656] Starting a new job with PID 6656
2020-02-22 01:08:42,318 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6656] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2020-02-22 01:08:42,362 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6656] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2020-02-22 01:08:42,379 [salt.minion      :1711][INFO    ][6656] Returning information for job: 20200222010841475878
2020-02-22 01:08:43,379 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command state.apply with jid 20200222010843366377
2020-02-22 01:08:43,411 [salt.minion      :1432][INFO    ][6667] Starting a new job with PID 6667
2020-02-22 01:08:49,941 [salt.state       :915 ][INFO    ][6667] Loading fresh modules for state activity
2020-02-22 01:08:50,557 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6667] Executing command 'salt-minion --version' in directory '/root'
2020-02-22 01:08:50,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6667] Executing command 'salt-minion --version' in directory '/root'
2020-02-22 01:08:52,095 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6667] Executing command 'salt-minion --version' in directory '/root'
2020-02-22 01:08:52,506 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6667] Executing command 'salt-minion --version' in directory '/root'
2020-02-22 01:08:54,898 [salt.state       :1780][INFO    ][6667] Running state [salt-minion] at time 01:08:54.898092
2020-02-22 01:08:54,898 [salt.state       :1813][INFO    ][6667] Executing state pkg.installed for [salt-minion]
2020-02-22 01:08:54,899 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6667] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-22 01:08:55,015 [salt.state       :300 ][INFO    ][6667] All specified packages are already installed
2020-02-22 01:08:55,016 [salt.state       :1951][INFO    ][6667] Completed state [salt-minion] at time 01:08:55.016007 duration_in_ms=117.915
2020-02-22 01:08:55,016 [salt.state       :1780][INFO    ][6667] Running state [salt_minion_dependency_packages] at time 01:08:55.016816
2020-02-22 01:08:55,017 [salt.state       :1813][INFO    ][6667] Executing state pkg.installed for [salt_minion_dependency_packages]
2020-02-22 01:08:55,037 [salt.state       :300 ][INFO    ][6667] All specified packages are already installed
2020-02-22 01:08:55,037 [salt.state       :1951][INFO    ][6667] Completed state [salt_minion_dependency_packages] at time 01:08:55.037494 duration_in_ms=20.68
2020-02-22 01:08:55,043 [salt.state       :1780][INFO    ][6667] Running state [/etc/salt/minion.d/minion.conf] at time 01:08:55.042916
2020-02-22 01:08:55,043 [salt.state       :1813][INFO    ][6667] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2020-02-22 01:08:55,358 [salt.state       :300 ][INFO    ][6667] File /etc/salt/minion.d/minion.conf is in the correct state
2020-02-22 01:08:55,359 [salt.state       :1951][INFO    ][6667] Completed state [/etc/salt/minion.d/minion.conf] at time 01:08:55.359254 duration_in_ms=316.338
2020-02-22 01:08:55,359 [salt.state       :1780][INFO    ][6667] Running state [python-netaddr] at time 01:08:55.359597
2020-02-22 01:08:55,359 [salt.state       :1813][INFO    ][6667] Executing state pkg.installed for [python-netaddr]
2020-02-22 01:08:55,371 [salt.state       :300 ][INFO    ][6667] All specified packages are already installed
2020-02-22 01:08:55,371 [salt.state       :1951][INFO    ][6667] Completed state [python-netaddr] at time 01:08:55.371487 duration_in_ms=11.89
2020-02-22 01:08:55,375 [salt.state       :1780][INFO    ][6667] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 01:08:55.375032
2020-02-22 01:08:55,375 [salt.state       :1813][INFO    ][6667] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2020-02-22 01:08:55,390 [salt.state       :300 ][INFO    ][6667] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2020-02-22 01:08:55,391 [salt.state       :1951][INFO    ][6667] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 01:08:55.390978 duration_in_ms=15.946
2020-02-22 01:08:55,393 [salt.state       :1780][INFO    ][6667] Running state [salt-minion] at time 01:08:55.393852
2020-02-22 01:08:55,394 [salt.state       :1813][INFO    ][6667] Executing state service.running for [salt-minion]
2020-02-22 01:08:55,394 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6667] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2020-02-22 01:08:55,443 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6667] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2020-02-22 01:08:55,461 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6667] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2020-02-22 01:08:55,485 [salt.state       :300 ][INFO    ][6667] The service salt-minion is already running
2020-02-22 01:08:55,485 [salt.state       :1951][INFO    ][6667] Completed state [salt-minion] at time 01:08:55.485700 duration_in_ms=91.847
2020-02-22 01:08:55,487 [salt.state       :1780][INFO    ][6667] Running state [/etc/salt/grains.d] at time 01:08:55.487665
2020-02-22 01:08:55,488 [salt.state       :1813][INFO    ][6667] Executing state file.directory for [/etc/salt/grains.d]
2020-02-22 01:08:55,492 [salt.state       :300 ][INFO    ][6667] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2020-02-22 01:08:55,492 [salt.state       :1951][INFO    ][6667] Completed state [/etc/salt/grains.d] at time 01:08:55.492540 duration_in_ms=4.875
2020-02-22 01:08:55,493 [salt.state       :1780][INFO    ][6667] Running state [/etc/salt/grains] at time 01:08:55.493273
2020-02-22 01:08:55,493 [salt.state       :1813][INFO    ][6667] Executing state file.managed for [/etc/salt/grains]
2020-02-22 01:08:55,494 [salt.state       :300 ][INFO    ][6667] File /etc/salt/grains exists with proper permissions. No changes made.
2020-02-22 01:08:55,494 [salt.state       :1951][INFO    ][6667] Completed state [/etc/salt/grains] at time 01:08:55.494419 duration_in_ms=1.146
2020-02-22 01:08:55,494 [salt.state       :1780][INFO    ][6667] Running state [/etc/salt/grains.d/placeholder] at time 01:08:55.494927
2020-02-22 01:08:55,495 [salt.state       :1813][INFO    ][6667] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2020-02-22 01:08:55,495 [salt.state       :300 ][INFO    ][6667] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2020-02-22 01:08:55,496 [salt.state       :1951][INFO    ][6667] Completed state [/etc/salt/grains.d/placeholder] at time 01:08:55.496036 duration_in_ms=1.109
2020-02-22 01:08:55,496 [salt.state       :1780][INFO    ][6667] Running state [/etc/salt/grains.d/sphinx] at time 01:08:55.496885
2020-02-22 01:08:55,497 [salt.state       :1813][INFO    ][6667] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2020-02-22 01:08:55,498 [salt.state       :300 ][INFO    ][6667] File /etc/salt/grains.d/sphinx is in the correct state
2020-02-22 01:08:55,498 [salt.state       :1951][INFO    ][6667] Completed state [/etc/salt/grains.d/sphinx] at time 01:08:55.498839 duration_in_ms=1.954
2020-02-22 01:08:55,503 [salt.state       :1780][INFO    ][6667] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 01:08:55.503380
2020-02-22 01:08:55,503 [salt.state       :1813][INFO    ][6667] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2020-02-22 01:08:55,504 [salt.state       :300 ][INFO    ][6667] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2020-02-22 01:08:55,504 [salt.state       :1951][INFO    ][6667] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 01:08:55.504593 duration_in_ms=1.213
2020-02-22 01:08:55,505 [salt.state       :1780][INFO    ][6667] Running state [/etc/salt/grains.d/dns_records] at time 01:08:55.505163
2020-02-22 01:08:55,505 [salt.state       :1813][INFO    ][6667] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2020-02-22 01:08:55,506 [salt.state       :300 ][INFO    ][6667] File /etc/salt/grains.d/dns_records is in the correct state
2020-02-22 01:08:55,506 [salt.state       :1951][INFO    ][6667] Completed state [/etc/salt/grains.d/dns_records] at time 01:08:55.506728 duration_in_ms=1.565
2020-02-22 01:08:55,507 [salt.state       :1780][INFO    ][6667] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 01:08:55.507785
2020-02-22 01:08:55,508 [salt.state       :1813][INFO    ][6667] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2020-02-22 01:08:55,508 [salt.state       :300 ][INFO    ][6667] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2020-02-22 01:08:55,508 [salt.state       :1951][INFO    ][6667] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 01:08:55.508701 duration_in_ms=0.915
2020-02-22 01:08:55,509 [salt.state       :1780][INFO    ][6667] Running state [/etc/salt/grains.d/salt] at time 01:08:55.509245
2020-02-22 01:08:55,509 [salt.state       :1813][INFO    ][6667] Executing state file.managed for [/etc/salt/grains.d/salt]
2020-02-22 01:08:55,510 [salt.state       :300 ][INFO    ][6667] File /etc/salt/grains.d/salt is in the correct state
2020-02-22 01:08:55,510 [salt.state       :1951][INFO    ][6667] Completed state [/etc/salt/grains.d/salt] at time 01:08:55.510829 duration_in_ms=1.583
2020-02-22 01:08:55,511 [salt.state       :1780][INFO    ][6667] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 01:08:55.511747
2020-02-22 01:08:55,512 [salt.state       :1813][INFO    ][6667] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2020-02-22 01:08:55,513 [salt.state       :300 ][INFO    ][6667] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2020-02-22 01:08:55,514 [salt.state       :1951][INFO    ][6667] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 01:08:55.514045 duration_in_ms=2.298
2020-02-22 01:08:55,516 [salt.state       :1780][INFO    ][6667] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 01:08:55.516082
2020-02-22 01:08:55,516 [salt.state       :1813][INFO    ][6667] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2020-02-22 01:08:55,516 [salt.state       :300 ][INFO    ][6667] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2020-02-22 01:08:55,517 [salt.state       :1951][INFO    ][6667] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 01:08:55.517000 duration_in_ms=0.918
2020-02-22 01:08:55,517 [salt.state       :1780][INFO    ][6667] Running state [mine.update] at time 01:08:55.517764
2020-02-22 01:08:55,518 [salt.state       :1813][INFO    ][6667] Executing state module.wait for [mine.update]
2020-02-22 01:08:55,518 [salt.state       :300 ][INFO    ][6667] No changes made for mine.update
2020-02-22 01:08:55,518 [salt.state       :1951][INFO    ][6667] Completed state [mine.update] at time 01:08:55.518569 duration_in_ms=0.805
2020-02-22 01:08:55,518 [salt.state       :1780][INFO    ][6667] Running state [ca-certificates] at time 01:08:55.518846
2020-02-22 01:08:55,519 [salt.state       :1813][INFO    ][6667] Executing state pkg.installed for [ca-certificates]
2020-02-22 01:08:55,538 [salt.state       :300 ][INFO    ][6667] All specified packages are already installed
2020-02-22 01:08:55,539 [salt.state       :1951][INFO    ][6667] Completed state [ca-certificates] at time 01:08:55.539286 duration_in_ms=20.438
2020-02-22 01:08:55,540 [salt.state       :1780][INFO    ][6667] Running state [update-ca-certificates] at time 01:08:55.540544
2020-02-22 01:08:55,540 [salt.state       :1813][INFO    ][6667] Executing state cmd.wait for [update-ca-certificates]
2020-02-22 01:08:55,541 [salt.state       :300 ][INFO    ][6667] No changes made for update-ca-certificates
2020-02-22 01:08:55,541 [salt.state       :1951][INFO    ][6667] Completed state [update-ca-certificates] at time 01:08:55.541512 duration_in_ms=0.968
2020-02-22 01:08:55,541 [salt.state       :1780][INFO    ][6667] Running state [iptables] at time 01:08:55.541855
2020-02-22 01:08:55,542 [salt.state       :1813][INFO    ][6667] Executing state pkg.installed for [iptables]
2020-02-22 01:08:55,555 [salt.state       :300 ][INFO    ][6667] All specified packages are already installed
2020-02-22 01:08:55,556 [salt.state       :1951][INFO    ][6667] Completed state [iptables] at time 01:08:55.555931 duration_in_ms=14.075
2020-02-22 01:08:55,556 [salt.state       :1780][INFO    ][6667] Running state [iptables-persistent] at time 01:08:55.556364
2020-02-22 01:08:55,556 [salt.state       :1813][INFO    ][6667] Executing state pkg.installed for [iptables-persistent]
2020-02-22 01:08:55,568 [salt.state       :300 ][INFO    ][6667] All specified packages are already installed
2020-02-22 01:08:55,569 [salt.state       :1951][INFO    ][6667] Completed state [iptables-persistent] at time 01:08:55.569029 duration_in_ms=12.665
2020-02-22 01:08:55,570 [salt.state       :1780][INFO    ][6667] Running state [iptables_modules_v4_load] at time 01:08:55.570420
2020-02-22 01:08:55,570 [salt.state       :1813][INFO    ][6667] Executing state kmod.present for [iptables_modules_v4_load]
2020-02-22 01:08:55,571 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6667] Executing command 'lsmod' in directory '/root'
2020-02-22 01:08:55,594 [salt.state       :300 ][INFO    ][6667] Kernel modules iptable_filter, ip_tables are already present
2020-02-22 01:08:55,595 [salt.state       :1951][INFO    ][6667] Completed state [iptables_modules_v4_load] at time 01:08:55.595057 duration_in_ms=24.637
2020-02-22 01:08:55,595 [salt.state       :1780][INFO    ][6667] Running state [/etc/iptables/rules.v4] at time 01:08:55.595763
2020-02-22 01:08:55,596 [salt.state       :1813][INFO    ][6667] Executing state file.managed for [/etc/iptables/rules.v4]
2020-02-22 01:08:55,707 [salt.state       :300 ][INFO    ][6667] File /etc/iptables/rules.v4 is in the correct state
2020-02-22 01:08:55,707 [salt.state       :1951][INFO    ][6667] Completed state [/etc/iptables/rules.v4] at time 01:08:55.707668 duration_in_ms=111.904
2020-02-22 01:08:55,709 [salt.state       :1780][INFO    ][6667] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 01:08:55.709842
2020-02-22 01:08:55,710 [salt.state       :1813][INFO    ][6667] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2020-02-22 01:08:55,710 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6667] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2020-02-22 01:08:55,733 [salt.state       :300 ][INFO    ][6667] onlyif execution failed
2020-02-22 01:08:55,734 [salt.state       :1951][INFO    ][6667] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 01:08:55.734262 duration_in_ms=24.42
2020-02-22 01:08:55,735 [salt.state       :1780][INFO    ][6667] Running state [netfilter-persistent] at time 01:08:55.735667
2020-02-22 01:08:55,736 [salt.state       :1813][INFO    ][6667] Executing state service.running for [netfilter-persistent]
2020-02-22 01:08:55,737 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6667] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2020-02-22 01:08:55,763 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6667] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2020-02-22 01:08:55,787 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6667] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2020-02-22 01:08:55,808 [salt.state       :300 ][INFO    ][6667] The service netfilter-persistent is already running
2020-02-22 01:08:55,809 [salt.state       :1951][INFO    ][6667] Completed state [netfilter-persistent] at time 01:08:55.809005 duration_in_ms=73.337
2020-02-22 01:08:55,810 [salt.state       :1780][INFO    ][6667] Running state [iptables_extra.remove_stale_tables] at time 01:08:55.810569
2020-02-22 01:08:55,811 [salt.state       :1813][INFO    ][6667] Executing state module.wait for [iptables_extra.remove_stale_tables]
2020-02-22 01:08:55,811 [salt.state       :300 ][INFO    ][6667] No changes made for iptables_extra.remove_stale_tables
2020-02-22 01:08:55,812 [salt.state       :1951][INFO    ][6667] Completed state [iptables_extra.remove_stale_tables] at time 01:08:55.811998 duration_in_ms=1.43
2020-02-22 01:08:55,815 [salt.state       :1780][INFO    ][6667] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 01:08:55.815095
2020-02-22 01:08:55,815 [salt.state       :1813][INFO    ][6667] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2020-02-22 01:08:55,816 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6667] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2020-02-22 01:08:55,830 [salt.state       :300 ][INFO    ][6667] onlyif execution failed
2020-02-22 01:08:55,831 [salt.state       :1951][INFO    ][6667] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 01:08:55.831325 duration_in_ms=16.23
2020-02-22 01:08:55,832 [salt.state       :1780][INFO    ][6667] Running state [/etc/iptables/rules.v6] at time 01:08:55.832780
2020-02-22 01:08:55,833 [salt.state       :1813][INFO    ][6667] Executing state file.absent for [/etc/iptables/rules.v6]
2020-02-22 01:08:55,834 [salt.state       :300 ][INFO    ][6667] File /etc/iptables/rules.v6 is not present
2020-02-22 01:08:55,834 [salt.state       :1951][INFO    ][6667] Completed state [/etc/iptables/rules.v6] at time 01:08:55.834762 duration_in_ms=1.982
2020-02-22 01:08:55,836 [salt.state       :1780][INFO    ][6667] Running state [iptables_extra.flush_all] at time 01:08:55.835925
2020-02-22 01:08:55,839 [salt.state       :1813][INFO    ][6667] Executing state module.wait for [iptables_extra.flush_all]
2020-02-22 01:08:55,840 [salt.state       :300 ][INFO    ][6667] No changes made for iptables_extra.flush_all
2020-02-22 01:08:55,840 [salt.state       :1951][INFO    ][6667] Completed state [iptables_extra.flush_all] at time 01:08:55.840680 duration_in_ms=4.756
2020-02-22 01:08:55,845 [salt.minion      :1711][INFO    ][6667] Returning information for job: 20200222010843366377
2020-02-22 01:08:56,870 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command state.apply with jid 20200222010856855039
2020-02-22 01:08:56,893 [salt.minion      :1432][INFO    ][6749] Starting a new job with PID 6749
2020-02-22 01:08:57,927 [salt.state       :915 ][INFO    ][6749] Loading fresh modules for state activity
2020-02-22 01:08:59,288 [salt.state       :1780][INFO    ][6749] Running state [maas-rack-controller] at time 01:08:59.287953
2020-02-22 01:08:59,288 [salt.state       :1813][INFO    ][6749] Executing state pkg.installed for [maas-rack-controller]
2020-02-22 01:08:59,289 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6749] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-22 01:08:59,422 [salt.state       :300 ][INFO    ][6749] All specified packages are already installed
2020-02-22 01:08:59,422 [salt.state       :1951][INFO    ][6749] Completed state [maas-rack-controller] at time 01:08:59.422341 duration_in_ms=134.388
2020-02-22 01:08:59,422 [salt.state       :1780][INFO    ][6749] Running state [ipmitool] at time 01:08:59.422720
2020-02-22 01:08:59,423 [salt.state       :1813][INFO    ][6749] Executing state pkg.installed for [ipmitool]
2020-02-22 01:08:59,432 [salt.state       :300 ][INFO    ][6749] All specified packages are already installed
2020-02-22 01:08:59,433 [salt.state       :1951][INFO    ][6749] Completed state [ipmitool] at time 01:08:59.432954 duration_in_ms=10.234
2020-02-22 01:08:59,437 [salt.state       :1780][INFO    ][6749] Running state [/etc/maas/rackd.conf] at time 01:08:59.437483
2020-02-22 01:08:59,437 [salt.state       :1813][INFO    ][6749] Executing state file.line for [/etc/maas/rackd.conf]
2020-02-22 01:08:59,438 [salt.state       :300 ][INFO    ][6749] No changes needed to be made
2020-02-22 01:08:59,439 [salt.state       :1951][INFO    ][6749] Completed state [/etc/maas/rackd.conf] at time 01:08:59.438999 duration_in_ms=1.516
2020-02-22 01:08:59,439 [salt.state       :1780][INFO    ][6749] Running state [/etc/maas/rackd.conf] at time 01:08:59.439294
2020-02-22 01:08:59,439 [salt.state       :1813][INFO    ][6749] Executing state file.managed for [/etc/maas/rackd.conf]
2020-02-22 01:08:59,439 [salt.loaded.int.states.file:2298][WARNING ][6749] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2020-02-22 01:08:59,440 [salt.state       :300 ][INFO    ][6749] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2020-02-22 01:08:59,440 [salt.state       :1951][INFO    ][6749] Completed state [/etc/maas/rackd.conf] at time 01:08:59.440835 duration_in_ms=1.541
2020-02-22 01:08:59,442 [salt.state       :1780][INFO    ][6749] Running state [maas-rackd] at time 01:08:59.442034
2020-02-22 01:08:59,442 [salt.state       :1813][INFO    ][6749] Executing state service.running for [maas-rackd]
2020-02-22 01:08:59,443 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6749] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2020-02-22 01:08:59,489 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6749] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2020-02-22 01:08:59,506 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6749] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2020-02-22 01:08:59,530 [salt.state       :300 ][INFO    ][6749] The service maas-rackd is already running
2020-02-22 01:08:59,530 [salt.state       :1951][INFO    ][6749] Completed state [maas-rackd] at time 01:08:59.530537 duration_in_ms=88.502
2020-02-22 01:08:59,534 [salt.minion      :1711][INFO    ][6749] Returning information for job: 20200222010856855039
2020-02-22 01:09:00,587 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command state.apply with jid 20200222010900577157
2020-02-22 01:09:00,612 [salt.minion      :1432][INFO    ][6772] Starting a new job with PID 6772
2020-02-22 01:09:01,681 [salt.state       :915 ][INFO    ][6772] Loading fresh modules for state activity
2020-02-22 01:09:03,251 [salt.state       :1780][INFO    ][6772] Running state [maas-region-controller] at time 01:09:03.251064
2020-02-22 01:09:03,251 [salt.state       :1813][INFO    ][6772] Executing state pkg.installed for [maas-region-controller]
2020-02-22 01:09:03,253 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6772] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-22 01:09:03,382 [salt.state       :300 ][INFO    ][6772] All specified packages are already installed
2020-02-22 01:09:03,383 [salt.state       :1951][INFO    ][6772] Completed state [maas-region-controller] at time 01:09:03.382986 duration_in_ms=131.922
2020-02-22 01:09:03,383 [salt.state       :1780][INFO    ][6772] Running state [python-oauth] at time 01:09:03.383370
2020-02-22 01:09:03,383 [salt.state       :1813][INFO    ][6772] Executing state pkg.installed for [python-oauth]
2020-02-22 01:09:03,395 [salt.state       :300 ][INFO    ][6772] All specified packages are already installed
2020-02-22 01:09:03,396 [salt.state       :1951][INFO    ][6772] Completed state [python-oauth] at time 01:09:03.396126 duration_in_ms=12.756
2020-02-22 01:09:03,400 [salt.state       :1780][INFO    ][6772] Running state [/etc/maas/regiond.conf] at time 01:09:03.400875
2020-02-22 01:09:03,401 [salt.state       :1813][INFO    ][6772] Executing state file.replace for [/etc/maas/regiond.conf]
2020-02-22 01:09:03,406 [salt.state       :300 ][INFO    ][6772] No changes needed to be made
2020-02-22 01:09:03,407 [salt.state       :1951][INFO    ][6772] Completed state [/etc/maas/regiond.conf] at time 01:09:03.407108 duration_in_ms=6.233
2020-02-22 01:09:03,407 [salt.state       :1780][INFO    ][6772] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 01:09:03.407622
2020-02-22 01:09:03,407 [salt.state       :1813][INFO    ][6772] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2020-02-22 01:09:03,482 [salt.state       :300 ][INFO    ][6772] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2020-02-22 01:09:03,482 [salt.state       :1951][INFO    ][6772] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 01:09:03.482627 duration_in_ms=75.004
2020-02-22 01:09:03,483 [salt.state       :1780][INFO    ][6772] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 01:09:03.483473
2020-02-22 01:09:03,484 [salt.state       :1813][INFO    ][6772] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-02-22 01:09:03,492 [salt.state       :300 ][INFO    ][6772] No changes needed to be made
2020-02-22 01:09:03,493 [salt.state       :1951][INFO    ][6772] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 01:09:03.493088 duration_in_ms=9.615
2020-02-22 01:09:03,494 [salt.state       :1780][INFO    ][6772] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 01:09:03.494044
2020-02-22 01:09:03,494 [salt.state       :1813][INFO    ][6772] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-02-22 01:09:03,500 [salt.state       :300 ][INFO    ][6772] No changes needed to be made
2020-02-22 01:09:03,500 [salt.state       :1951][INFO    ][6772] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 01:09:03.500301 duration_in_ms=6.257
2020-02-22 01:09:03,500 [salt.state       :1780][INFO    ][6772] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 01:09:03.500811
2020-02-22 01:09:03,501 [salt.state       :1813][INFO    ][6772] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2020-02-22 01:09:03,536 [salt.state       :300 ][INFO    ][6772] No changes needed to be made
2020-02-22 01:09:03,536 [salt.state       :1951][INFO    ][6772] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 01:09:03.536886 duration_in_ms=36.075
2020-02-22 01:09:03,537 [salt.state       :1780][INFO    ][6772] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 01:09:03.537181
2020-02-22 01:09:03,537 [salt.state       :1813][INFO    ][6772] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2020-02-22 01:09:03,563 [salt.state       :300 ][INFO    ][6772] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2020-02-22 01:09:03,567 [salt.state       :1951][INFO    ][6772] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 01:09:03.567456 duration_in_ms=30.275
2020-02-22 01:09:03,568 [salt.state       :1780][INFO    ][6772] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 01:09:03.568732
2020-02-22 01:09:03,569 [salt.state       :1813][INFO    ][6772] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2020-02-22 01:09:03,626 [salt.state       :300 ][INFO    ][6772] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2020-02-22 01:09:03,626 [salt.state       :1951][INFO    ][6772] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 01:09:03.626827 duration_in_ms=58.094
2020-02-22 01:09:03,627 [salt.state       :1780][INFO    ][6772] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 01:09:03.627707
2020-02-22 01:09:03,628 [salt.state       :1813][INFO    ][6772] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2020-02-22 01:09:03,689 [salt.state       :300 ][INFO    ][6772] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2020-02-22 01:09:03,690 [salt.state       :1951][INFO    ][6772] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 01:09:03.690003 duration_in_ms=62.296
2020-02-22 01:09:03,690 [salt.state       :1780][INFO    ][6772] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 01:09:03.690670
2020-02-22 01:09:03,691 [salt.state       :1813][INFO    ][6772] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2020-02-22 01:09:03,785 [salt.state       :300 ][INFO    ][6772] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2020-02-22 01:09:03,785 [salt.state       :1951][INFO    ][6772] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 01:09:03.785862 duration_in_ms=95.191
2020-02-22 01:09:03,787 [salt.state       :1780][INFO    ][6772] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 01:09:03.786926
2020-02-22 01:09:03,787 [salt.state       :1813][INFO    ][6772] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic]
2020-02-22 01:09:03,867 [salt.state       :300 ][INFO    ][6772] File /etc/maas/preseeds/curtin_userdata_amd64_generic_bionic is in the correct state
2020-02-22 01:09:03,868 [salt.state       :1951][INFO    ][6772] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 01:09:03.867997 duration_in_ms=81.071
2020-02-22 01:09:03,868 [salt.state       :1780][INFO    ][6772] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 01:09:03.868889
2020-02-22 01:09:03,869 [salt.state       :1813][INFO    ][6772] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic]
2020-02-22 01:09:03,942 [salt.state       :300 ][INFO    ][6772] File /etc/maas/preseeds/curtin_userdata_arm64_generic_bionic is in the correct state
2020-02-22 01:09:03,942 [salt.state       :1951][INFO    ][6772] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 01:09:03.942823 duration_in_ms=73.934
2020-02-22 01:09:03,943 [salt.state       :1780][INFO    ][6772] Running state [/root/.pgpass] at time 01:09:03.943129
2020-02-22 01:09:03,943 [salt.state       :1813][INFO    ][6772] Executing state file.managed for [/root/.pgpass]
2020-02-22 01:09:04,006 [salt.state       :300 ][INFO    ][6772] File /root/.pgpass is in the correct state
2020-02-22 01:09:04,007 [salt.state       :1951][INFO    ][6772] Completed state [/root/.pgpass] at time 01:09:04.007201 duration_in_ms=64.072
2020-02-22 01:09:04,016 [salt.state       :1780][INFO    ][6772] Running state [maas-region migrate --noinput] at time 01:09:04.016708
2020-02-22 01:09:04,017 [salt.state       :1813][INFO    ][6772] Executing state cmd.run for [maas-region migrate --noinput]
2020-02-22 01:09:04,018 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6772] Executing command 'maas-region migrate --noinput' in directory '/root'
2020-02-22 01:09:07,337 [salt.state       :300 ][INFO    ][6772] {'pid': 6810, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Apply all migrations: auth, contenttypes, maasserver, metadataserver, piston3, sessions, sites\nRunning migrations:\n  No migrations to apply.'}
2020-02-22 01:09:07,338 [salt.state       :1951][INFO    ][6772] Completed state [maas-region migrate --noinput] at time 01:09:07.337920 duration_in_ms=3321.21
2020-02-22 01:09:07,338 [salt.state       :2022][WARNING ][6772] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-02-22 01:09:07,341 [salt.state       :1780][INFO    ][6772] Running state [maas-regiond] at time 01:09:07.341236
2020-02-22 01:09:07,341 [salt.state       :1813][INFO    ][6772] Executing state service.running for [maas-regiond]
2020-02-22 01:09:07,343 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6772] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2020-02-22 01:09:07,400 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6772] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2020-02-22 01:09:07,430 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6772] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2020-02-22 01:09:07,451 [salt.state       :300 ][INFO    ][6772] The service maas-regiond is already running
2020-02-22 01:09:07,452 [salt.state       :1951][INFO    ][6772] Completed state [maas-regiond] at time 01:09:07.452132 duration_in_ms=110.896
2020-02-22 01:09:07,454 [salt.state       :1780][INFO    ][6772] Running state [bind9] at time 01:09:07.454228
2020-02-22 01:09:07,455 [salt.state       :1813][INFO    ][6772] Executing state service.running for [bind9]
2020-02-22 01:09:07,456 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6772] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2020-02-22 01:09:07,485 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6772] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2020-02-22 01:09:07,511 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6772] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2020-02-22 01:09:07,539 [salt.state       :300 ][INFO    ][6772] The service bind9 is already running
2020-02-22 01:09:07,540 [salt.state       :1951][INFO    ][6772] Completed state [bind9] at time 01:09:07.540081 duration_in_ms=85.852
2020-02-22 01:09:07,541 [salt.state       :1780][INFO    ][6772] Running state [maasng.wait_for_http_code] at time 01:09:07.541575
2020-02-22 01:09:07,541 [salt.state       :1813][INFO    ][6772] Executing state module.run for [maasng.wait_for_http_code]
2020-02-22 01:09:07,542 [salt.utils.decorators:613 ][WARNING ][6772] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 01:09:07,547 [salt.state       :300 ][INFO    ][6772] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2020-02-22 01:09:07,547 [salt.state       :1951][INFO    ][6772] Completed state [maasng.wait_for_http_code] at time 01:09:07.547441 duration_in_ms=5.866
2020-02-22 01:09:07,550 [salt.state       :1780][INFO    ][6772] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 01:09:07.550784
2020-02-22 01:09:07,551 [salt.state       :1813][INFO    ][6772] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2020-02-22 01:09:07,551 [salt.state       :300 ][INFO    ][6772] /var/lib/maas/.setup_admin exists
2020-02-22 01:09:07,551 [salt.state       :1951][INFO    ][6772] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 01:09:07.551880 duration_in_ms=1.096
2020-02-22 01:09:07,553 [salt.state       :1780][INFO    ][6772] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:09:07.553267
2020-02-22 01:09:07,553 [salt.state       :1813][INFO    ][6772] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-22 01:09:07,554 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6772] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-22 01:09:09,890 [salt.state       :300 ][INFO    ][6772] {'pid': 6824, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-22 01:09:09,891 [salt.state       :1951][INFO    ][6772] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:09:09.891457 duration_in_ms=2338.188
2020-02-22 01:09:09,902 [salt.state       :1780][INFO    ][6772] Running state [maas_region_boot_source_resources_mirror] at time 01:09:09.902007
2020-02-22 01:09:09,902 [salt.state       :1813][INFO    ][6772] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2020-02-22 01:09:09,980 [salt.state       :300 ][INFO    ][6772] {'changes': {}}
2020-02-22 01:09:09,981 [salt.state       :1951][INFO    ][6772] Completed state [maas_region_boot_source_resources_mirror] at time 01:09:09.981189 duration_in_ms=79.181
2020-02-22 01:09:09,982 [salt.state       :1780][INFO    ][6772] Running state [maasng.boot_resources_import] at time 01:09:09.982363
2020-02-22 01:09:09,982 [salt.state       :1813][INFO    ][6772] Executing state module.run for [maasng.boot_resources_import]
2020-02-22 01:09:09,983 [salt.utils.decorators:613 ][WARNING ][6772] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 01:09:10,041 [salt.loaded.ext.module.maasng:1600][INFO    ][6772] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2020-02-22 01:09:15,135 [salt.state       :300 ][INFO    ][6772] {'ret': True}
2020-02-22 01:09:15,135 [salt.state       :1951][INFO    ][6772] Completed state [maasng.boot_resources_import] at time 01:09:15.135582 duration_in_ms=5153.218
2020-02-22 01:09:15,137 [salt.state       :1780][INFO    ][6772] Running state [maas_region_boot_sources_selection_bionic] at time 01:09:15.137462
2020-02-22 01:09:15,138 [salt.state       :1813][INFO    ][6772] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_bionic]
2020-02-22 01:09:15,292 [salt.state       :300 ][INFO    ][6772] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2020-02-22 01:09:15,293 [salt.state       :1951][INFO    ][6772] Completed state [maas_region_boot_sources_selection_bionic] at time 01:09:15.293385 duration_in_ms=155.922
2020-02-22 01:09:15,294 [salt.state       :1780][INFO    ][6772] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 01:09:15.294678
2020-02-22 01:09:15,295 [salt.state       :1813][INFO    ][6772] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2020-02-22 01:09:15,295 [salt.utils.decorators:613 ][WARNING ][6772] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 01:09:15,296 [salt.loaded.ext.module.maasng:1771][INFO    ][6772] boot-sources sync initiated for ALL Rack's
2020-02-22 01:09:15,646 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222010915639125
2020-02-22 01:09:15,672 [salt.minion      :1432][INFO    ][6856] Starting a new job with PID 6856
2020-02-22 01:09:15,704 [salt.minion      :1711][INFO    ][6856] Returning information for job: 20200222010915639125
2020-02-22 01:09:16,019 [salt.state       :300 ][INFO    ][6772] {'ret': True}
2020-02-22 01:09:16,019 [salt.state       :1951][INFO    ][6772] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 01:09:16.019842 duration_in_ms=725.163
2020-02-22 01:09:16,022 [salt.state       :1780][INFO    ][6772] Running state [maas.process_maas_config] at time 01:09:16.022852
2020-02-22 01:09:16,023 [salt.state       :1813][INFO    ][6772] Executing state module.run for [maas.process_maas_config]
2020-02-22 01:09:16,024 [salt.utils.decorators:613 ][WARNING ][6772] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 01:09:16,025 [salt.loaded.ext.module.maas:92  ][INFO    ][6772] maasconfig name=enable_http_proxy value=True
2020-02-22 01:09:16,087 [salt.loaded.ext.module.maas:92  ][INFO    ][6772] maasconfig name=upstream_dns value=8.8.8.8
2020-02-22 01:09:16,129 [salt.loaded.ext.module.maas:92  ][INFO    ][6772] maasconfig name=commissioning_distro_series value=bionic
2020-02-22 01:09:16,179 [salt.loaded.ext.module.maas:92  ][INFO    ][6772] maasconfig name=default_osystem value=ubuntu
2020-02-22 01:09:16,229 [salt.loaded.ext.module.maas:92  ][INFO    ][6772] maasconfig name=active_discovery_interval value=600
2020-02-22 01:09:16,271 [salt.loaded.ext.module.maas:92  ][INFO    ][6772] maasconfig name=dnssec_validation value=no
2020-02-22 01:09:16,319 [salt.loaded.ext.module.maas:92  ][INFO    ][6772] maasconfig name=kernel_opts value=spectre_v2=off nopti kpti=off nospec_store_bypass_disable noibrs noibpb
2020-02-22 01:09:17,702 [salt.loaded.ext.module.maas:92  ][INFO    ][6772] maasconfig name=network_discovery value=enabled
2020-02-22 01:09:17,785 [salt.loaded.ext.module.maas:92  ][INFO    ][6772] maasconfig name=enable_third_party_drivers value=True
2020-02-22 01:09:17,816 [salt.loaded.ext.module.maas:92  ][INFO    ][6772] maasconfig name=default_storage_layout value=lvm
2020-02-22 01:09:17,846 [salt.loaded.ext.module.maas:92  ][INFO    ][6772] maasconfig name=maas_name value=mas01
2020-02-22 01:09:17,900 [salt.loaded.ext.module.maas:92  ][INFO    ][6772] maasconfig name=disk_erase_with_secure_erase value=False
2020-02-22 01:09:17,931 [salt.loaded.ext.module.maas:92  ][INFO    ][6772] maasconfig name=default_distro_series value=bionic
2020-02-22 01:09:17,971 [salt.loaded.ext.module.maas:92  ][INFO    ][6772] maasconfig name=ntp_external_only value=True
2020-02-22 01:09:18,003 [salt.loaded.ext.module.maas:92  ][INFO    ][6772] maasconfig name=default_min_hwe_kernel value=ga-18.04
2020-02-22 01:09:18,071 [salt.state       :300 ][INFO    ][6772] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'kernel_opts', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'maas_name', 'disk_erase_with_secure_erase', 'default_distro_series', 'ntp_external_only', 'default_min_hwe_kernel']}}
2020-02-22 01:09:18,071 [salt.state       :1951][INFO    ][6772] Completed state [maas.process_maas_config] at time 01:09:18.071352 duration_in_ms=2048.5
2020-02-22 01:09:18,074 [salt.state       :1780][INFO    ][6772] Running state [pxe_admin] at time 01:09:18.074665
2020-02-22 01:09:18,075 [salt.state       :1813][INFO    ][6772] Executing state maasng.fabric_present for [pxe_admin]
2020-02-22 01:09:18,119 [salt.loaded.ext.module.maasng:945 ][INFO    ][6772] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-02-22 01:09:18,173 [salt.loaded.ext.module.maasng:1008][WARNING ][6772] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2020-02-22 01:09:18,173 [salt.loaded.ext.module.maasng:1011][WARNING ][6772] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2020-02-22 01:09:18,230 [salt.state       :300 ][INFO    ][6772] {'new': 'Fabric  pxe_admin created', 'result': True}
2020-02-22 01:09:18,230 [salt.state       :1951][INFO    ][6772] Completed state [pxe_admin] at time 01:09:18.230499 duration_in_ms=155.833
2020-02-22 01:09:18,231 [salt.state       :1780][INFO    ][6772] Running state [vlan 0] at time 01:09:18.231002
2020-02-22 01:09:18,231 [salt.state       :1813][INFO    ][6772] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-02-22 01:09:18,274 [salt.loaded.ext.module.maasng:945 ][INFO    ][6772] [{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'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-02-22 01:09:18,347 [salt.loaded.ext.module.maasng:945 ][INFO    ][6772] [{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'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-02-22 01:09:18,565 [salt.loaded.ext.module.maasng:945 ][INFO    ][6772] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-02-22 01:09:18,634 [salt.state       :300 ][INFO    ][6772] {'new': 'Vlan untagged was updated'}
2020-02-22 01:09:18,634 [salt.state       :1951][INFO    ][6772] Completed state [vlan 0] at time 01:09:18.634742 duration_in_ms=403.738
2020-02-22 01:09:18,637 [salt.state       :1780][INFO    ][6772] Running state [192.168.11.0/24] at time 01:09:18.637028
2020-02-22 01:09:18,637 [salt.state       :1813][INFO    ][6772] Executing state maasng.subnet_present for [192.168.11.0/24]
2020-02-22 01:09:18,792 [salt.loaded.ext.module.maasng:945 ][INFO    ][6772] [{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'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-02-22 01:09:18,794 [salt.loaded.ext.module.maasng:1235][WARNING ][6772] Ignoring parameter vlan:0
2020-02-22 01:09:18,842 [salt.state       :300 ][INFO    ][6772] Subnet 192.168.11.0/24 has been updated for pxe_admin
2020-02-22 01:09:18,842 [salt.state       :1951][INFO    ][6772] Completed state [192.168.11.0/24] at time 01:09:18.842722 duration_in_ms=205.693
2020-02-22 01:09:18,843 [salt.state       :1780][INFO    ][6772] Running state [maas_create_iprange_1] at time 01:09:18.843856
2020-02-22 01:09:18,844 [salt.state       :1813][INFO    ][6772] Executing state maasng.iprange_present for [maas_create_iprange_1]
2020-02-22 01:09:18,884 [salt.state       :300 ][INFO    ][6772] Iprange maas_create_iprange_1 already exist.
2020-02-22 01:09:18,885 [salt.state       :1951][INFO    ][6772] Completed state [maas_create_iprange_1] at time 01:09:18.885544 duration_in_ms=41.689
2020-02-22 01:09:18,886 [salt.state       :1780][INFO    ][6772] Running state [vlan 0] at time 01:09:18.886028
2020-02-22 01:09:18,886 [salt.state       :1813][INFO    ][6772] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-02-22 01:09:18,940 [salt.loaded.ext.module.maasng:945 ][INFO    ][6772] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'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'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'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'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'pxe_admin', u'id': 1}]
2020-02-22 01:09:19,015 [salt.loaded.ext.module.maasng:945 ][INFO    ][6772] [{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'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-02-22 01:09:19,245 [salt.loaded.ext.module.maasng:945 ][INFO    ][6772] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2020-02-22 01:09:19,337 [salt.state       :300 ][INFO    ][6772] {'new': 'Vlan untagged was updated'}
2020-02-22 01:09:19,338 [salt.state       :1951][INFO    ][6772] Completed state [vlan 0] at time 01:09:19.337932 duration_in_ms=451.904
2020-02-22 01:09:19,338 [salt.state       :1780][INFO    ][6772] Running state [opnfv] at time 01:09:19.338703
2020-02-22 01:09:19,339 [salt.state       :1813][INFO    ][6772] Executing state maasng.sshkey_present for [opnfv]
2020-02-22 01:09:19,375 [salt.loaded.ext.module.maasng:1903][INFO    ][6772] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2020-02-22 01:09:19,375 [salt.state       :300 ][INFO    ][6772] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2020-02-22 01:09:19,376 [salt.state       :1951][INFO    ][6772] Completed state [opnfv] at time 01:09:19.375986 duration_in_ms=37.283
2020-02-22 01:09:19,379 [salt.minion      :1711][INFO    ][6772] Returning information for job: 20200222010900577157
2020-02-22 01:09:20,538 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command state.apply with jid 20200222010920525920
2020-02-22 01:09:20,559 [salt.minion      :1432][INFO    ][6901] Starting a new job with PID 6901
2020-02-22 01:09:26,957 [salt.state       :915 ][INFO    ][6901] Loading fresh modules for state activity
2020-02-22 01:09:27,068 [salt.state       :1780][INFO    ][6901] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:09:27.068221
2020-02-22 01:09:27,069 [salt.state       :1813][INFO    ][6901] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-22 01:09:27,071 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6901] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-22 01:09:29,408 [salt.state       :300 ][INFO    ][6901] {'pid': 6909, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-22 01:09:29,409 [salt.state       :1951][INFO    ][6901] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:09:29.409529 duration_in_ms=2341.309
2020-02-22 01:09:29,412 [salt.state       :1780][INFO    ][6901] Running state [maas.process_machines] at time 01:09:29.412066
2020-02-22 01:09:29,413 [salt.state       :1813][INFO    ][6901] Executing state module.run for [maas.process_machines]
2020-02-22 01:09:29,414 [salt.utils.decorators:613 ][WARNING ][6901] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 01:09:30,151 [salt.loaded.ext.module.maas:412 ][WARNING ][6901] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-22 01:09:30,152 [salt.loaded.ext.module.maas:92  ][INFO    ][6901] 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=stg4ws architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-22 01:09:30,805 [salt.loaded.ext.module.maas:412 ][WARNING ][6901] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-22 01:09:30,806 [salt.loaded.ext.module.maas:92  ][INFO    ][6901] 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=k7e64f architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-22 01:09:31,476 [salt.loaded.ext.module.maas:412 ][WARNING ][6901] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-22 01:09:31,477 [salt.loaded.ext.module.maas:92  ][INFO    ][6901] 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=6dwcrb architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-22 01:09:32,242 [salt.loaded.ext.module.maas:412 ][WARNING ][6901] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-22 01:09:32,243 [salt.loaded.ext.module.maas:92  ][INFO    ][6901] 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=67s83w architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-22 01:09:32,986 [salt.loaded.ext.module.maas:412 ][WARNING ][6901] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-22 01:09:32,987 [salt.loaded.ext.module.maas:92  ][INFO    ][6901] 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=wcfdpt architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-22 01:09:33,731 [salt.state       :300 ][INFO    ][6901] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2020-02-22 01:09:33,732 [salt.state       :1951][INFO    ][6901] Completed state [maas.process_machines] at time 01:09:33.732068 duration_in_ms=4320.001
2020-02-22 01:09:33,736 [salt.minion      :1711][INFO    ][6901] Returning information for job: 20200222010920525920
2020-02-22 01:10:08,670 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command state.apply with jid 20200222011008657434
2020-02-22 01:10:08,697 [salt.minion      :1432][INFO    ][6954] Starting a new job with PID 6954
2020-02-22 01:10:15,088 [salt.state       :915 ][INFO    ][6954] Loading fresh modules for state activity
2020-02-22 01:10:15,203 [salt.state       :1780][INFO    ][6954] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:10:15.202982
2020-02-22 01:10:15,203 [salt.state       :1813][INFO    ][6954] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-22 01:10:15,206 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6954] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-22 01:10:17,540 [salt.state       :300 ][INFO    ][6954] {'pid': 6967, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-22 01:10:17,541 [salt.state       :1951][INFO    ][6954] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:10:17.540933 duration_in_ms=2337.954
2020-02-22 01:10:17,542 [salt.state       :1780][INFO    ][6954] Running state [maas.wait_for_machine_status] at time 01:10:17.542703
2020-02-22 01:10:17,543 [salt.state       :1813][INFO    ][6954] Executing state module.run for [maas.wait_for_machine_status]
2020-02-22 01:10:17,543 [salt.utils.decorators:613 ][WARNING ][6954] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 01:10:20,694 [salt.state       :300 ][INFO    ][6954] {'ret': True}
2020-02-22 01:10:20,695 [salt.state       :1951][INFO    ][6954] Completed state [maas.wait_for_machine_status] at time 01:10:20.695184 duration_in_ms=3152.48
2020-02-22 01:10:20,699 [salt.minion      :1711][INFO    ][6954] Returning information for job: 20200222011008657434
2020-02-22 01:10:21,703 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command state.apply with jid 20200222011021691891
2020-02-22 01:10:21,724 [salt.minion      :1432][INFO    ][6979] Starting a new job with PID 6979
2020-02-22 01:10:22,706 [salt.state       :915 ][INFO    ][6979] Loading fresh modules for state activity
2020-02-22 01:10:22,864 [salt.state       :1780][INFO    ][6979] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:10:22.864806
2020-02-22 01:10:22,865 [salt.state       :1813][INFO    ][6979] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-22 01:10:22,867 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6979] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-22 01:10:25,229 [salt.state       :300 ][INFO    ][6979] {'pid': 6986, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-22 01:10:25,230 [salt.state       :1951][INFO    ][6979] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:10:25.230705 duration_in_ms=2365.897
2020-02-22 01:10:25,234 [salt.state       :1780][INFO    ][6979] Running state [maas_machines_storage_cmp002_lvm] at time 01:10:25.234085
2020-02-22 01:10:25,234 [salt.state       :1813][INFO    ][6979] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2020-02-22 01:10:26,461 [salt.loaded.ext.module.maasng:610 ][INFO    ][6979] stg4ws
2020-02-22 01:10:26,462 [salt.loaded.ext.module.maasng:626 ][INFO    ][6979] sda
2020-02-22 01:10:27,087 [salt.loaded.ext.module.maasng:361 ][INFO    ][6979] stg4ws
2020-02-22 01:10:27,172 [salt.loaded.ext.module.maasng:367 ][INFO    ][6979] [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'32f84951-a7f8-4128-a62c-fa893b4fd83d', u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/1/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'stg4ws', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'863ae5b3-ff3f-4a2b-b616-03dbc3a3a327', 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'name': u'sda', u'system_id': u'stg4ws', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/13/', u'uuid': u'a74922b4-eaae-4c11-841d-465a4c2df290', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'9f9c23e1-3cd4-4b37-83f1-85a1b24ae639', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'stg4ws', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 13, u'size': 800097042432}]
2020-02-22 01:10:27,173 [salt.loaded.ext.module.maasng:632 ][INFO    ][6979] vgroot
2020-02-22 01:10:27,173 [salt.loaded.ext.module.maasng:635 ][INFO    ][6979] lvroot
2020-02-22 01:10:27,173 [salt.loaded.ext.module.maasng:639 ][INFO    ][6979] 107374182400
2020-02-22 01:10:27,769 [salt.loaded.ext.module.maasng:645 ][INFO    ][6979] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': None, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'9b422aff-1de4-4e2d-a8d9-ec315dbab953', u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/1/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'stg4ws', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'28d00bb5-dbdb-40fc-9cd4-1da668c1296d', 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'filesystem': None, u'name': u'sda', u'system_id': u'stg4ws', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/15/', u'uuid': u'3f6b81b2-ddda-42ea-b73e-0e641b3bd541', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'4efca768-3dfb-47a1-be1e-d00cceff5ce8', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'stg4ws', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 15, 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/stg4ws/', 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'address_ttl': None, u'system_id': u'stg4ws', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'min_hwe_kernel': u'ga-18.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'9b422aff-1de4-4e2d-a8d9-ec315dbab953', u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/1/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'stg4ws', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'28d00bb5-dbdb-40fc-9cd4-1da668c1296d', 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'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'stg4ws', 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'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}], u'ip_addresses': [], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'stg4ws', u'id': 10}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'9b422aff-1de4-4e2d-a8d9-ec315dbab953', u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/1/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'stg4ws', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'28d00bb5-dbdb-40fc-9cd4-1da668c1296d', 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'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'stg4ws', 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'firmware_version': u'5.22', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}, u'interface_set': [{u'children': [], u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 62, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'system_id': u'stg4ws', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/interfaces/4/'}, {u'children': [], u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'effective_mtu': 1500, u'system_id': u'stg4ws', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/interfaces/11/'}, {u'children': [], u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 63, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'effective_mtu': 1500, u'system_id': u'stg4ws', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/interfaces/12/'}, {u'children': [], u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'effective_mtu': 1500, u'system_id': u'stg4ws', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/interfaces/13/'}, {u'children': [], 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'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'effective_mtu': 1500, u'system_id': u'stg4ws', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/interfaces/14/'}, {u'children': [], 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'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'effective_mtu': 1500, u'system_id': u'stg4ws', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/interfaces/15/'}], u'current_testing_result_id': 20, u'cpu_test_status': -1, u'pool': {u'id': 0, u'resource_uri': u'/MAAS/api/2.0/resourcepool/0/', u'name': u'default', u'description': u'Default pool'}, u'storage_test_status': 2, u'locked': False, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/blockdevices/15/', u'uuid': u'3f6b81b2-ddda-42ea-b73e-0e641b3bd541', 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'4efca768-3dfb-47a1-be1e-d00cceff5ce8', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'stg4ws', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 15, u'name': u'vgroot-lvroot'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'children': [], u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 62, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': [], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'system_id': u'stg4ws', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/stg4ws/interfaces/4/'}, u'current_commissioning_result_id': 19, u'commissioning_status_name': u'Passed', u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2020-02-22 01:10:27,771 [salt.state       :300 ][INFO    ][6979] {'new': {'storage_layout': 'lvm'}}
2020-02-22 01:10:27,772 [salt.state       :1951][INFO    ][6979] Completed state [maas_machines_storage_cmp002_lvm] at time 01:10:27.771962 duration_in_ms=2537.876
2020-02-22 01:10:27,773 [salt.state       :1780][INFO    ][6979] Running state [maas_machines_storage_cmp001_lvm] at time 01:10:27.773075
2020-02-22 01:10:27,773 [salt.state       :1813][INFO    ][6979] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2020-02-22 01:10:29,015 [salt.loaded.ext.module.maasng:610 ][INFO    ][6979] k7e64f
2020-02-22 01:10:29,016 [salt.loaded.ext.module.maasng:626 ][INFO    ][6979] sda
2020-02-22 01:10:29,659 [salt.loaded.ext.module.maasng:361 ][INFO    ][6979] k7e64f
2020-02-22 01:10:29,737 [salt.loaded.ext.module.maasng:367 ][INFO    ][6979] [{u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/blockdevices/2/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'd708e91a-5ca9-446a-b572-88ccd0f5b7b9', u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/blockdevices/2/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'k7e64f', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'b34e1fcc-c743-46b7-82e0-f78824fd324b', 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'used_for': u'MBR partitioned with 1 partition', u'system_id': u'k7e64f', 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'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/blockdevices/14/', u'available_size': 0, u'uuid': u'708d7028-a7d6-4040-a9ce-a2335705bd9b', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'k7e64f', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'03a0b938-a47f-487b-ae6b-b40fe9545d87', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 14, u'serial': None, u'size': 800097042432}]
2020-02-22 01:10:29,738 [salt.loaded.ext.module.maasng:632 ][INFO    ][6979] vgroot
2020-02-22 01:10:29,738 [salt.loaded.ext.module.maasng:635 ][INFO    ][6979] lvroot
2020-02-22 01:10:29,738 [salt.loaded.ext.module.maasng:639 ][INFO    ][6979] 107374182400
2020-02-22 01:10:30,300 [salt.loaded.ext.module.maasng:645 ][INFO    ][6979] {u'node_type_name': u'Machine', u'hwe_kernel': None, u'testing_status_name': u'Passed', 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'status_action': u'', u'tag_names': [], u'swap_size': None, u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'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/k7e64f/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'k7e64f', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'firmware_version': u'5.22', u'size': 800109715456, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'1f2a4ea1-4f6e-4ec0-96ca-9202e63b4ac5', u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/blockdevices/2/partition/11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'k7e64f', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'3def5a40-e439-4d7a-8dfe-def4c0921807', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 11, u'device_id': 2}]}, u'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/k7e64f/', 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'k7e64f', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'uuid': u'27c15174-860f-49e4-a8ae-6d0880f2e926', 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/k7e64f/blockdevices/16/', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'k7e64f', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'1a657d2d-cbaf-48f9-956f-fe6f9ac0d5d4', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 16, u'size': 107374182400}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'1f2a4ea1-4f6e-4ec0-96ca-9202e63b4ac5', u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/blockdevices/2/partition/11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'k7e64f', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'3def5a40-e439-4d7a-8dfe-def4c0921807', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 11, u'device_id': 2}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'k7e64f', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/blockdevices/16/', u'uuid': u'27c15174-860f-49e4-a8ae-6d0880f2e926', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'k7e64f', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'1a657d2d-cbaf-48f9-956f-fe6f9ac0d5d4', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 16, 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'ip_addresses': [], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'k7e64f', u'id': 11}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 70, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [], u'parents': [], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'k7e64f', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/interfaces/5/'}, u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 70, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [], u'parents': [], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'k7e64f', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/interfaces/5/'}, {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'children': [], u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'k7e64f', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/interfaces/16/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'n4xe8n', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 71, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [], u'parents': [], u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'k7e64f', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/interfaces/17/'}, {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'children': [], u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'k7e64f', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/interfaces/18/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'fabric': u'fabric-0', 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'name': u'untagged'}, u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 72, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'k7e64f', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/interfaces/19/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'fabric': u'fabric-0', 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'name': u'untagged'}, u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 73, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'k7e64f', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/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'architecture': u'amd64/generic', u'bcaches': [], u'locked': False, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'k7e64f', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'firmware_version': u'5.22', u'size': 800109715456, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'1f2a4ea1-4f6e-4ec0-96ca-9202e63b4ac5', u'resource_uri': u'/MAAS/api/2.0/nodes/k7e64f/blockdevices/2/partition/11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'k7e64f', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'3def5a40-e439-4d7a-8dfe-def4c0921807', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 11, u'device_id': 2}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 21, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2020-02-22 01:10:30,303 [salt.state       :300 ][INFO    ][6979] {'new': {'storage_layout': 'lvm'}}
2020-02-22 01:10:30,303 [salt.state       :1951][INFO    ][6979] Completed state [maas_machines_storage_cmp001_lvm] at time 01:10:30.303745 duration_in_ms=2530.67
2020-02-22 01:10:30,307 [salt.minion      :1711][INFO    ][6979] Returning information for job: 20200222011021691891
2020-02-22 01:10:31,301 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command state.apply with jid 20200222011031288255
2020-02-22 01:10:31,325 [salt.minion      :1432][INFO    ][6999] Starting a new job with PID 6999
2020-02-22 01:10:37,816 [salt.state       :915 ][INFO    ][6999] Loading fresh modules for state activity
2020-02-22 01:10:37,924 [salt.state       :1780][INFO    ][6999] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:10:37.924016
2020-02-22 01:10:37,924 [salt.state       :1813][INFO    ][6999] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-22 01:10:37,927 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6999] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-22 01:10:40,257 [salt.state       :300 ][INFO    ][6999] {'pid': 7013, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-22 01:10:40,258 [salt.state       :1951][INFO    ][6999] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:10:40.258127 duration_in_ms=2334.118
2020-02-22 01:10:40,260 [salt.state       :1780][INFO    ][6999] Running state [maas.deploy_machines] at time 01:10:40.259985
2020-02-22 01:10:40,261 [salt.state       :1813][INFO    ][6999] Executing state module.run for [maas.deploy_machines]
2020-02-22 01:10:40,262 [salt.utils.decorators:613 ][WARNING ][6999] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 01:10:40,864 [salt.loaded.ext.module.maas:684 ][INFO    ][6999] deploymachines hwe_kernel=ga-18.04 system_id=stg4ws distro_series=bionic
2020-02-22 01:10:42,881 [salt.loaded.ext.module.maas:684 ][INFO    ][6999] deploymachines hwe_kernel=ga-18.04 system_id=k7e64f distro_series=bionic
2020-02-22 01:10:44,816 [salt.state       :300 ][INFO    ][6999] {'ret': {'updated': ['kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': ['cmp002', 'cmp001']}}
2020-02-22 01:10:44,817 [salt.state       :1951][INFO    ][6999] Completed state [maas.deploy_machines] at time 01:10:44.817118 duration_in_ms=4557.131
2020-02-22 01:10:44,824 [salt.minion      :1711][INFO    ][6999] Returning information for job: 20200222011031288255
2020-02-22 01:10:45,801 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command state.apply with jid 20200222011045791289
2020-02-22 01:10:45,824 [salt.minion      :1432][INFO    ][7053] Starting a new job with PID 7053
2020-02-22 01:10:46,851 [salt.state       :915 ][INFO    ][7053] Loading fresh modules for state activity
2020-02-22 01:10:46,964 [salt.state       :1780][INFO    ][7053] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:10:46.964636
2020-02-22 01:10:46,965 [salt.state       :1813][INFO    ][7053] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-22 01:10:46,967 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7053] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-22 01:10:49,204 [salt.state       :300 ][INFO    ][7053] {'pid': 7062, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-22 01:10:49,205 [salt.state       :1951][INFO    ][7053] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:10:49.205114 duration_in_ms=2240.478
2020-02-22 01:10:49,207 [salt.state       :1780][INFO    ][7053] Running state [maas.wait_for_machine_status] at time 01:10:49.206992
2020-02-22 01:10:49,207 [salt.state       :1813][INFO    ][7053] Executing state module.run for [maas.wait_for_machine_status]
2020-02-22 01:10:49,207 [salt.utils.decorators:613 ][WARNING ][7053] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-22 01:10:52,291 [salt.loaded.ext.module.maas:1024][INFO    ][7053] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2246.92786503s left)
2020-02-22 01:11:00,970 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222011100914470
2020-02-22 01:11:00,995 [salt.minion      :1432][INFO    ][7077] Starting a new job with PID 7077
2020-02-22 01:11:01,026 [salt.minion      :1711][INFO    ][7077] Returning information for job: 20200222011100914470
2020-02-22 01:11:25,635 [salt.loaded.ext.module.maas:1024][INFO    ][7053] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2213.58370209s left)
2020-02-22 01:11:31,062 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222011131049913
2020-02-22 01:11:31,082 [salt.minion      :1432][INFO    ][7097] Starting a new job with PID 7097
2020-02-22 01:11:31,111 [salt.minion      :1711][INFO    ][7097] Returning information for job: 20200222011131049913
2020-02-22 01:11:58,682 [salt.loaded.ext.module.maas:1024][INFO    ][7053] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2180.536901s left)
2020-02-22 01:12:01,133 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222011201121398
2020-02-22 01:12:01,155 [salt.minion      :1432][INFO    ][7149] Starting a new job with PID 7149
2020-02-22 01:12:01,183 [salt.minion      :1711][INFO    ][7149] Returning information for job: 20200222011201121398
2020-02-22 01:12:31,214 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222011231199960
2020-02-22 01:12:31,241 [salt.minion      :1432][INFO    ][7170] Starting a new job with PID 7170
2020-02-22 01:12:31,276 [salt.minion      :1711][INFO    ][7170] Returning information for job: 20200222011231199960
2020-02-22 01:12:31,893 [salt.loaded.ext.module.maas:1024][INFO    ][7053] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2147.32614112s left)
2020-02-22 01:13:01,294 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222011301282861
2020-02-22 01:13:01,314 [salt.minion      :1432][INFO    ][7202] Starting a new job with PID 7202
2020-02-22 01:13:01,341 [salt.minion      :1711][INFO    ][7202] Returning information for job: 20200222011301282861
2020-02-22 01:13:05,265 [salt.loaded.ext.module.maas:1024][INFO    ][7053] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2113.95387912s left)
2020-02-22 01:13:31,361 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222011331352121
2020-02-22 01:13:31,386 [salt.minion      :1432][INFO    ][7235] Starting a new job with PID 7235
2020-02-22 01:13:31,415 [salt.minion      :1711][INFO    ][7235] Returning information for job: 20200222011331352121
2020-02-22 01:13:38,469 [salt.loaded.ext.module.maas:1024][INFO    ][7053] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2080.75026107s left)
2020-02-22 01:14:01,439 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222011401429520
2020-02-22 01:14:01,467 [salt.minion      :1432][INFO    ][7299] Starting a new job with PID 7299
2020-02-22 01:14:01,491 [salt.minion      :1711][INFO    ][7299] Returning information for job: 20200222011401429520
2020-02-22 01:14:12,127 [salt.loaded.ext.module.maas:1024][INFO    ][7053] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2047.09172511s left)
2020-02-22 01:14:31,540 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222011431525288
2020-02-22 01:14:31,565 [salt.minion      :1432][INFO    ][7348] Starting a new job with PID 7348
2020-02-22 01:14:31,590 [salt.minion      :1711][INFO    ][7348] Returning information for job: 20200222011431525288
2020-02-22 01:14:46,014 [salt.loaded.ext.module.maas:1024][INFO    ][7053] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2013.20523405s left)
2020-02-22 01:15:01,639 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222011501624988
2020-02-22 01:15:01,663 [salt.minion      :1432][INFO    ][7383] Starting a new job with PID 7383
2020-02-22 01:15:01,691 [salt.minion      :1711][INFO    ][7383] Returning information for job: 20200222011501624988
2020-02-22 01:15:19,634 [salt.loaded.ext.module.maas:1024][INFO    ][7053] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1979.58527303s left)
2020-02-22 01:15:31,728 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222011531719488
2020-02-22 01:15:31,756 [salt.minion      :1432][INFO    ][7406] Starting a new job with PID 7406
2020-02-22 01:15:31,783 [salt.minion      :1711][INFO    ][7406] Returning information for job: 20200222011531719488
2020-02-22 01:15:53,043 [salt.loaded.ext.module.maas:1024][INFO    ][7053] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1946.17582607s left)
2020-02-22 01:16:01,851 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222011601833226
2020-02-22 01:16:01,878 [salt.minion      :1432][INFO    ][7441] Starting a new job with PID 7441
2020-02-22 01:16:01,906 [salt.minion      :1711][INFO    ][7441] Returning information for job: 20200222011601833226
2020-02-22 01:16:26,563 [salt.loaded.ext.module.maas:1024][INFO    ][7053] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1912.65615201s left)
2020-02-22 01:16:31,962 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222011631950044
2020-02-22 01:16:31,987 [salt.minion      :1432][INFO    ][7466] Starting a new job with PID 7466
2020-02-22 01:16:32,027 [salt.minion      :1711][INFO    ][7466] Returning information for job: 20200222011631950044
2020-02-22 01:17:00,010 [salt.loaded.ext.module.maas:1024][INFO    ][7053] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1879.20907903s left)
2020-02-22 01:17:02,103 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222011702090912
2020-02-22 01:17:02,126 [salt.minion      :1432][INFO    ][7505] Starting a new job with PID 7505
2020-02-22 01:17:02,154 [salt.minion      :1711][INFO    ][7505] Returning information for job: 20200222011702090912
2020-02-22 01:17:32,203 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222011732187852
2020-02-22 01:17:32,226 [salt.minion      :1432][INFO    ][7524] Starting a new job with PID 7524
2020-02-22 01:17:32,253 [salt.minion      :1711][INFO    ][7524] Returning information for job: 20200222011732187852
2020-02-22 01:17:33,371 [salt.loaded.ext.module.maas:1024][INFO    ][7053] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1845.84786606s left)
2020-02-22 01:18:02,323 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222011802312694
2020-02-22 01:18:02,350 [salt.minion      :1432][INFO    ][7556] Starting a new job with PID 7556
2020-02-22 01:18:02,374 [salt.minion      :1711][INFO    ][7556] Returning information for job: 20200222011802312694
2020-02-22 01:18:07,152 [salt.loaded.ext.module.maas:1024][INFO    ][7053] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1812.06749606s left)
2020-02-22 01:18:32,440 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222011832429179
2020-02-22 01:18:32,469 [salt.minion      :1432][INFO    ][7577] Starting a new job with PID 7577
2020-02-22 01:18:32,493 [salt.minion      :1711][INFO    ][7577] Returning information for job: 20200222011832429179
2020-02-22 01:18:40,539 [salt.loaded.ext.module.maas:1024][INFO    ][7053] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1778.67973304s left)
2020-02-22 01:19:02,571 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222011902559921
2020-02-22 01:19:02,595 [salt.minion      :1432][INFO    ][7609] Starting a new job with PID 7609
2020-02-22 01:19:02,623 [salt.minion      :1711][INFO    ][7609] Returning information for job: 20200222011902559921
2020-02-22 01:19:13,965 [salt.loaded.ext.module.maas:1024][INFO    ][7053] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1745.25440192s left)
2020-02-22 01:19:32,703 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222011932687969
2020-02-22 01:19:32,727 [salt.minion      :1432][INFO    ][7632] Starting a new job with PID 7632
2020-02-22 01:19:32,752 [salt.minion      :1711][INFO    ][7632] Returning information for job: 20200222011932687969
2020-02-22 01:19:47,455 [salt.loaded.ext.module.maas:1024][INFO    ][7053] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1711.76454997s left)
2020-02-22 01:20:02,853 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222012002835476
2020-02-22 01:20:02,884 [salt.minion      :1432][INFO    ][7668] Starting a new job with PID 7668
2020-02-22 01:20:02,909 [salt.minion      :1711][INFO    ][7668] Returning information for job: 20200222012002835476
2020-02-22 01:20:21,019 [salt.loaded.ext.module.maas:1024][INFO    ][7053] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1678.19984293s left)
2020-02-22 01:20:33,009 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222012032996712
2020-02-22 01:20:33,033 [salt.minion      :1432][INFO    ][7691] Starting a new job with PID 7691
2020-02-22 01:20:33,058 [salt.minion      :1711][INFO    ][7691] Returning information for job: 20200222012032996712
2020-02-22 01:20:54,769 [salt.loaded.ext.module.maas:1024][INFO    ][7053] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1644.45134902s left)
2020-02-22 01:21:03,131 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222012103119807
2020-02-22 01:21:03,158 [salt.minion      :1432][INFO    ][7743] Starting a new job with PID 7743
2020-02-22 01:21:03,183 [salt.minion      :1711][INFO    ][7743] Returning information for job: 20200222012103119807
2020-02-22 01:21:28,066 [salt.loaded.ext.module.maas:1024][INFO    ][7053] Waiting status:Deployed for machines:['cmp001']
sleep for:30s Timeout:2250s (1611.15293908s left)
2020-02-22 01:21:33,294 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command saltutil.find_job with jid 20200222012133285008
2020-02-22 01:21:33,318 [salt.minion      :1432][INFO    ][7774] Starting a new job with PID 7774
2020-02-22 01:21:33,346 [salt.minion      :1711][INFO    ][7774] Returning information for job: 20200222012133285008
2020-02-22 01:22:01,463 [salt.state       :300 ][INFO    ][7053] {'ret': True}
2020-02-22 01:22:01,465 [salt.state       :1951][INFO    ][7053] Completed state [maas.wait_for_machine_status] at time 01:22:01.465270 duration_in_ms=672258.276
2020-02-22 01:22:01,471 [salt.minion      :1711][INFO    ][7053] Returning information for job: 20200222011045791289
2020-02-22 01:22:20,066 [salt.utils.schedule:1377][INFO    ][3088] Running scheduled job: __mine_interval
2020-02-22 02:22:20,065 [salt.utils.schedule:1377][INFO    ][3088] Running scheduled job: __mine_interval
2020-02-22 03:03:03,402 [salt.minion      :1308][INFO    ][3088] User sudo_ubuntu Executing command cp.push_dir with jid 20200222030303388648
2020-02-22 03:03:03,437 [salt.minion      :1432][INFO    ][11434] Starting a new job with PID 11434
