2020-02-21 04:23:47,728 [salt.utils.decorators:613 ][WARNING ][1501] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-21 04:23:48,742 [salt.utils.decorators:613 ][WARNING ][1501] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-21 04:23:57,786 [salt.loaded.int.states.file:2298][WARNING ][1681] 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-21 04:24:05,146 [salt.state       :2022][WARNING ][1895] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-02-21 04:24:05,891 [salt.utils.decorators:613 ][WARNING ][1895] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-21 04:24:22,397 [salt.utils.decorators:613 ][WARNING ][1895] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-21 04:24:49,295 [salt.utils.decorators:613 ][WARNING ][1895] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-21 04:24:50,029 [salt.utils.decorators:613 ][WARNING ][1895] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-21 04:24:52,290 [salt.loaded.ext.module.maasng:1008][WARNING ][1895] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2020-02-21 04:24:52,290 [salt.loaded.ext.module.maasng:1011][WARNING ][1895] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2020-02-21 04:24:52,851 [salt.loaded.ext.module.maasng:1235][WARNING ][1895] Ignoring parameter vlan:0
2020-02-21 04:24:53,555 [salt.utils.decorators:613 ][WARNING ][1895] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-21 04:24:56,602 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3310] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2020-02-21 04:24:56,642 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3310] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2020-02-21 04:24:56,685 [salt.utils.parsers:1051][WARNING ][154] Minion received a SIGTERM. Exiting.
2020-02-21 04:24:57,763 [salt.cli.daemons :293 ][INFO    ][3380] Setting up the Salt Minion "mas01.mcp-odl-noha.local"
2020-02-21 04:24:57,947 [salt.cli.daemons :82  ][INFO    ][3380] Starting up the Salt Minion
2020-02-21 04:24:57,947 [salt.utils.event :1017][INFO    ][3380] Starting pull socket on /var/run/salt/minion/minion_event_0f3e0c7467_pull.ipc
2020-02-21 04:24:59,157 [salt.minion      :976 ][INFO    ][3380] Creating minion process manager
2020-02-21 04:25:00,985 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3380] Executing command ['date', '+%z'] in directory '/root'
2020-02-21 04:25:01,011 [salt.utils.schedule:568 ][INFO    ][3380] Updating job settings for scheduled job: __mine_interval
2020-02-21 04:25:01,013 [salt.minion      :1108][INFO    ][3380] Added mine.update to scheduler
2020-02-21 04:25:01,018 [salt.minion      :1975][INFO    ][3380] Minion is starting as user 'root'
2020-02-21 04:25:01,037 [salt.minion      :2336][INFO    ][3380] Minion is ready to receive requests!
2020-02-21 04:25:03,379 [salt.utils.decorators:613 ][WARNING ][3334] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-21 04:25:03,424 [salt.loaded.ext.module.maas:412 ][WARNING ][3334] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-21 04:25:04,307 [salt.loaded.ext.module.maas:412 ][WARNING ][3334] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-21 04:25:05,155 [salt.loaded.ext.module.maas:412 ][WARNING ][3334] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-21 04:25:05,999 [salt.loaded.ext.module.maas:412 ][WARNING ][3334] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-21 04:25:06,764 [salt.loaded.ext.module.maas:412 ][WARNING ][3334] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-21 04:25:39,172 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command state.apply with jid 20200221042539155862
2020-02-21 04:25:39,197 [salt.minion      :1432][INFO    ][3554] Starting a new job with PID 3554
2020-02-21 04:25:45,505 [salt.state       :915 ][INFO    ][3554] Loading fresh modules for state activity
2020-02-21 04:25:45,570 [salt.fileclient  :1219][INFO    ][3554] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2020-02-21 04:25:45,626 [salt.state       :1780][INFO    ][3554] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:25:45.626593
2020-02-21 04:25:45,626 [salt.state       :1813][INFO    ][3554] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-21 04:25:45,629 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3554] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-21 04:25:47,642 [salt.state       :300 ][INFO    ][3554] {'pid': 3561, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-21 04:25:47,643 [salt.state       :1951][INFO    ][3554] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:25:47.643700 duration_in_ms=2017.107
2020-02-21 04:25:47,647 [salt.state       :1780][INFO    ][3554] Running state [maas.wait_for_machine_status] at time 04:25:47.647323
2020-02-21 04:25:47,647 [salt.state       :1813][INFO    ][3554] Executing state module.run for [maas.wait_for_machine_status]
2020-02-21 04:25:47,649 [salt.utils.decorators:613 ][WARNING ][3554] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-21 04:25:48,611 [salt.loaded.ext.module.maas:1024][INFO    ][3554] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.04931307s left)
2020-02-21 04:25:54,245 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221042554234653
2020-02-21 04:25:54,267 [salt.minion      :1432][INFO    ][3571] Starting a new job with PID 3571
2020-02-21 04:25:54,289 [salt.minion      :1711][INFO    ][3571] Returning information for job: 20200221042554234653
2020-02-21 04:26:19,537 [salt.loaded.ext.module.maas:1024][INFO    ][3554] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.12353611s left)
2020-02-21 04:26:24,333 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221042624317969
2020-02-21 04:26:24,357 [salt.minion      :1432][INFO    ][3603] Starting a new job with PID 3603
2020-02-21 04:26:24,378 [salt.minion      :1711][INFO    ][3603] Returning information for job: 20200221042624317969
2020-02-21 04:26:50,520 [salt.loaded.ext.module.maas:1024][INFO    ][3554] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1437.14077902s left)
2020-02-21 04:26:54,411 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221042654395163
2020-02-21 04:26:54,438 [salt.minion      :1432][INFO    ][3622] Starting a new job with PID 3622
2020-02-21 04:26:54,459 [salt.minion      :1711][INFO    ][3622] Returning information for job: 20200221042654395163
2020-02-21 04:27:21,447 [salt.loaded.ext.module.maas:1024][INFO    ][3554] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1406.21374202s left)
2020-02-21 04:27:24,495 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221042724476719
2020-02-21 04:27:24,520 [salt.minion      :1432][INFO    ][3654] Starting a new job with PID 3654
2020-02-21 04:27:24,539 [salt.minion      :1711][INFO    ][3654] Returning information for job: 20200221042724476719
2020-02-21 04:27:52,592 [salt.loaded.ext.module.maas:1024][INFO    ][3554] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1375.06780505s left)
2020-02-21 04:27:54,534 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221042754524960
2020-02-21 04:27:54,558 [salt.minion      :1432][INFO    ][3685] Starting a new job with PID 3685
2020-02-21 04:27:54,575 [salt.minion      :1711][INFO    ][3685] Returning information for job: 20200221042754524960
2020-02-21 04:28:24,095 [salt.loaded.ext.module.maas:1024][INFO    ][3554] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1343.56489301s left)
2020-02-21 04:28:24,620 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221042824606694
2020-02-21 04:28:24,646 [salt.minion      :1432][INFO    ][3750] Starting a new job with PID 3750
2020-02-21 04:28:24,669 [salt.minion      :1711][INFO    ][3750] Returning information for job: 20200221042824606694
2020-02-21 04:28:54,735 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221042854722279
2020-02-21 04:28:54,753 [salt.minion      :1432][INFO    ][3781] Starting a new job with PID 3781
2020-02-21 04:28:54,777 [salt.minion      :1711][INFO    ][3781] Returning information for job: 20200221042854722279
2020-02-21 04:28:55,446 [salt.loaded.ext.module.maas:1024][INFO    ][3554] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1312.21477604s left)
2020-02-21 04:29:24,833 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221042924819342
2020-02-21 04:29:24,849 [salt.minion      :1432][INFO    ][3857] Starting a new job with PID 3857
2020-02-21 04:29:24,873 [salt.minion      :1711][INFO    ][3857] Returning information for job: 20200221042924819342
2020-02-21 04:29:26,894 [salt.loaded.ext.module.maas:1024][INFO    ][3554] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1280.7661581s left)
2020-02-21 04:29:54,894 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221042954883870
2020-02-21 04:29:54,916 [salt.minion      :1432][INFO    ][3907] Starting a new job with PID 3907
2020-02-21 04:29:54,938 [salt.minion      :1711][INFO    ][3907] Returning information for job: 20200221042954883870
2020-02-21 04:29:58,969 [salt.loaded.ext.module.maas:1024][INFO    ][3554] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1248.69125509s left)
2020-02-21 04:30:25,000 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221043024980148
2020-02-21 04:30:25,019 [salt.minion      :1432][INFO    ][3990] Starting a new job with PID 3990
2020-02-21 04:30:25,043 [salt.minion      :1711][INFO    ][3990] Returning information for job: 20200221043024980148
2020-02-21 04:30:31,579 [salt.loaded.ext.module.maas:1024][INFO    ][3554] Waiting status:Ready|Deployed for machines:['gtw01', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1216.08141994s left)
2020-02-21 04:30:55,091 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221043055082000
2020-02-21 04:30:55,120 [salt.minion      :1432][INFO    ][4013] Starting a new job with PID 4013
2020-02-21 04:30:55,142 [salt.minion      :1711][INFO    ][4013] Returning information for job: 20200221043055082000
2020-02-21 04:31:04,097 [salt.loaded.ext.module.maas:1024][INFO    ][3554] Waiting status:Ready|Deployed for machines:['gtw01', 'odl01', 'ctl01']
sleep for:30s Timeout:1500s (1183.56304216s left)
2020-02-21 04:31:25,200 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221043125191867
2020-02-21 04:31:25,219 [salt.minion      :1432][INFO    ][4053] Starting a new job with PID 4053
2020-02-21 04:31:25,250 [salt.minion      :1711][INFO    ][4053] Returning information for job: 20200221043125191867
2020-02-21 04:31:36,579 [salt.loaded.ext.module.maas:1024][INFO    ][3554] Waiting status:Ready|Deployed for machines:['gtw01', 'odl01', 'ctl01']
sleep for:30s Timeout:1500s (1151.08176398s left)
2020-02-21 04:31:55,320 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221043155309791
2020-02-21 04:31:55,346 [salt.minion      :1432][INFO    ][4072] Starting a new job with PID 4072
2020-02-21 04:31:55,370 [salt.minion      :1711][INFO    ][4072] Returning information for job: 20200221043155309791
2020-02-21 04:32:10,042 [salt.loaded.ext.module.maas:1024][INFO    ][3554] Waiting status:Ready|Deployed for machines:['gtw01', 'odl01', 'ctl01']
sleep for:30s Timeout:1500s (1117.61865902s left)
2020-02-21 04:32:25,444 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221043225434439
2020-02-21 04:32:25,469 [salt.minion      :1432][INFO    ][4150] Starting a new job with PID 4150
2020-02-21 04:32:25,487 [salt.minion      :1711][INFO    ][4150] Returning information for job: 20200221043225434439
2020-02-21 04:32:43,610 [salt.state       :300 ][INFO    ][3554] {'ret': True}
2020-02-21 04:32:43,610 [salt.state       :1951][INFO    ][3554] Completed state [maas.wait_for_machine_status] at time 04:32:43.610564 duration_in_ms=415963.241
2020-02-21 04:32:43,614 [salt.minion      :1711][INFO    ][3554] Returning information for job: 20200221042539155862
2020-02-21 04:32:44,455 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command state.apply with jid 20200221043244439098
2020-02-21 04:32:44,479 [salt.minion      :1432][INFO    ][4179] Starting a new job with PID 4179
2020-02-21 04:32:50,685 [salt.state       :915 ][INFO    ][4179] Loading fresh modules for state activity
2020-02-21 04:32:50,745 [salt.fileclient  :1219][INFO    ][4179] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2020-02-21 04:32:50,851 [salt.state       :1780][INFO    ][4179] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:32:50.851040
2020-02-21 04:32:50,851 [salt.state       :1813][INFO    ][4179] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-21 04:32:50,853 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4179] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-21 04:32:52,994 [salt.state       :300 ][INFO    ][4179] {'pid': 4186, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-21 04:32:52,995 [salt.state       :1951][INFO    ][4179] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:32:52.995294 duration_in_ms=2144.252
2020-02-21 04:32:52,997 [salt.state       :1780][INFO    ][4179] Running state [maas_machines_storage_cmp002_lvm] at time 04:32:52.997323
2020-02-21 04:32:52,997 [salt.state       :1813][INFO    ][4179] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2020-02-21 04:32:54,379 [salt.loaded.ext.module.maasng:610 ][INFO    ][4179] yhqde3
2020-02-21 04:32:54,380 [salt.loaded.ext.module.maasng:626 ][INFO    ][4179] sda
2020-02-21 04:32:55,028 [salt.loaded.ext.module.maasng:361 ][INFO    ][4179] yhqde3
2020-02-21 04:32:55,116 [salt.loaded.ext.module.maasng:367 ][INFO    ][4179] [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'9a05ce52-cf11-45d9-b312-ea4a8d1b4ea4', u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yhqde3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e19d602a-f634-4131-9171-1e35d14cbeda', 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'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'yhqde3', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/blockdevices/3/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'uuid': u'777c3c30-47df-4c7b-8582-822f8bd7e7fd', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'yhqde3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'4b6c8b8d-5207-409e-8762-2763a3aff3bf', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 3, u'size': 800097042432}]
2020-02-21 04:32:55,117 [salt.loaded.ext.module.maasng:632 ][INFO    ][4179] vgroot
2020-02-21 04:32:55,118 [salt.loaded.ext.module.maasng:635 ][INFO    ][4179] lvroot
2020-02-21 04:32:55,118 [salt.loaded.ext.module.maasng:639 ][INFO    ][4179] 107374182400
2020-02-21 04:32:55,762 [salt.loaded.ext.module.maasng:645 ][INFO    ][4179] {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'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': None, u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'yhqde3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/interfaces/5/'}, u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'9513a953-1280-4c10-85cf-1c279cd91e4c', u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yhqde3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'38015358-650d-4aaf-b27f-e75e7ef2834d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'yhqde3', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'uuid': None}, 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/yhqde3/', u'disable_ipv4': False, u'hardware_info': {u'mainboard_firmware_version': u'I31', u'mainboard_product': u'Unknown', u'system_vendor': u'HP', u'cpu_model': u'Intel(R) Xeon(R) CPU E5-2680 v2', u'mainboard_firmware_date': u'12/20/2013', u'mainboard_vendor': u'Unknown', u'system_serial': u'USE410T2Y0', u'system_version': u'Unknown', u'system_product': u'ProLiant BL460c Gen8 (735151-B21)'}, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'yhqde3', 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'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'9513a953-1280-4c10-85cf-1c279cd91e4c', u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yhqde3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'38015358-650d-4aaf-b27f-e75e7ef2834d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'yhqde3', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'firmware_version': u'5.22', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'uuid': None}], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'9513a953-1280-4c10-85cf-1c279cd91e4c', u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yhqde3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'38015358-650d-4aaf-b27f-e75e7ef2834d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'yhqde3', 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/yhqde3/blockdevices/11/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'65fd1cca-1d28-4754-890c-d9d52f134f78', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'yhqde3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'67a1e850-b004-433f-826f-b64273987e22', 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': 107374182400, u'id': 11, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'yhqde3', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'yhqde3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/interfaces/5/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 11, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'yhqde3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/interfaces/11/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'id': 12, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'yhqde3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/interfaces/12/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vendor': u'HP', u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 13, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'yhqde3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/interfaces/13/'}, {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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'id': 14, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'yhqde3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/interfaces/14/'}, {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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'id': 15, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'yhqde3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/interfaces/15/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'pool': {u'id': 0, u'resource_uri': u'/MAAS/api/2.0/resourcepool/0/', u'name': u'default', u'description': u'Default pool'}, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'locked': False, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/blockdevices/11/', u'available_size': 0, 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'yhqde3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'67a1e850-b004-433f-826f-b64273987e22', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'uuid': u'65fd1cca-1d28-4754-890c-d9d52f134f78'}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2020-02-21 04:32:55,763 [salt.state       :300 ][INFO    ][4179] {'new': {'storage_layout': 'lvm'}}
2020-02-21 04:32:55,763 [salt.state       :1951][INFO    ][4179] Completed state [maas_machines_storage_cmp002_lvm] at time 04:32:55.763829 duration_in_ms=2766.505
2020-02-21 04:32:55,766 [salt.state       :1780][INFO    ][4179] Running state [maas_machines_storage_cmp001_lvm] at time 04:32:55.764213
2020-02-21 04:32:55,766 [salt.state       :1813][INFO    ][4179] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2020-02-21 04:32:57,105 [salt.loaded.ext.module.maasng:610 ][INFO    ][4179] xmhcy8
2020-02-21 04:32:57,105 [salt.loaded.ext.module.maasng:626 ][INFO    ][4179] sda
2020-02-21 04:32:57,762 [salt.loaded.ext.module.maasng:361 ][INFO    ][4179] xmhcy8
2020-02-21 04:32:57,847 [salt.loaded.ext.module.maasng:367 ][INFO    ][4179] [{u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'65c7b1a3-1bf7-4702-bf3c-d493eca009c4', u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xmhcy8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'9443fcec-7a32-42e0-8ec1-19548b824748', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'device_id': 2}], u'uuid': None, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'xmhcy8', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/4/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'uuid': u'01705b1d-9a7a-43ab-a1b9-d17193bd568b', u'id': 4, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xmhcy8', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'7fa3cc2d-c746-41d2-969d-62872ba5be7a', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'block_size': 4096, u'type': u'virtual', u'model': None, u'serial': None, u'size': 800097042432}]
2020-02-21 04:32:57,848 [salt.loaded.ext.module.maasng:632 ][INFO    ][4179] vgroot
2020-02-21 04:32:57,849 [salt.loaded.ext.module.maasng:635 ][INFO    ][4179] lvroot
2020-02-21 04:32:57,849 [salt.loaded.ext.module.maasng:639 ][INFO    ][4179] 107374182400
2020-02-21 04:32:58,503 [salt.loaded.ext.module.maasng:645 ][INFO    ][4179] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': None, u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 28, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'id': 7, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'xmhcy8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/interfaces/7/'}, u'fqdn': u'cmp001.maas', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, 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'c3a354fb-0855-4928-b2dc-16c16f590f66', u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xmhcy8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a86bb6ad-327f-4929-8f7d-4b2bd88b7585', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'id': 2, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/2/', u'system_id': u'xmhcy8', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'firmware_version': u'5.22', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/xmhcy8/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'xmhcy8', 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'disable_ipv4': False, u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/2/', u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'c3a354fb-0855-4928-b2dc-16c16f590f66', u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xmhcy8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a86bb6ad-327f-4929-8f7d-4b2bd88b7585', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'filesystem': None, u'id': 2, u'name': u'sda', u'system_id': u'xmhcy8', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/12/', u'uuid': u'db347a2a-abbb-499b-a8e1-1f3a463ac06e', 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'e29c349c-3813-4a62-a1ff-deab21918341', u'fstype': u'ext4'}, u'id': 12, u'name': u'vgroot-lvroot', u'system_id': u'xmhcy8', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'serial': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'xmhcy8', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 28, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'id': 7, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'xmhcy8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/interfaces/7/'}, {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'mac_address': u'38:ea:a7:8f:1f:d4', u'enabled': True, u'id': 16, u'discovered': None, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'xmhcy8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/interfaces/16/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 29, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a4', u'enabled': True, u'id': 17, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.44'}], u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'xmhcy8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/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'mac_address': u'38:ea:a7:8f:1f:d5', u'enabled': True, u'id': 18, u'discovered': None, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'xmhcy8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/interfaces/18/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 30, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cc', u'enabled': True, u'id': 19, u'discovered': None, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'xmhcy8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/interfaces/19/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 31, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cd', u'enabled': True, u'id': 20, u'discovered': None, u'parents': [], u'effective_mtu': 1500, u'params': u'', u'firmware_version': u'bc 7.8.24', u'system_id': u'xmhcy8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/interfaces/20/'}], u'current_testing_result_id': 9, 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'block_size': 4096, 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'c3a354fb-0855-4928-b2dc-16c16f590f66', u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xmhcy8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a86bb6ad-327f-4929-8f7d-4b2bd88b7585', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'id': 2, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/2/', u'system_id': u'xmhcy8', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'firmware_version': u'5.22', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'status_action': u'', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'block_size': 4096, u'uuid': u'db347a2a-abbb-499b-a8e1-1f3a463ac06e', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'id': 12, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/12/', u'system_id': u'xmhcy8', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'e29c349c-3813-4a62-a1ff-deab21918341', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'current_commissioning_result_id': 8, u'address_ttl': None, u'other_test_status': -1, 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'distro_series': u'', u'memory_test_status': -1}
2020-02-21 04:32:58,507 [salt.state       :300 ][INFO    ][4179] {'new': {'storage_layout': 'lvm'}}
2020-02-21 04:32:58,507 [salt.state       :1951][INFO    ][4179] Completed state [maas_machines_storage_cmp001_lvm] at time 04:32:58.507862 duration_in_ms=2743.646
2020-02-21 04:32:58,514 [salt.minion      :1711][INFO    ][4179] Returning information for job: 20200221043244439098
2020-02-21 04:32:59,348 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command state.apply with jid 20200221043259335470
2020-02-21 04:32:59,373 [salt.minion      :1432][INFO    ][4198] Starting a new job with PID 4198
2020-02-21 04:33:00,297 [salt.state       :915 ][INFO    ][4198] Loading fresh modules for state activity
2020-02-21 04:33:00,356 [salt.fileclient  :1219][INFO    ][4198] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2020-02-21 04:33:00,403 [salt.state       :1780][INFO    ][4198] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:33:00.403823
2020-02-21 04:33:00,404 [salt.state       :1813][INFO    ][4198] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-21 04:33:00,406 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4198] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-21 04:33:02,426 [salt.state       :300 ][INFO    ][4198] {'pid': 4211, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-21 04:33:02,427 [salt.state       :1951][INFO    ][4198] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:33:02.426974 duration_in_ms=2023.15
2020-02-21 04:33:02,430 [salt.state       :1780][INFO    ][4198] Running state [maas.deploy_machines] at time 04:33:02.430102
2020-02-21 04:33:02,430 [salt.state       :1813][INFO    ][4198] Executing state module.run for [maas.deploy_machines]
2020-02-21 04:33:02,431 [salt.utils.decorators:613 ][WARNING ][4198] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-21 04:33:03,109 [salt.loaded.ext.module.maas:684 ][INFO    ][4198] deploymachines hwe_kernel=ga-18.04 system_id=fnnyhg distro_series=bionic
2020-02-21 04:33:05,135 [salt.loaded.ext.module.maas:684 ][INFO    ][4198] deploymachines hwe_kernel=ga-18.04 system_id=yhqde3 distro_series=bionic
2020-02-21 04:33:07,114 [salt.loaded.ext.module.maas:684 ][INFO    ][4198] deploymachines hwe_kernel=ga-18.04 system_id=q6pccn distro_series=bionic
2020-02-21 04:33:09,137 [salt.loaded.ext.module.maas:684 ][INFO    ][4198] deploymachines hwe_kernel=ga-18.04 system_id=xmhcy8 distro_series=bionic
2020-02-21 04:33:11,107 [salt.loaded.ext.module.maas:684 ][INFO    ][4198] deploymachines hwe_kernel=ga-18.04 system_id=kpa8tw distro_series=bionic
2020-02-21 04:33:13,100 [salt.state       :300 ][INFO    ][4198] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']}}
2020-02-21 04:33:13,100 [salt.state       :1951][INFO    ][4198] Completed state [maas.deploy_machines] at time 04:33:13.100874 duration_in_ms=10670.771
2020-02-21 04:33:13,103 [salt.minion      :1711][INFO    ][4198] Returning information for job: 20200221043259335470
2020-02-21 04:33:13,916 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command state.apply with jid 20200221043313901316
2020-02-21 04:33:13,943 [salt.minion      :1432][INFO    ][4269] Starting a new job with PID 4269
2020-02-21 04:33:20,301 [salt.state       :915 ][INFO    ][4269] Loading fresh modules for state activity
2020-02-21 04:33:20,361 [salt.fileclient  :1219][INFO    ][4269] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2020-02-21 04:33:20,411 [salt.state       :1780][INFO    ][4269] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:33:20.411316
2020-02-21 04:33:20,411 [salt.state       :1813][INFO    ][4269] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-21 04:33:20,413 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4269] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-21 04:33:22,365 [salt.state       :300 ][INFO    ][4269] {'pid': 4280, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-21 04:33:22,366 [salt.state       :1951][INFO    ][4269] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:33:22.366333 duration_in_ms=1955.016
2020-02-21 04:33:22,371 [salt.state       :1780][INFO    ][4269] Running state [maas.wait_for_machine_status] at time 04:33:22.371126
2020-02-21 04:33:22,371 [salt.state       :1813][INFO    ][4269] Executing state module.run for [maas.wait_for_machine_status]
2020-02-21 04:33:22,372 [salt.utils.decorators:613 ][WARNING ][4269] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-21 04:33:25,865 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2246.52137399s left)
2020-02-21 04:33:28,938 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221043328925265
2020-02-21 04:33:28,959 [salt.minion      :1432][INFO    ][4289] Starting a new job with PID 4289
2020-02-21 04:33:28,983 [salt.minion      :1711][INFO    ][4289] Returning information for job: 20200221043328925265
2020-02-21 04:33:59,013 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221043359001926
2020-02-21 04:33:59,034 [salt.minion      :1432][INFO    ][4308] Starting a new job with PID 4308
2020-02-21 04:33:59,055 [salt.minion      :1711][INFO    ][4308] Returning information for job: 20200221043359001926
2020-02-21 04:33:59,358 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2213.02853703s left)
2020-02-21 04:34:29,090 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221043429075824
2020-02-21 04:34:29,117 [salt.minion      :1432][INFO    ][4359] Starting a new job with PID 4359
2020-02-21 04:34:29,135 [salt.minion      :1711][INFO    ][4359] Returning information for job: 20200221043429075824
2020-02-21 04:34:33,041 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2179.34500408s left)
2020-02-21 04:34:59,145 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221043459137629
2020-02-21 04:34:59,167 [salt.minion      :1432][INFO    ][4383] Starting a new job with PID 4383
2020-02-21 04:34:59,191 [salt.minion      :1711][INFO    ][4383] Returning information for job: 20200221043459137629
2020-02-21 04:35:06,654 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2145.73237491s left)
2020-02-21 04:35:29,215 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221043529203131
2020-02-21 04:35:29,247 [salt.minion      :1432][INFO    ][4418] Starting a new job with PID 4418
2020-02-21 04:35:29,270 [salt.minion      :1711][INFO    ][4418] Returning information for job: 20200221043529203131
2020-02-21 04:35:39,973 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2112.41295099s left)
2020-02-21 04:35:59,286 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221043559273131
2020-02-21 04:35:59,310 [salt.minion      :1432][INFO    ][4447] Starting a new job with PID 4447
2020-02-21 04:35:59,337 [salt.minion      :1711][INFO    ][4447] Returning information for job: 20200221043559273131
2020-02-21 04:36:13,312 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2079.07437491s left)
2020-02-21 04:36:29,371 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221043629359092
2020-02-21 04:36:29,394 [salt.minion      :1432][INFO    ][4512] Starting a new job with PID 4512
2020-02-21 04:36:29,416 [salt.minion      :1711][INFO    ][4512] Returning information for job: 20200221043629359092
2020-02-21 04:36:46,859 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2045.52723908s left)
2020-02-21 04:36:59,456 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221043659446471
2020-02-21 04:36:59,481 [salt.minion      :1432][INFO    ][4552] Starting a new job with PID 4552
2020-02-21 04:36:59,506 [salt.minion      :1711][INFO    ][4552] Returning information for job: 20200221043659446471
2020-02-21 04:37:18,245 [salt.loaded.ext.module.maas:993 ][INFO    ][4269] Machine yhqde3 mark broken
2020-02-21 04:37:29,550 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221043729538406
2020-02-21 04:37:29,576 [salt.minion      :1432][INFO    ][4625] Starting a new job with PID 4625
2020-02-21 04:37:29,604 [salt.minion      :1711][INFO    ][4625] Returning information for job: 20200221043729538406
2020-02-21 04:37:48,872 [salt.loaded.ext.module.maas:997 ][INFO    ][4269] Machine yhqde3 mark fixed
2020-02-21 04:37:49,965 [salt.loaded.ext.module.maas:684 ][INFO    ][4269] deploymachines hwe_kernel=ga-18.04 system_id=yhqde3 distro_series=bionic
2020-02-21 04:37:53,119 [salt.loaded.ext.module.maas:993 ][INFO    ][4269] Machine xmhcy8 mark broken
2020-02-21 04:37:59,651 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221043759642312
2020-02-21 04:37:59,669 [salt.minion      :1432][INFO    ][4671] Starting a new job with PID 4671
2020-02-21 04:37:59,695 [salt.minion      :1711][INFO    ][4671] Returning information for job: 20200221043759642312
2020-02-21 04:38:23,725 [salt.loaded.ext.module.maas:997 ][INFO    ][4269] Machine xmhcy8 mark fixed
2020-02-21 04:38:25,049 [salt.loaded.ext.module.maas:684 ][INFO    ][4269] deploymachines hwe_kernel=ga-18.04 system_id=xmhcy8 distro_series=bionic
2020-02-21 04:38:27,759 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1944.62711501s left)
2020-02-21 04:38:29,768 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221043829754943
2020-02-21 04:38:29,798 [salt.minion      :1432][INFO    ][4767] Starting a new job with PID 4767
2020-02-21 04:38:29,824 [salt.minion      :1711][INFO    ][4767] Returning information for job: 20200221043829754943
2020-02-21 04:38:59,866 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221043859854172
2020-02-21 04:38:59,883 [salt.minion      :1432][INFO    ][4799] Starting a new job with PID 4799
2020-02-21 04:38:59,907 [salt.minion      :1711][INFO    ][4799] Returning information for job: 20200221043859854172
2020-02-21 04:39:01,375 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1911.01138711s left)
2020-02-21 04:39:29,963 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221043929955530
2020-02-21 04:39:29,983 [salt.minion      :1432][INFO    ][4837] Starting a new job with PID 4837
2020-02-21 04:39:30,015 [salt.minion      :1711][INFO    ][4837] Returning information for job: 20200221043929955530
2020-02-21 04:39:34,957 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1877.429775s left)
2020-02-21 04:40:00,055 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221044000043121
2020-02-21 04:40:00,079 [salt.minion      :1432][INFO    ][4858] Starting a new job with PID 4858
2020-02-21 04:40:00,102 [salt.minion      :1711][INFO    ][4858] Returning information for job: 20200221044000043121
2020-02-21 04:40:08,424 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1843.96284199s left)
2020-02-21 04:40:30,195 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221044030186692
2020-02-21 04:40:30,212 [salt.minion      :1432][INFO    ][4901] Starting a new job with PID 4901
2020-02-21 04:40:30,234 [salt.minion      :1711][INFO    ][4901] Returning information for job: 20200221044030186692
2020-02-21 04:40:42,056 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1810.33002496s left)
2020-02-21 04:41:00,311 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221044100299978
2020-02-21 04:41:00,328 [salt.minion      :1432][INFO    ][4930] Starting a new job with PID 4930
2020-02-21 04:41:00,355 [salt.minion      :1711][INFO    ][4930] Returning information for job: 20200221044100299978
2020-02-21 04:41:15,567 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1776.81933999s left)
2020-02-21 04:41:30,443 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221044130430976
2020-02-21 04:41:30,466 [salt.minion      :1432][INFO    ][4988] Starting a new job with PID 4988
2020-02-21 04:41:30,491 [salt.minion      :1711][INFO    ][4988] Returning information for job: 20200221044130430976
2020-02-21 04:41:47,035 [salt.loaded.ext.module.maas:993 ][INFO    ][4269] Machine yhqde3 mark broken
2020-02-21 04:42:00,567 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221044200557130
2020-02-21 04:42:00,585 [salt.minion      :1432][INFO    ][5030] Starting a new job with PID 5030
2020-02-21 04:42:00,607 [salt.minion      :1711][INFO    ][5030] Returning information for job: 20200221044200557130
2020-02-21 04:42:17,673 [salt.loaded.ext.module.maas:997 ][INFO    ][4269] Machine yhqde3 mark fixed
2020-02-21 04:42:18,205 [salt.loaded.ext.module.maas:1001][INFO    ][4269] Machine yhqde3 fio test
2020-02-21 04:42:21,679 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1710.70744205s left)
2020-02-21 04:42:30,683 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221044230674468
2020-02-21 04:42:30,706 [salt.minion      :1432][INFO    ][5077] Starting a new job with PID 5077
2020-02-21 04:42:30,728 [salt.minion      :1711][INFO    ][5077] Returning information for job: 20200221044230674468
2020-02-21 04:42:54,811 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1677.57553196s left)
2020-02-21 04:43:00,824 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221044300812908
2020-02-21 04:43:00,851 [salt.minion      :1432][INFO    ][5116] Starting a new job with PID 5116
2020-02-21 04:43:00,879 [salt.minion      :1711][INFO    ][5116] Returning information for job: 20200221044300812908
2020-02-21 04:43:27,267 [salt.loaded.ext.module.maas:993 ][INFO    ][4269] Machine xmhcy8 mark broken
2020-02-21 04:43:30,975 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221044330964903
2020-02-21 04:43:31,005 [salt.minion      :1432][INFO    ][5154] Starting a new job with PID 5154
2020-02-21 04:43:31,027 [salt.minion      :1711][INFO    ][5154] Returning information for job: 20200221044330964903
2020-02-21 04:43:57,888 [salt.loaded.ext.module.maas:997 ][INFO    ][4269] Machine xmhcy8 mark fixed
2020-02-21 04:43:58,457 [salt.loaded.ext.module.maas:1001][INFO    ][4269] Machine xmhcy8 fio test
2020-02-21 04:44:00,414 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1611.97194099s left)
2020-02-21 04:44:01,137 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221044401127659
2020-02-21 04:44:01,167 [salt.minion      :1432][INFO    ][5186] Starting a new job with PID 5186
2020-02-21 04:44:01,187 [salt.minion      :1711][INFO    ][5186] Returning information for job: 20200221044401127659
2020-02-21 04:44:31,290 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221044431277095
2020-02-21 04:44:31,310 [salt.minion      :1432][INFO    ][5233] Starting a new job with PID 5233
2020-02-21 04:44:31,330 [salt.minion      :1711][INFO    ][5233] Returning information for job: 20200221044431277095
2020-02-21 04:44:33,422 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1578.96444011s left)
2020-02-21 04:45:01,456 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221044501446024
2020-02-21 04:45:01,474 [salt.minion      :1432][INFO    ][5260] Starting a new job with PID 5260
2020-02-21 04:45:01,498 [salt.minion      :1711][INFO    ][5260] Returning information for job: 20200221044501446024
2020-02-21 04:45:06,299 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1546.08755112s left)
2020-02-21 04:45:31,627 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221044531617341
2020-02-21 04:45:31,648 [salt.minion      :1432][INFO    ][5309] Starting a new job with PID 5309
2020-02-21 04:45:31,668 [salt.minion      :1711][INFO    ][5309] Returning information for job: 20200221044531617341
2020-02-21 04:45:39,303 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1513.08341002s left)
2020-02-21 04:46:01,787 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221044601777088
2020-02-21 04:46:01,817 [salt.minion      :1432][INFO    ][5328] Starting a new job with PID 5328
2020-02-21 04:46:01,847 [salt.minion      :1711][INFO    ][5328] Returning information for job: 20200221044601777088
2020-02-21 04:46:12,465 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1479.92117691s left)
2020-02-21 04:46:31,979 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221044631965528
2020-02-21 04:46:32,003 [salt.minion      :1432][INFO    ][5368] Starting a new job with PID 5368
2020-02-21 04:46:32,030 [salt.minion      :1711][INFO    ][5368] Returning information for job: 20200221044631965528
2020-02-21 04:46:45,565 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1446.82130003s left)
2020-02-21 04:47:02,086 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221044702078027
2020-02-21 04:47:02,104 [salt.minion      :1432][INFO    ][5433] Starting a new job with PID 5433
2020-02-21 04:47:02,121 [salt.minion      :1711][INFO    ][5433] Returning information for job: 20200221044702078027
2020-02-21 04:47:18,895 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1413.49186397s left)
2020-02-21 04:47:32,223 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221044732213714
2020-02-21 04:47:32,238 [salt.minion      :1432][INFO    ][5514] Starting a new job with PID 5514
2020-02-21 04:47:32,263 [salt.minion      :1711][INFO    ][5514] Returning information for job: 20200221044732213714
2020-02-21 04:47:52,090 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1380.29610896s left)
2020-02-21 04:48:02,432 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221044802414010
2020-02-21 04:48:02,458 [salt.minion      :1432][INFO    ][5536] Starting a new job with PID 5536
2020-02-21 04:48:02,483 [salt.minion      :1711][INFO    ][5536] Returning information for job: 20200221044802414010
2020-02-21 04:48:25,222 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1347.16415501s left)
2020-02-21 04:48:32,655 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221044832646196
2020-02-21 04:48:32,681 [salt.minion      :1432][INFO    ][5577] Starting a new job with PID 5577
2020-02-21 04:48:32,703 [salt.minion      :1711][INFO    ][5577] Returning information for job: 20200221044832646196
2020-02-21 04:48:58,290 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1314.09639907s left)
2020-02-21 04:49:02,679 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221044902668623
2020-02-21 04:49:02,703 [salt.minion      :1432][INFO    ][5596] Starting a new job with PID 5596
2020-02-21 04:49:02,726 [salt.minion      :1711][INFO    ][5596] Returning information for job: 20200221044902668623
2020-02-21 04:49:31,925 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1280.46118307s left)
2020-02-21 04:49:32,705 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221044932686957
2020-02-21 04:49:32,735 [salt.minion      :1432][INFO    ][5654] Starting a new job with PID 5654
2020-02-21 04:49:32,769 [salt.minion      :1711][INFO    ][5654] Returning information for job: 20200221044932686957
2020-02-21 04:50:02,747 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221045002737426
2020-02-21 04:50:02,770 [salt.minion      :1432][INFO    ][5672] Starting a new job with PID 5672
2020-02-21 04:50:02,793 [salt.minion      :1711][INFO    ][5672] Returning information for job: 20200221045002737426
2020-02-21 04:50:05,314 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1247.07217097s left)
2020-02-21 04:50:32,777 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221045032764352
2020-02-21 04:50:32,805 [salt.minion      :1432][INFO    ][5710] Starting a new job with PID 5710
2020-02-21 04:50:32,827 [salt.minion      :1711][INFO    ][5710] Returning information for job: 20200221045032764352
2020-02-21 04:50:38,827 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1213.55909109s left)
2020-02-21 04:51:02,823 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221045102813956
2020-02-21 04:51:02,847 [salt.minion      :1432][INFO    ][5733] Starting a new job with PID 5733
2020-02-21 04:51:02,870 [salt.minion      :1711][INFO    ][5733] Returning information for job: 20200221045102813956
2020-02-21 04:51:12,265 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1180.12105608s left)
2020-02-21 04:51:32,891 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221045132879015
2020-02-21 04:51:32,917 [salt.minion      :1432][INFO    ][5772] Starting a new job with PID 5772
2020-02-21 04:51:32,939 [salt.minion      :1711][INFO    ][5772] Returning information for job: 20200221045132879015
2020-02-21 04:51:45,569 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1146.81699491s left)
2020-02-21 04:52:02,953 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221045202943554
2020-02-21 04:52:02,982 [salt.minion      :1432][INFO    ][5792] Starting a new job with PID 5792
2020-02-21 04:52:03,011 [salt.minion      :1711][INFO    ][5792] Returning information for job: 20200221045202943554
2020-02-21 04:52:19,041 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1113.34526801s left)
2020-02-21 04:52:33,033 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221045233024185
2020-02-21 04:52:33,059 [salt.minion      :1432][INFO    ][5828] Starting a new job with PID 5828
2020-02-21 04:52:33,081 [salt.minion      :1711][INFO    ][5828] Returning information for job: 20200221045233024185
2020-02-21 04:52:52,701 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1079.68511701s left)
2020-02-21 04:53:03,107 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221045303096946
2020-02-21 04:53:03,129 [salt.minion      :1432][INFO    ][5849] Starting a new job with PID 5849
2020-02-21 04:53:03,153 [salt.minion      :1711][INFO    ][5849] Returning information for job: 20200221045303096946
2020-02-21 04:53:25,958 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1046.42833304s left)
2020-02-21 04:53:33,207 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221045333195174
2020-02-21 04:53:33,235 [salt.minion      :1432][INFO    ][5883] Starting a new job with PID 5883
2020-02-21 04:53:33,254 [salt.minion      :1711][INFO    ][5883] Returning information for job: 20200221045333195174
2020-02-21 04:53:59,427 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1012.95950603s left)
2020-02-21 04:54:03,317 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221045403298135
2020-02-21 04:54:03,346 [salt.minion      :1432][INFO    ][5908] Starting a new job with PID 5908
2020-02-21 04:54:03,375 [salt.minion      :1711][INFO    ][5908] Returning information for job: 20200221045403298135
2020-02-21 04:54:32,822 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (979.56427598s left)
2020-02-21 04:54:33,431 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221045433421837
2020-02-21 04:54:33,455 [salt.minion      :1432][INFO    ][5955] Starting a new job with PID 5955
2020-02-21 04:54:33,480 [salt.minion      :1711][INFO    ][5955] Returning information for job: 20200221045433421837
2020-02-21 04:55:03,558 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221045503547959
2020-02-21 04:55:03,575 [salt.minion      :1432][INFO    ][5973] Starting a new job with PID 5973
2020-02-21 04:55:03,597 [salt.minion      :1711][INFO    ][5973] Returning information for job: 20200221045503547959
2020-02-21 04:55:06,016 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (946.370791912s left)
2020-02-21 04:55:33,715 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221045533706889
2020-02-21 04:55:33,739 [salt.minion      :1432][INFO    ][6013] Starting a new job with PID 6013
2020-02-21 04:55:33,765 [salt.minion      :1711][INFO    ][6013] Returning information for job: 20200221045533706889
2020-02-21 04:55:39,322 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (913.064479113s left)
2020-02-21 04:56:03,905 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221045603889870
2020-02-21 04:56:03,930 [salt.minion      :1432][INFO    ][6034] Starting a new job with PID 6034
2020-02-21 04:56:03,951 [salt.minion      :1711][INFO    ][6034] Returning information for job: 20200221045603889870
2020-02-21 04:56:12,680 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (879.70599103s left)
2020-02-21 04:56:34,063 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221045634049798
2020-02-21 04:56:34,087 [salt.minion      :1432][INFO    ][6073] Starting a new job with PID 6073
2020-02-21 04:56:34,110 [salt.minion      :1711][INFO    ][6073] Returning information for job: 20200221045634049798
2020-02-21 04:56:45,872 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (846.514612913s left)
2020-02-21 04:57:04,242 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221045704231429
2020-02-21 04:57:04,267 [salt.minion      :1432][INFO    ][6092] Starting a new job with PID 6092
2020-02-21 04:57:04,292 [salt.minion      :1711][INFO    ][6092] Returning information for job: 20200221045704231429
2020-02-21 04:57:19,154 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (813.232368946s left)
2020-02-21 04:57:34,457 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221045734448594
2020-02-21 04:57:34,480 [salt.minion      :1432][INFO    ][6130] Starting a new job with PID 6130
2020-02-21 04:57:34,506 [salt.minion      :1711][INFO    ][6130] Returning information for job: 20200221045734448594
2020-02-21 04:57:52,312 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (780.074645996s left)
2020-02-21 04:58:04,682 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221045804668061
2020-02-21 04:58:04,706 [salt.minion      :1432][INFO    ][6151] Starting a new job with PID 6151
2020-02-21 04:58:04,730 [salt.minion      :1711][INFO    ][6151] Returning information for job: 20200221045804668061
2020-02-21 04:58:25,409 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (746.977107048s left)
2020-02-21 04:58:34,863 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221045834851585
2020-02-21 04:58:34,881 [salt.minion      :1432][INFO    ][6190] Starting a new job with PID 6190
2020-02-21 04:58:34,905 [salt.minion      :1711][INFO    ][6190] Returning information for job: 20200221045834851585
2020-02-21 04:58:58,711 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (713.67581296s left)
2020-02-21 04:59:04,891 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221045904880013
2020-02-21 04:59:04,918 [salt.minion      :1432][INFO    ][6211] Starting a new job with PID 6211
2020-02-21 04:59:04,942 [salt.minion      :1711][INFO    ][6211] Returning information for job: 20200221045904880013
2020-02-21 04:59:31,960 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (680.426033974s left)
2020-02-21 04:59:34,921 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221045934913202
2020-02-21 04:59:34,951 [salt.minion      :1432][INFO    ][6247] Starting a new job with PID 6247
2020-02-21 04:59:34,975 [salt.minion      :1711][INFO    ][6247] Returning information for job: 20200221045934913202
2020-02-21 05:00:04,971 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221050004959022
2020-02-21 05:00:04,992 [salt.minion      :1432][INFO    ][6268] Starting a new job with PID 6268
2020-02-21 05:00:05,014 [salt.minion      :1711][INFO    ][6268] Returning information for job: 20200221050004959022
2020-02-21 05:00:05,239 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (647.147130013s left)
2020-02-21 05:00:35,025 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221050035017464
2020-02-21 05:00:35,048 [salt.minion      :1432][INFO    ][6304] Starting a new job with PID 6304
2020-02-21 05:00:35,070 [salt.minion      :1711][INFO    ][6304] Returning information for job: 20200221050035017464
2020-02-21 05:00:38,338 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (614.048269987s left)
2020-02-21 05:01:05,114 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221050105101921
2020-02-21 05:01:05,140 [salt.minion      :1432][INFO    ][6323] Starting a new job with PID 6323
2020-02-21 05:01:05,166 [salt.minion      :1711][INFO    ][6323] Returning information for job: 20200221050105101921
2020-02-21 05:01:11,637 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (580.749351025s left)
2020-02-21 05:01:35,222 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221050135208085
2020-02-21 05:01:35,242 [salt.minion      :1432][INFO    ][6358] Starting a new job with PID 6358
2020-02-21 05:01:35,266 [salt.minion      :1711][INFO    ][6358] Returning information for job: 20200221050135208085
2020-02-21 05:01:44,778 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (547.608937025s left)
2020-02-21 05:02:05,345 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221050205334537
2020-02-21 05:02:05,371 [salt.minion      :1432][INFO    ][6377] Starting a new job with PID 6377
2020-02-21 05:02:05,395 [salt.minion      :1711][INFO    ][6377] Returning information for job: 20200221050205334537
2020-02-21 05:02:17,899 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (514.486903906s left)
2020-02-21 05:02:35,453 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221050235442963
2020-02-21 05:02:35,470 [salt.minion      :1432][INFO    ][6411] Starting a new job with PID 6411
2020-02-21 05:02:35,492 [salt.minion      :1711][INFO    ][6411] Returning information for job: 20200221050235442963
2020-02-21 05:02:50,943 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (481.442991972s left)
2020-02-21 05:03:05,630 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221050305622227
2020-02-21 05:03:05,654 [salt.minion      :1432][INFO    ][6429] Starting a new job with PID 6429
2020-02-21 05:03:05,684 [salt.minion      :1711][INFO    ][6429] Returning information for job: 20200221050305622227
2020-02-21 05:03:24,242 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (448.144829035s left)
2020-02-21 05:03:35,819 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221050335806571
2020-02-21 05:03:35,839 [salt.minion      :1432][INFO    ][6463] Starting a new job with PID 6463
2020-02-21 05:03:35,862 [salt.minion      :1711][INFO    ][6463] Returning information for job: 20200221050335806571
2020-02-21 05:03:57,371 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (415.015311956s left)
2020-02-21 05:04:05,980 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221050405967499
2020-02-21 05:04:06,004 [salt.minion      :1432][INFO    ][6490] Starting a new job with PID 6490
2020-02-21 05:04:06,025 [salt.minion      :1711][INFO    ][6490] Returning information for job: 20200221050405967499
2020-02-21 05:04:30,643 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (381.74336791s left)
2020-02-21 05:04:36,167 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221050436158061
2020-02-21 05:04:36,195 [salt.minion      :1432][INFO    ][6538] Starting a new job with PID 6538
2020-02-21 05:04:36,218 [salt.minion      :1711][INFO    ][6538] Returning information for job: 20200221050436158061
2020-02-21 05:05:03,879 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (348.507059097s left)
2020-02-21 05:05:06,356 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221050506344262
2020-02-21 05:05:06,378 [salt.minion      :1432][INFO    ][6560] Starting a new job with PID 6560
2020-02-21 05:05:06,398 [salt.minion      :1711][INFO    ][6560] Returning information for job: 20200221050506344262
2020-02-21 05:05:36,411 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221050536397380
2020-02-21 05:05:36,431 [salt.minion      :1432][INFO    ][6596] Starting a new job with PID 6596
2020-02-21 05:05:36,453 [salt.minion      :1711][INFO    ][6596] Returning information for job: 20200221050536397380
2020-02-21 05:05:37,083 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (315.303822994s left)
2020-02-21 05:06:06,614 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221050606603833
2020-02-21 05:06:06,633 [salt.minion      :1432][INFO    ][6614] Starting a new job with PID 6614
2020-02-21 05:06:06,652 [salt.minion      :1711][INFO    ][6614] Returning information for job: 20200221050606603833
2020-02-21 05:06:10,283 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (282.103471041s left)
2020-02-21 05:06:36,688 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221050636677603
2020-02-21 05:06:36,712 [salt.minion      :1432][INFO    ][6648] Starting a new job with PID 6648
2020-02-21 05:06:36,736 [salt.minion      :1711][INFO    ][6648] Returning information for job: 20200221050636677603
2020-02-21 05:06:43,451 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (248.935805082s left)
2020-02-21 05:07:06,741 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221050706728908
2020-02-21 05:07:06,765 [salt.minion      :1432][INFO    ][6669] Starting a new job with PID 6669
2020-02-21 05:07:06,787 [salt.minion      :1711][INFO    ][6669] Returning information for job: 20200221050706728908
2020-02-21 05:07:16,732 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (215.654464006s left)
2020-02-21 05:07:36,846 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221050736834206
2020-02-21 05:07:36,873 [salt.minion      :1432][INFO    ][6703] Starting a new job with PID 6703
2020-02-21 05:07:36,894 [salt.minion      :1711][INFO    ][6703] Returning information for job: 20200221050736834206
2020-02-21 05:07:49,778 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (182.608258009s left)
2020-02-21 05:08:06,973 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221050806962095
2020-02-21 05:08:07,001 [salt.minion      :1432][INFO    ][6722] Starting a new job with PID 6722
2020-02-21 05:08:07,023 [salt.minion      :1711][INFO    ][6722] Returning information for job: 20200221050806962095
2020-02-21 05:08:23,037 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (149.349811077s left)
2020-02-21 05:08:37,164 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221050837151613
2020-02-21 05:08:37,191 [salt.minion      :1432][INFO    ][6757] Starting a new job with PID 6757
2020-02-21 05:08:37,214 [salt.minion      :1711][INFO    ][6757] Returning information for job: 20200221050837151613
2020-02-21 05:08:56,278 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (116.108746052s left)
2020-02-21 05:09:07,323 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221050907314519
2020-02-21 05:09:07,347 [salt.minion      :1432][INFO    ][6779] Starting a new job with PID 6779
2020-02-21 05:09:07,367 [salt.minion      :1711][INFO    ][6779] Returning information for job: 20200221050907314519
2020-02-21 05:09:29,321 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (83.0655660629s left)
2020-02-21 05:09:37,473 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221050937461381
2020-02-21 05:09:37,505 [salt.minion      :1432][INFO    ][6820] Starting a new job with PID 6820
2020-02-21 05:09:37,530 [salt.minion      :1711][INFO    ][6820] Returning information for job: 20200221050937461381
2020-02-21 05:10:02,590 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (49.7964360714s left)
2020-02-21 05:10:07,654 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221051007643570
2020-02-21 05:10:07,676 [salt.minion      :1432][INFO    ][6843] Starting a new job with PID 6843
2020-02-21 05:10:07,701 [salt.minion      :1711][INFO    ][6843] Returning information for job: 20200221051007643570
2020-02-21 05:10:35,754 [salt.loaded.ext.module.maas:1024][INFO    ][4269] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (16.6326980591s left)
2020-02-21 05:10:37,688 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221051037678277
2020-02-21 05:10:37,719 [salt.minion      :1432][INFO    ][6875] Starting a new job with PID 6875
2020-02-21 05:10:37,742 [salt.minion      :1711][INFO    ][6875] Returning information for job: 20200221051037678277
2020-02-21 05:11:07,756 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221051107745294
2020-02-21 05:11:07,783 [salt.minion      :1432][INFO    ][6900] Starting a new job with PID 6900
2020-02-21 05:11:07,810 [salt.minion      :1711][INFO    ][6900] Returning information for job: 20200221051107745294
2020-02-21 05:11:09,047 [salt.state       :302 ][ERROR   ][4269] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['cmp002', 'cmp001']not in Deployed state
2020-02-21 05:11:09,047 [salt.state       :1951][INFO    ][4269] Completed state [maas.wait_for_machine_status] at time 05:11:09.047591 duration_in_ms=2266676.459
2020-02-21 05:11:09,053 [salt.minion      :1711][INFO    ][4269] Returning information for job: 20200221043313901316
2020-02-21 05:11:20,051 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command pillar.get with jid 20200221051120043564
2020-02-21 05:11:20,065 [salt.minion      :1432][INFO    ][6917] Starting a new job with PID 6917
2020-02-21 05:11:20,075 [salt.minion      :1711][INFO    ][6917] Returning information for job: 20200221051120043564
2020-02-21 05:11:20,891 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command service.status with jid 20200221051120879014
2020-02-21 05:11:20,915 [salt.minion      :1432][INFO    ][6922] Starting a new job with PID 6922
2020-02-21 05:11:21,461 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6922] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2020-02-21 05:11:21,499 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6922] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2020-02-21 05:11:21,520 [salt.minion      :1711][INFO    ][6922] Returning information for job: 20200221051120879014
2020-02-21 05:11:22,327 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command state.apply with jid 20200221051122311943
2020-02-21 05:11:22,354 [salt.minion      :1432][INFO    ][6933] Starting a new job with PID 6933
2020-02-21 05:11:28,620 [salt.state       :915 ][INFO    ][6933] Loading fresh modules for state activity
2020-02-21 05:11:29,233 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6933] Executing command 'salt-minion --version' in directory '/root'
2020-02-21 05:11:29,606 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6933] Executing command 'salt-minion --version' in directory '/root'
2020-02-21 05:11:30,673 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6933] Executing command 'salt-minion --version' in directory '/root'
2020-02-21 05:11:31,014 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6933] Executing command 'salt-minion --version' in directory '/root'
2020-02-21 05:11:32,973 [salt.state       :1780][INFO    ][6933] Running state [salt-minion] at time 05:11:32.973888
2020-02-21 05:11:32,974 [salt.state       :1813][INFO    ][6933] Executing state pkg.installed for [salt-minion]
2020-02-21 05:11:32,975 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6933] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-21 05:11:33,103 [salt.state       :300 ][INFO    ][6933] All specified packages are already installed
2020-02-21 05:11:33,103 [salt.state       :1951][INFO    ][6933] Completed state [salt-minion] at time 05:11:33.103539 duration_in_ms=129.651
2020-02-21 05:11:33,103 [salt.state       :1780][INFO    ][6933] Running state [salt_minion_dependency_packages] at time 05:11:33.103859
2020-02-21 05:11:33,104 [salt.state       :1813][INFO    ][6933] Executing state pkg.installed for [salt_minion_dependency_packages]
2020-02-21 05:11:33,113 [salt.state       :300 ][INFO    ][6933] All specified packages are already installed
2020-02-21 05:11:33,113 [salt.state       :1951][INFO    ][6933] Completed state [salt_minion_dependency_packages] at time 05:11:33.113550 duration_in_ms=9.691
2020-02-21 05:11:33,117 [salt.state       :1780][INFO    ][6933] Running state [/etc/salt/minion.d/minion.conf] at time 05:11:33.117097
2020-02-21 05:11:33,117 [salt.state       :1813][INFO    ][6933] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2020-02-21 05:11:33,414 [salt.state       :300 ][INFO    ][6933] File /etc/salt/minion.d/minion.conf is in the correct state
2020-02-21 05:11:33,415 [salt.state       :1951][INFO    ][6933] Completed state [/etc/salt/minion.d/minion.conf] at time 05:11:33.414845 duration_in_ms=297.747
2020-02-21 05:11:33,422 [salt.state       :1780][INFO    ][6933] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:11:33.422119
2020-02-21 05:11:33,422 [salt.state       :1813][INFO    ][6933] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2020-02-21 05:11:33,438 [salt.state       :300 ][INFO    ][6933] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2020-02-21 05:11:33,438 [salt.state       :1951][INFO    ][6933] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:11:33.438757 duration_in_ms=16.639
2020-02-21 05:11:33,440 [salt.state       :1780][INFO    ][6933] Running state [salt-minion] at time 05:11:33.440103
2020-02-21 05:11:33,440 [salt.state       :1813][INFO    ][6933] Executing state service.running for [salt-minion]
2020-02-21 05:11:33,441 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6933] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2020-02-21 05:11:33,492 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6933] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2020-02-21 05:11:33,514 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6933] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2020-02-21 05:11:33,541 [salt.state       :300 ][INFO    ][6933] The service salt-minion is already running
2020-02-21 05:11:33,542 [salt.state       :1951][INFO    ][6933] Completed state [salt-minion] at time 05:11:33.541990 duration_in_ms=101.886
2020-02-21 05:11:33,544 [salt.state       :1780][INFO    ][6933] Running state [/etc/salt/grains.d] at time 05:11:33.544188
2020-02-21 05:11:33,544 [salt.state       :1813][INFO    ][6933] Executing state file.directory for [/etc/salt/grains.d]
2020-02-21 05:11:33,546 [salt.state       :300 ][INFO    ][6933] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2020-02-21 05:11:33,546 [salt.state       :1951][INFO    ][6933] Completed state [/etc/salt/grains.d] at time 05:11:33.546258 duration_in_ms=2.069
2020-02-21 05:11:33,547 [salt.state       :1780][INFO    ][6933] Running state [/etc/salt/grains] at time 05:11:33.547169
2020-02-21 05:11:33,547 [salt.state       :1813][INFO    ][6933] Executing state file.managed for [/etc/salt/grains]
2020-02-21 05:11:33,548 [salt.state       :300 ][INFO    ][6933] File /etc/salt/grains exists with proper permissions. No changes made.
2020-02-21 05:11:33,549 [salt.state       :1951][INFO    ][6933] Completed state [/etc/salt/grains] at time 05:11:33.549023 duration_in_ms=1.854
2020-02-21 05:11:33,549 [salt.state       :1780][INFO    ][6933] Running state [/etc/salt/grains.d/placeholder] at time 05:11:33.549650
2020-02-21 05:11:33,550 [salt.state       :1813][INFO    ][6933] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2020-02-21 05:11:33,550 [salt.state       :300 ][INFO    ][6933] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2020-02-21 05:11:33,551 [salt.state       :1951][INFO    ][6933] Completed state [/etc/salt/grains.d/placeholder] at time 05:11:33.550947 duration_in_ms=1.296
2020-02-21 05:11:33,551 [salt.state       :1780][INFO    ][6933] Running state [/etc/salt/grains.d/sphinx] at time 05:11:33.551589
2020-02-21 05:11:33,551 [salt.state       :1813][INFO    ][6933] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2020-02-21 05:11:33,555 [salt.state       :300 ][INFO    ][6933] File /etc/salt/grains.d/sphinx is in the correct state
2020-02-21 05:11:33,555 [salt.state       :1951][INFO    ][6933] Completed state [/etc/salt/grains.d/sphinx] at time 05:11:33.555390 duration_in_ms=3.801
2020-02-21 05:11:33,558 [salt.state       :1780][INFO    ][6933] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:11:33.558047
2020-02-21 05:11:33,558 [salt.state       :1813][INFO    ][6933] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2020-02-21 05:11:33,558 [salt.state       :300 ][INFO    ][6933] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2020-02-21 05:11:33,558 [salt.state       :1951][INFO    ][6933] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:11:33.558851 duration_in_ms=0.804
2020-02-21 05:11:33,559 [salt.state       :1780][INFO    ][6933] Running state [/etc/salt/grains.d/dns_records] at time 05:11:33.559335
2020-02-21 05:11:33,559 [salt.state       :1813][INFO    ][6933] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2020-02-21 05:11:33,560 [salt.state       :300 ][INFO    ][6933] File /etc/salt/grains.d/dns_records is in the correct state
2020-02-21 05:11:33,560 [salt.state       :1951][INFO    ][6933] Completed state [/etc/salt/grains.d/dns_records] at time 05:11:33.560704 duration_in_ms=1.37
2020-02-21 05:11:33,561 [salt.state       :1780][INFO    ][6933] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:11:33.561613
2020-02-21 05:11:33,561 [salt.state       :1813][INFO    ][6933] 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-21 05:11:33,562 [salt.state       :300 ][INFO    ][6933] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2020-02-21 05:11:33,562 [salt.state       :1951][INFO    ][6933] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:11:33.562392 duration_in_ms=0.779
2020-02-21 05:11:33,562 [salt.state       :1780][INFO    ][6933] Running state [/etc/salt/grains.d/salt] at time 05:11:33.562877
2020-02-21 05:11:33,563 [salt.state       :1813][INFO    ][6933] Executing state file.managed for [/etc/salt/grains.d/salt]
2020-02-21 05:11:33,564 [salt.state       :300 ][INFO    ][6933] File /etc/salt/grains.d/salt is in the correct state
2020-02-21 05:11:33,564 [salt.state       :1951][INFO    ][6933] Completed state [/etc/salt/grains.d/salt] at time 05:11:33.564235 duration_in_ms=1.358
2020-02-21 05:11:33,565 [salt.state       :1780][INFO    ][6933] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:11:33.565713
2020-02-21 05:11:33,565 [salt.state       :1813][INFO    ][6933] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2020-02-21 05:11:33,566 [salt.state       :300 ][INFO    ][6933] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2020-02-21 05:11:33,566 [salt.state       :1951][INFO    ][6933] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:11:33.566455 duration_in_ms=0.742
2020-02-21 05:11:33,568 [salt.state       :1780][INFO    ][6933] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:11:33.568921
2020-02-21 05:11:33,569 [salt.state       :1813][INFO    ][6933] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2020-02-21 05:11:33,569 [salt.state       :300 ][INFO    ][6933] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2020-02-21 05:11:33,569 [salt.state       :1951][INFO    ][6933] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:11:33.569696 duration_in_ms=0.775
2020-02-21 05:11:33,570 [salt.state       :1780][INFO    ][6933] Running state [mine.update] at time 05:11:33.570342
2020-02-21 05:11:33,570 [salt.state       :1813][INFO    ][6933] Executing state module.wait for [mine.update]
2020-02-21 05:11:33,570 [salt.state       :300 ][INFO    ][6933] No changes made for mine.update
2020-02-21 05:11:33,571 [salt.state       :1951][INFO    ][6933] Completed state [mine.update] at time 05:11:33.571035 duration_in_ms=0.693
2020-02-21 05:11:33,571 [salt.state       :1780][INFO    ][6933] Running state [ca-certificates] at time 05:11:33.571274
2020-02-21 05:11:33,571 [salt.state       :1813][INFO    ][6933] Executing state pkg.installed for [ca-certificates]
2020-02-21 05:11:33,582 [salt.state       :300 ][INFO    ][6933] All specified packages are already installed
2020-02-21 05:11:33,582 [salt.state       :1951][INFO    ][6933] Completed state [ca-certificates] at time 05:11:33.582235 duration_in_ms=10.96
2020-02-21 05:11:33,583 [salt.state       :1780][INFO    ][6933] Running state [update-ca-certificates] at time 05:11:33.583164
2020-02-21 05:11:33,583 [salt.state       :1813][INFO    ][6933] Executing state cmd.wait for [update-ca-certificates]
2020-02-21 05:11:33,583 [salt.state       :300 ][INFO    ][6933] No changes made for update-ca-certificates
2020-02-21 05:11:33,583 [salt.state       :1951][INFO    ][6933] Completed state [update-ca-certificates] at time 05:11:33.583925 duration_in_ms=0.762
2020-02-21 05:11:33,584 [salt.state       :1780][INFO    ][6933] Running state [iptables] at time 05:11:33.584169
2020-02-21 05:11:33,584 [salt.state       :1813][INFO    ][6933] Executing state pkg.installed for [iptables]
2020-02-21 05:11:33,593 [salt.state       :300 ][INFO    ][6933] All specified packages are already installed
2020-02-21 05:11:33,594 [salt.state       :1951][INFO    ][6933] Completed state [iptables] at time 05:11:33.593991 duration_in_ms=9.822
2020-02-21 05:11:33,594 [salt.state       :1780][INFO    ][6933] Running state [iptables-persistent] at time 05:11:33.594264
2020-02-21 05:11:33,594 [salt.state       :1813][INFO    ][6933] Executing state pkg.installed for [iptables-persistent]
2020-02-21 05:11:33,604 [salt.state       :300 ][INFO    ][6933] All specified packages are already installed
2020-02-21 05:11:33,604 [salt.state       :1951][INFO    ][6933] Completed state [iptables-persistent] at time 05:11:33.604685 duration_in_ms=10.421
2020-02-21 05:11:33,605 [salt.state       :1780][INFO    ][6933] Running state [iptables_modules_v4_load] at time 05:11:33.605666
2020-02-21 05:11:33,605 [salt.state       :1813][INFO    ][6933] Executing state kmod.present for [iptables_modules_v4_load]
2020-02-21 05:11:33,606 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6933] Executing command 'lsmod' in directory '/root'
2020-02-21 05:11:33,629 [salt.state       :300 ][INFO    ][6933] Kernel modules iptable_filter, ip_tables are already present
2020-02-21 05:11:33,629 [salt.state       :1951][INFO    ][6933] Completed state [iptables_modules_v4_load] at time 05:11:33.629724 duration_in_ms=24.058
2020-02-21 05:11:33,630 [salt.state       :1780][INFO    ][6933] Running state [/etc/iptables/rules.v4] at time 05:11:33.630412
2020-02-21 05:11:33,630 [salt.state       :1813][INFO    ][6933] Executing state file.managed for [/etc/iptables/rules.v4]
2020-02-21 05:11:33,738 [salt.state       :300 ][INFO    ][6933] File /etc/iptables/rules.v4 is in the correct state
2020-02-21 05:11:33,739 [salt.state       :1951][INFO    ][6933] Completed state [/etc/iptables/rules.v4] at time 05:11:33.739049 duration_in_ms=108.636
2020-02-21 05:11:33,740 [salt.state       :1780][INFO    ][6933] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:11:33.740154
2020-02-21 05:11:33,740 [salt.state       :1813][INFO    ][6933] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2020-02-21 05:11:33,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6933] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2020-02-21 05:11:33,761 [salt.state       :300 ][INFO    ][6933] onlyif execution failed
2020-02-21 05:11:33,762 [salt.state       :1951][INFO    ][6933] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:11:33.762498 duration_in_ms=22.343
2020-02-21 05:11:33,764 [salt.state       :1780][INFO    ][6933] Running state [netfilter-persistent] at time 05:11:33.764572
2020-02-21 05:11:33,765 [salt.state       :1813][INFO    ][6933] Executing state service.running for [netfilter-persistent]
2020-02-21 05:11:33,766 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6933] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2020-02-21 05:11:33,790 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6933] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2020-02-21 05:11:33,812 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6933] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2020-02-21 05:11:33,834 [salt.state       :300 ][INFO    ][6933] The service netfilter-persistent is already running
2020-02-21 05:11:33,834 [salt.state       :1951][INFO    ][6933] Completed state [netfilter-persistent] at time 05:11:33.834574 duration_in_ms=70.002
2020-02-21 05:11:33,836 [salt.state       :1780][INFO    ][6933] Running state [iptables_extra.remove_stale_tables] at time 05:11:33.836173
2020-02-21 05:11:33,836 [salt.state       :1813][INFO    ][6933] Executing state module.wait for [iptables_extra.remove_stale_tables]
2020-02-21 05:11:33,837 [salt.state       :300 ][INFO    ][6933] No changes made for iptables_extra.remove_stale_tables
2020-02-21 05:11:33,837 [salt.state       :1951][INFO    ][6933] Completed state [iptables_extra.remove_stale_tables] at time 05:11:33.837640 duration_in_ms=1.468
2020-02-21 05:11:33,838 [salt.state       :1780][INFO    ][6933] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:11:33.838091
2020-02-21 05:11:33,838 [salt.state       :1813][INFO    ][6933] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2020-02-21 05:11:33,839 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6933] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2020-02-21 05:11:33,858 [salt.state       :300 ][INFO    ][6933] onlyif execution failed
2020-02-21 05:11:33,859 [salt.state       :1951][INFO    ][6933] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:11:33.859311 duration_in_ms=21.22
2020-02-21 05:11:33,861 [salt.state       :1780][INFO    ][6933] Running state [/etc/iptables/rules.v6] at time 05:11:33.861345
2020-02-21 05:11:33,861 [salt.state       :1813][INFO    ][6933] Executing state file.absent for [/etc/iptables/rules.v6]
2020-02-21 05:11:33,862 [salt.state       :300 ][INFO    ][6933] File /etc/iptables/rules.v6 is not present
2020-02-21 05:11:33,863 [salt.state       :1951][INFO    ][6933] Completed state [/etc/iptables/rules.v6] at time 05:11:33.863255 duration_in_ms=1.91
2020-02-21 05:11:33,866 [salt.state       :1780][INFO    ][6933] Running state [iptables_extra.flush_all] at time 05:11:33.866903
2020-02-21 05:11:33,867 [salt.state       :1813][INFO    ][6933] Executing state module.wait for [iptables_extra.flush_all]
2020-02-21 05:11:33,867 [salt.state       :300 ][INFO    ][6933] No changes made for iptables_extra.flush_all
2020-02-21 05:11:33,867 [salt.state       :1951][INFO    ][6933] Completed state [iptables_extra.flush_all] at time 05:11:33.867731 duration_in_ms=0.827
2020-02-21 05:11:33,871 [salt.minion      :1711][INFO    ][6933] Returning information for job: 20200221051122311943
2020-02-21 05:11:34,679 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command state.apply with jid 20200221051134670229
2020-02-21 05:11:34,703 [salt.minion      :1432][INFO    ][7017] Starting a new job with PID 7017
2020-02-21 05:11:35,646 [salt.state       :915 ][INFO    ][7017] Loading fresh modules for state activity
2020-02-21 05:11:36,616 [salt.state       :1780][INFO    ][7017] Running state [maas-rack-controller] at time 05:11:36.616166
2020-02-21 05:11:36,616 [salt.state       :1813][INFO    ][7017] Executing state pkg.installed for [maas-rack-controller]
2020-02-21 05:11:36,617 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7017] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-21 05:11:36,734 [salt.state       :300 ][INFO    ][7017] All specified packages are already installed
2020-02-21 05:11:36,734 [salt.state       :1951][INFO    ][7017] Completed state [maas-rack-controller] at time 05:11:36.734514 duration_in_ms=118.349
2020-02-21 05:11:36,734 [salt.state       :1780][INFO    ][7017] Running state [ipmitool] at time 05:11:36.734887
2020-02-21 05:11:36,735 [salt.state       :1813][INFO    ][7017] Executing state pkg.installed for [ipmitool]
2020-02-21 05:11:36,743 [salt.state       :300 ][INFO    ][7017] All specified packages are already installed
2020-02-21 05:11:36,744 [salt.state       :1951][INFO    ][7017] Completed state [ipmitool] at time 05:11:36.744164 duration_in_ms=9.277
2020-02-21 05:11:36,749 [salt.state       :1780][INFO    ][7017] Running state [/etc/maas/rackd.conf] at time 05:11:36.749028
2020-02-21 05:11:36,749 [salt.state       :1813][INFO    ][7017] Executing state file.line for [/etc/maas/rackd.conf]
2020-02-21 05:11:36,750 [salt.state       :300 ][INFO    ][7017] No changes needed to be made
2020-02-21 05:11:36,750 [salt.state       :1951][INFO    ][7017] Completed state [/etc/maas/rackd.conf] at time 05:11:36.750440 duration_in_ms=1.412
2020-02-21 05:11:36,750 [salt.state       :1780][INFO    ][7017] Running state [/etc/maas/rackd.conf] at time 05:11:36.750683
2020-02-21 05:11:36,750 [salt.state       :1813][INFO    ][7017] Executing state file.managed for [/etc/maas/rackd.conf]
2020-02-21 05:11:36,751 [salt.loaded.int.states.file:2298][WARNING ][7017] 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-21 05:11:36,751 [salt.state       :300 ][INFO    ][7017] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2020-02-21 05:11:36,752 [salt.state       :1951][INFO    ][7017] Completed state [/etc/maas/rackd.conf] at time 05:11:36.752004 duration_in_ms=1.321
2020-02-21 05:11:36,753 [salt.state       :1780][INFO    ][7017] Running state [maas-rackd] at time 05:11:36.753186
2020-02-21 05:11:36,753 [salt.state       :1813][INFO    ][7017] Executing state service.running for [maas-rackd]
2020-02-21 05:11:36,754 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7017] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2020-02-21 05:11:36,801 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7017] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2020-02-21 05:11:36,823 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7017] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2020-02-21 05:11:36,847 [salt.state       :300 ][INFO    ][7017] The service maas-rackd is already running
2020-02-21 05:11:36,848 [salt.state       :1951][INFO    ][7017] Completed state [maas-rackd] at time 05:11:36.848658 duration_in_ms=95.471
2020-02-21 05:11:36,850 [salt.minion      :1711][INFO    ][7017] Returning information for job: 20200221051134670229
2020-02-21 05:11:37,707 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command state.apply with jid 20200221051137693810
2020-02-21 05:11:37,735 [salt.minion      :1432][INFO    ][7046] Starting a new job with PID 7046
2020-02-21 05:11:38,641 [salt.state       :915 ][INFO    ][7046] Loading fresh modules for state activity
2020-02-21 05:11:39,767 [salt.state       :1780][INFO    ][7046] Running state [maas-region-controller] at time 05:11:39.767172
2020-02-21 05:11:39,767 [salt.state       :1813][INFO    ][7046] Executing state pkg.installed for [maas-region-controller]
2020-02-21 05:11:39,770 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7046] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2020-02-21 05:11:39,890 [salt.state       :300 ][INFO    ][7046] All specified packages are already installed
2020-02-21 05:11:39,890 [salt.state       :1951][INFO    ][7046] Completed state [maas-region-controller] at time 05:11:39.890636 duration_in_ms=123.488
2020-02-21 05:11:39,891 [salt.state       :1780][INFO    ][7046] Running state [python-oauth] at time 05:11:39.890985
2020-02-21 05:11:39,891 [salt.state       :1813][INFO    ][7046] Executing state pkg.installed for [python-oauth]
2020-02-21 05:11:39,903 [salt.state       :300 ][INFO    ][7046] All specified packages are already installed
2020-02-21 05:11:39,903 [salt.state       :1951][INFO    ][7046] Completed state [python-oauth] at time 05:11:39.903593 duration_in_ms=12.608
2020-02-21 05:11:39,907 [salt.state       :1780][INFO    ][7046] Running state [/etc/maas/regiond.conf] at time 05:11:39.907081
2020-02-21 05:11:39,907 [salt.state       :1813][INFO    ][7046] Executing state file.replace for [/etc/maas/regiond.conf]
2020-02-21 05:11:39,914 [salt.state       :300 ][INFO    ][7046] No changes needed to be made
2020-02-21 05:11:39,914 [salt.state       :1951][INFO    ][7046] Completed state [/etc/maas/regiond.conf] at time 05:11:39.914219 duration_in_ms=7.139
2020-02-21 05:11:39,914 [salt.state       :1780][INFO    ][7046] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:11:39.914692
2020-02-21 05:11:39,914 [salt.state       :1813][INFO    ][7046] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2020-02-21 05:11:39,980 [salt.state       :300 ][INFO    ][7046] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2020-02-21 05:11:39,981 [salt.state       :1951][INFO    ][7046] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:11:39.981176 duration_in_ms=66.484
2020-02-21 05:11:39,981 [salt.state       :1780][INFO    ][7046] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:11:39.981655
2020-02-21 05:11:39,981 [salt.state       :1813][INFO    ][7046] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-02-21 05:11:39,985 [salt.state       :300 ][INFO    ][7046] No changes needed to be made
2020-02-21 05:11:39,986 [salt.state       :1951][INFO    ][7046] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:11:39.986125 duration_in_ms=4.47
2020-02-21 05:11:39,986 [salt.state       :1780][INFO    ][7046] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:11:39.986596
2020-02-21 05:11:39,986 [salt.state       :1813][INFO    ][7046] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2020-02-21 05:11:39,990 [salt.state       :300 ][INFO    ][7046] No changes needed to be made
2020-02-21 05:11:39,990 [salt.state       :1951][INFO    ][7046] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:11:39.990383 duration_in_ms=3.787
2020-02-21 05:11:39,990 [salt.state       :1780][INFO    ][7046] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:11:39.990844
2020-02-21 05:11:39,991 [salt.state       :1813][INFO    ][7046] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2020-02-21 05:11:40,015 [salt.state       :300 ][INFO    ][7046] No changes needed to be made
2020-02-21 05:11:40,015 [salt.state       :1951][INFO    ][7046] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:11:40.015792 duration_in_ms=24.948
2020-02-21 05:11:40,016 [salt.state       :1780][INFO    ][7046] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:11:40.016038
2020-02-21 05:11:40,016 [salt.state       :1813][INFO    ][7046] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2020-02-21 05:11:40,031 [salt.state       :300 ][INFO    ][7046] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2020-02-21 05:11:40,032 [salt.state       :1951][INFO    ][7046] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:11:40.032061 duration_in_ms=16.023
2020-02-21 05:11:40,034 [salt.state       :1780][INFO    ][7046] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:11:40.034670
2020-02-21 05:11:40,034 [salt.state       :1813][INFO    ][7046] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2020-02-21 05:11:40,088 [salt.state       :300 ][INFO    ][7046] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2020-02-21 05:11:40,088 [salt.state       :1951][INFO    ][7046] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:11:40.088439 duration_in_ms=53.769
2020-02-21 05:11:40,088 [salt.state       :1780][INFO    ][7046] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:11:40.088941
2020-02-21 05:11:40,089 [salt.state       :1813][INFO    ][7046] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2020-02-21 05:11:40,144 [salt.state       :300 ][INFO    ][7046] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2020-02-21 05:11:40,144 [salt.state       :1951][INFO    ][7046] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:11:40.144454 duration_in_ms=55.513
2020-02-21 05:11:40,144 [salt.state       :1780][INFO    ][7046] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:11:40.144936
2020-02-21 05:11:40,145 [salt.state       :1813][INFO    ][7046] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2020-02-21 05:11:40,213 [salt.state       :300 ][INFO    ][7046] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2020-02-21 05:11:40,213 [salt.state       :1951][INFO    ][7046] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:11:40.213371 duration_in_ms=68.435
2020-02-21 05:11:40,213 [salt.state       :1780][INFO    ][7046] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 05:11:40.213850
2020-02-21 05:11:40,214 [salt.state       :1813][INFO    ][7046] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic]
2020-02-21 05:11:40,277 [salt.state       :300 ][INFO    ][7046] File /etc/maas/preseeds/curtin_userdata_amd64_generic_bionic is in the correct state
2020-02-21 05:11:40,277 [salt.state       :1951][INFO    ][7046] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 05:11:40.277801 duration_in_ms=63.95
2020-02-21 05:11:40,278 [salt.state       :1780][INFO    ][7046] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 05:11:40.278487
2020-02-21 05:11:40,278 [salt.state       :1813][INFO    ][7046] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic]
2020-02-21 05:11:40,342 [salt.state       :300 ][INFO    ][7046] File /etc/maas/preseeds/curtin_userdata_arm64_generic_bionic is in the correct state
2020-02-21 05:11:40,343 [salt.state       :1951][INFO    ][7046] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 05:11:40.343182 duration_in_ms=64.694
2020-02-21 05:11:40,343 [salt.state       :1780][INFO    ][7046] Running state [/root/.pgpass] at time 05:11:40.343464
2020-02-21 05:11:40,343 [salt.state       :1813][INFO    ][7046] Executing state file.managed for [/root/.pgpass]
2020-02-21 05:11:40,387 [salt.state       :300 ][INFO    ][7046] File /root/.pgpass is in the correct state
2020-02-21 05:11:40,388 [salt.state       :1951][INFO    ][7046] Completed state [/root/.pgpass] at time 05:11:40.388283 duration_in_ms=44.818
2020-02-21 05:11:40,396 [salt.state       :1780][INFO    ][7046] Running state [maas-region migrate --noinput] at time 05:11:40.396265
2020-02-21 05:11:40,396 [salt.state       :1813][INFO    ][7046] Executing state cmd.run for [maas-region migrate --noinput]
2020-02-21 05:11:40,397 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7046] Executing command 'maas-region migrate --noinput' in directory '/root'
2020-02-21 05:11:43,255 [salt.state       :300 ][INFO    ][7046] {'pid': 7077, '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-21 05:11:43,256 [salt.state       :1951][INFO    ][7046] Completed state [maas-region migrate --noinput] at time 05:11:43.256095 duration_in_ms=2859.828
2020-02-21 05:11:43,256 [salt.state       :2022][WARNING ][7046] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2020-02-21 05:11:43,258 [salt.state       :1780][INFO    ][7046] Running state [maas-regiond] at time 05:11:43.258364
2020-02-21 05:11:43,258 [salt.state       :1813][INFO    ][7046] Executing state service.running for [maas-regiond]
2020-02-21 05:11:43,259 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7046] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2020-02-21 05:11:43,312 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7046] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2020-02-21 05:11:43,331 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7046] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2020-02-21 05:11:43,353 [salt.state       :300 ][INFO    ][7046] The service maas-regiond is already running
2020-02-21 05:11:43,354 [salt.state       :1951][INFO    ][7046] Completed state [maas-regiond] at time 05:11:43.354149 duration_in_ms=95.784
2020-02-21 05:11:43,358 [salt.state       :1780][INFO    ][7046] Running state [bind9] at time 05:11:43.358274
2020-02-21 05:11:43,358 [salt.state       :1813][INFO    ][7046] Executing state service.running for [bind9]
2020-02-21 05:11:43,360 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7046] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2020-02-21 05:11:43,386 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7046] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2020-02-21 05:11:43,409 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7046] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2020-02-21 05:11:43,432 [salt.state       :300 ][INFO    ][7046] The service bind9 is already running
2020-02-21 05:11:43,433 [salt.state       :1951][INFO    ][7046] Completed state [bind9] at time 05:11:43.433029 duration_in_ms=74.755
2020-02-21 05:11:43,435 [salt.state       :1780][INFO    ][7046] Running state [maasng.wait_for_http_code] at time 05:11:43.435023
2020-02-21 05:11:43,435 [salt.state       :1813][INFO    ][7046] Executing state module.run for [maasng.wait_for_http_code]
2020-02-21 05:11:43,436 [salt.utils.decorators:613 ][WARNING ][7046] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-21 05:11:43,562 [salt.state       :300 ][INFO    ][7046] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2020-02-21 05:11:43,563 [salt.state       :1951][INFO    ][7046] Completed state [maasng.wait_for_http_code] at time 05:11:43.563198 duration_in_ms=128.175
2020-02-21 05:11:43,565 [salt.state       :1780][INFO    ][7046] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:11:43.564931
2020-02-21 05:11:43,565 [salt.state       :1813][INFO    ][7046] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2020-02-21 05:11:43,566 [salt.state       :300 ][INFO    ][7046] /var/lib/maas/.setup_admin exists
2020-02-21 05:11:43,566 [salt.state       :1951][INFO    ][7046] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:11:43.566350 duration_in_ms=1.419
2020-02-21 05:11:43,567 [salt.state       :1780][INFO    ][7046] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:11:43.567427
2020-02-21 05:11:43,567 [salt.state       :1813][INFO    ][7046] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-21 05:11:43,568 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7046] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-21 05:11:45,599 [salt.state       :300 ][INFO    ][7046] {'pid': 7092, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-21 05:11:45,600 [salt.state       :1951][INFO    ][7046] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:11:45.599905 duration_in_ms=2032.476
2020-02-21 05:11:45,609 [salt.state       :1780][INFO    ][7046] Running state [maas_region_boot_source_resources_mirror] at time 05:11:45.609713
2020-02-21 05:11:45,610 [salt.state       :1813][INFO    ][7046] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2020-02-21 05:11:45,685 [salt.state       :300 ][INFO    ][7046] {'changes': {}}
2020-02-21 05:11:45,686 [salt.state       :1951][INFO    ][7046] Completed state [maas_region_boot_source_resources_mirror] at time 05:11:45.686342 duration_in_ms=76.629
2020-02-21 05:11:45,687 [salt.state       :1780][INFO    ][7046] Running state [maasng.boot_resources_import] at time 05:11:45.687511
2020-02-21 05:11:45,688 [salt.state       :1813][INFO    ][7046] Executing state module.run for [maasng.boot_resources_import]
2020-02-21 05:11:45,688 [salt.utils.decorators:613 ][WARNING ][7046] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-21 05:11:45,782 [salt.loaded.ext.module.maasng:1600][INFO    ][7046] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2020-02-21 05:11:50,820 [salt.loaded.ext.module.maasng:1600][INFO    ][7046] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2020-02-21 05:11:52,827 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221051152807611
2020-02-21 05:11:52,856 [salt.minion      :1432][INFO    ][7116] Starting a new job with PID 7116
2020-02-21 05:11:52,879 [salt.minion      :1711][INFO    ][7116] Returning information for job: 20200221051152807611
2020-02-21 05:11:55,878 [salt.state       :300 ][INFO    ][7046] {'ret': True}
2020-02-21 05:11:55,878 [salt.state       :1951][INFO    ][7046] Completed state [maasng.boot_resources_import] at time 05:11:55.878522 duration_in_ms=10191.011
2020-02-21 05:11:55,879 [salt.state       :1780][INFO    ][7046] Running state [maas_region_boot_sources_selection_bionic] at time 05:11:55.879306
2020-02-21 05:11:55,879 [salt.state       :1813][INFO    ][7046] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_bionic]
2020-02-21 05:11:56,021 [salt.state       :300 ][INFO    ][7046] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2020-02-21 05:11:56,022 [salt.state       :1951][INFO    ][7046] Completed state [maas_region_boot_sources_selection_bionic] at time 05:11:56.022389 duration_in_ms=143.081
2020-02-21 05:11:56,025 [salt.state       :1780][INFO    ][7046] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:11:56.024948
2020-02-21 05:11:56,025 [salt.state       :1813][INFO    ][7046] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2020-02-21 05:11:56,026 [salt.utils.decorators:613 ][WARNING ][7046] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-21 05:11:56,026 [salt.loaded.ext.module.maasng:1771][INFO    ][7046] boot-sources sync initiated for ALL Rack's
2020-02-21 05:11:56,895 [salt.state       :300 ][INFO    ][7046] {'ret': True}
2020-02-21 05:11:56,895 [salt.state       :1951][INFO    ][7046] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:11:56.895748 duration_in_ms=870.798
2020-02-21 05:11:56,898 [salt.state       :1780][INFO    ][7046] Running state [maas.process_maas_config] at time 05:11:56.898401
2020-02-21 05:11:56,898 [salt.state       :1813][INFO    ][7046] Executing state module.run for [maas.process_maas_config]
2020-02-21 05:11:56,899 [salt.utils.decorators:613 ][WARNING ][7046] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-21 05:11:56,900 [salt.loaded.ext.module.maas:92  ][INFO    ][7046] maasconfig name=enable_http_proxy value=True
2020-02-21 05:11:56,947 [salt.loaded.ext.module.maas:92  ][INFO    ][7046] maasconfig name=upstream_dns value=8.8.8.8
2020-02-21 05:11:58,300 [salt.loaded.ext.module.maas:92  ][INFO    ][7046] maasconfig name=commissioning_distro_series value=bionic
2020-02-21 05:11:58,343 [salt.loaded.ext.module.maas:92  ][INFO    ][7046] maasconfig name=default_osystem value=ubuntu
2020-02-21 05:11:58,380 [salt.loaded.ext.module.maas:92  ][INFO    ][7046] maasconfig name=active_discovery_interval value=600
2020-02-21 05:11:58,417 [salt.loaded.ext.module.maas:92  ][INFO    ][7046] maasconfig name=dnssec_validation value=no
2020-02-21 05:11:58,459 [salt.loaded.ext.module.maas:92  ][INFO    ][7046] maasconfig name=kernel_opts value=spectre_v2=off nopti kpti=off nospec_store_bypass_disable noibrs noibpb
2020-02-21 05:11:58,489 [salt.loaded.ext.module.maas:92  ][INFO    ][7046] maasconfig name=network_discovery value=enabled
2020-02-21 05:11:58,931 [salt.loaded.ext.module.maas:92  ][INFO    ][7046] maasconfig name=enable_third_party_drivers value=True
2020-02-21 05:11:58,969 [salt.loaded.ext.module.maas:92  ][INFO    ][7046] maasconfig name=default_storage_layout value=lvm
2020-02-21 05:11:58,998 [salt.loaded.ext.module.maas:92  ][INFO    ][7046] maasconfig name=maas_name value=mas01
2020-02-21 05:11:59,237 [salt.loaded.ext.module.maas:92  ][INFO    ][7046] maasconfig name=disk_erase_with_secure_erase value=False
2020-02-21 05:11:59,269 [salt.loaded.ext.module.maas:92  ][INFO    ][7046] maasconfig name=default_distro_series value=bionic
2020-02-21 05:11:59,635 [salt.loaded.ext.module.maas:92  ][INFO    ][7046] maasconfig name=ntp_external_only value=True
2020-02-21 05:11:59,678 [salt.loaded.ext.module.maas:92  ][INFO    ][7046] maasconfig name=default_min_hwe_kernel value=ga-18.04
2020-02-21 05:11:59,737 [salt.state       :300 ][INFO    ][7046] {'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-21 05:11:59,738 [salt.state       :1951][INFO    ][7046] Completed state [maas.process_maas_config] at time 05:11:59.738207 duration_in_ms=2839.805
2020-02-21 05:11:59,739 [salt.state       :1780][INFO    ][7046] Running state [pxe_admin] at time 05:11:59.739333
2020-02-21 05:11:59,739 [salt.state       :1813][INFO    ][7046] Executing state maasng.fabric_present for [pxe_admin]
2020-02-21 05:11:59,783 [salt.loaded.ext.module.maasng:945 ][INFO    ][7046] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'pxe_admin', u'id': 2}]
2020-02-21 05:11:59,835 [salt.loaded.ext.module.maasng:1008][WARNING ][7046] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2020-02-21 05:11:59,836 [salt.loaded.ext.module.maasng:1011][WARNING ][7046] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2020-02-21 05:11:59,873 [salt.state       :300 ][INFO    ][7046] {'new': 'Fabric  pxe_admin created', 'result': True}
2020-02-21 05:11:59,874 [salt.state       :1951][INFO    ][7046] Completed state [pxe_admin] at time 05:11:59.873992 duration_in_ms=134.66
2020-02-21 05:11:59,874 [salt.state       :1780][INFO    ][7046] Running state [vlan 0] at time 05:11:59.874315
2020-02-21 05:11:59,874 [salt.state       :1813][INFO    ][7046] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-02-21 05:11:59,927 [salt.loaded.ext.module.maasng:945 ][INFO    ][7046] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'pxe_admin', u'id': 2}]
2020-02-21 05:12:00,004 [salt.loaded.ext.module.maasng:945 ][INFO    ][7046] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'pxe_admin', u'id': 2}]
2020-02-21 05:12:00,195 [salt.loaded.ext.module.maasng:945 ][INFO    ][7046] [{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': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2020-02-21 05:12:00,245 [salt.state       :300 ][INFO    ][7046] {'new': 'Vlan untagged was updated'}
2020-02-21 05:12:00,246 [salt.state       :1951][INFO    ][7046] Completed state [vlan 0] at time 05:12:00.246048 duration_in_ms=371.732
2020-02-21 05:12:00,247 [salt.state       :1780][INFO    ][7046] Running state [192.168.11.0/24] at time 05:12:00.247025
2020-02-21 05:12:00,247 [salt.state       :1813][INFO    ][7046] Executing state maasng.subnet_present for [192.168.11.0/24]
2020-02-21 05:12:00,401 [salt.loaded.ext.module.maasng:945 ][INFO    ][7046] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'pxe_admin', u'id': 2}]
2020-02-21 05:12:00,401 [salt.loaded.ext.module.maasng:1235][WARNING ][7046] Ignoring parameter vlan:0
2020-02-21 05:12:00,442 [salt.state       :300 ][INFO    ][7046] Subnet 192.168.11.0/24 has been updated for pxe_admin
2020-02-21 05:12:00,442 [salt.state       :1951][INFO    ][7046] Completed state [192.168.11.0/24] at time 05:12:00.442455 duration_in_ms=195.43
2020-02-21 05:12:00,443 [salt.state       :1780][INFO    ][7046] Running state [maas_create_iprange_1] at time 05:12:00.443273
2020-02-21 05:12:00,443 [salt.state       :1813][INFO    ][7046] Executing state maasng.iprange_present for [maas_create_iprange_1]
2020-02-21 05:12:00,477 [salt.state       :300 ][INFO    ][7046] Iprange maas_create_iprange_1 already exist.
2020-02-21 05:12:00,477 [salt.state       :1951][INFO    ][7046] Completed state [maas_create_iprange_1] at time 05:12:00.477431 duration_in_ms=34.158
2020-02-21 05:12:00,477 [salt.state       :1780][INFO    ][7046] Running state [vlan 0] at time 05:12:00.477708
2020-02-21 05:12:00,477 [salt.state       :1813][INFO    ][7046] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2020-02-21 05:12:00,512 [salt.loaded.ext.module.maasng:945 ][INFO    ][7046] [{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': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {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': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2020-02-21 05:12:00,579 [salt.loaded.ext.module.maasng:945 ][INFO    ][7046] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': None, u'name': u'pxe_admin', u'id': 2}]
2020-02-21 05:12:00,777 [salt.loaded.ext.module.maasng:945 ][INFO    ][7046] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'pxe_admin', u'id': 2}]
2020-02-21 05:12:00,864 [salt.state       :300 ][INFO    ][7046] {'new': 'Vlan untagged was updated'}
2020-02-21 05:12:00,866 [salt.state       :1951][INFO    ][7046] Completed state [vlan 0] at time 05:12:00.865927 duration_in_ms=388.217
2020-02-21 05:12:00,866 [salt.state       :1780][INFO    ][7046] Running state [opnfv] at time 05:12:00.866645
2020-02-21 05:12:00,866 [salt.state       :1813][INFO    ][7046] Executing state maasng.sshkey_present for [opnfv]
2020-02-21 05:12:00,902 [salt.loaded.ext.module.maasng:1903][INFO    ][7046] [{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-21 05:12:00,903 [salt.state       :300 ][INFO    ][7046] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2020-02-21 05:12:00,903 [salt.state       :1951][INFO    ][7046] Completed state [opnfv] at time 05:12:00.903398 duration_in_ms=36.753
2020-02-21 05:12:00,904 [salt.state       :1780][INFO    ][7046] Running state [maas.process_tags] at time 05:12:00.904018
2020-02-21 05:12:00,904 [salt.state       :1813][INFO    ][7046] Executing state module.run for [maas.process_tags]
2020-02-21 05:12:00,904 [salt.utils.decorators:613 ][WARNING ][7046] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-21 05:12:00,932 [salt.loaded.ext.module.maas:92  ][INFO    ][7046] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"]|//capability[@id="cp15_barrier"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G kpti=off
2020-02-21 05:12:00,967 [salt.state       :300 ][INFO    ][7046] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2020-02-21 05:12:00,967 [salt.state       :1951][INFO    ][7046] Completed state [maas.process_tags] at time 05:12:00.967477 duration_in_ms=63.46
2020-02-21 05:12:00,970 [salt.minion      :1711][INFO    ][7046] Returning information for job: 20200221051137693810
2020-02-21 05:12:01,913 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command state.apply with jid 20200221051201893434
2020-02-21 05:12:01,943 [salt.minion      :1432][INFO    ][7169] Starting a new job with PID 7169
2020-02-21 05:12:08,238 [salt.state       :915 ][INFO    ][7169] Loading fresh modules for state activity
2020-02-21 05:12:08,347 [salt.state       :1780][INFO    ][7169] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:12:08.347039
2020-02-21 05:12:08,347 [salt.state       :1813][INFO    ][7169] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-21 05:12:08,349 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7169] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-21 05:12:10,491 [salt.state       :300 ][INFO    ][7169] {'pid': 7190, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-21 05:12:10,492 [salt.state       :1951][INFO    ][7169] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:12:10.492186 duration_in_ms=2145.146
2020-02-21 05:12:10,494 [salt.state       :1780][INFO    ][7169] Running state [maas.process_machines] at time 05:12:10.494524
2020-02-21 05:12:10,494 [salt.state       :1813][INFO    ][7169] Executing state module.run for [maas.process_machines]
2020-02-21 05:12:10,495 [salt.utils.decorators:613 ][WARNING ][7169] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-21 05:12:11,107 [salt.loaded.ext.module.maas:412 ][WARNING ][7169] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-21 05:12:11,108 [salt.loaded.ext.module.maas:92  ][INFO    ][7169] machine hostname=gtw01 power_type=ipmi mac_addresses=['14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=fnnyhg architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-21 05:12:11,842 [salt.loaded.ext.module.maas:412 ][WARNING ][7169] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-21 05:12:11,843 [salt.loaded.ext.module.maas:92  ][INFO    ][7169] 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=yhqde3 architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-21 05:12:12,584 [salt.loaded.ext.module.maas:412 ][WARNING ][7169] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-21 05:12:12,585 [salt.loaded.ext.module.maas:92  ][INFO    ][7169] machine hostname=odl01 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=q6pccn architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-21 05:12:13,334 [salt.loaded.ext.module.maas:412 ][WARNING ][7169] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-21 05:12:13,335 [salt.loaded.ext.module.maas:92  ][INFO    ][7169] 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=xmhcy8 architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-21 05:12:14,035 [salt.loaded.ext.module.maas:412 ][WARNING ][7169] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2020-02-21 05:12:14,037 [salt.loaded.ext.module.maas:92  ][INFO    ][7169] machine hostname=ctl01 power_type=ipmi mac_addresses=['14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=kpa8tw architecture=amd64/generic power_parameters_power_user=opnfv
2020-02-21 05:12:14,839 [salt.state       :300 ][INFO    ][7169] {'ret': {'updated': ['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2020-02-21 05:12:14,839 [salt.state       :1951][INFO    ][7169] Completed state [maas.process_machines] at time 05:12:14.839504 duration_in_ms=4344.978
2020-02-21 05:12:14,843 [salt.minion      :1711][INFO    ][7169] Returning information for job: 20200221051201893434
2020-02-21 05:12:48,618 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command state.apply with jid 20200221051248602813
2020-02-21 05:12:48,642 [salt.minion      :1432][INFO    ][7223] Starting a new job with PID 7223
2020-02-21 05:12:54,927 [salt.state       :915 ][INFO    ][7223] Loading fresh modules for state activity
2020-02-21 05:12:55,030 [salt.state       :1780][INFO    ][7223] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:12:55.030631
2020-02-21 05:12:55,031 [salt.state       :1813][INFO    ][7223] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-21 05:12:55,033 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7223] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-21 05:12:57,217 [salt.state       :300 ][INFO    ][7223] {'pid': 7231, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-21 05:12:57,218 [salt.state       :1951][INFO    ][7223] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:12:57.218649 duration_in_ms=2188.017
2020-02-21 05:12:57,222 [salt.state       :1780][INFO    ][7223] Running state [maas.wait_for_machine_status] at time 05:12:57.222716
2020-02-21 05:12:57,223 [salt.state       :1813][INFO    ][7223] Executing state module.run for [maas.wait_for_machine_status]
2020-02-21 05:12:57,224 [salt.utils.decorators:613 ][WARNING ][7223] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-21 05:13:00,358 [salt.state       :300 ][INFO    ][7223] {'ret': True}
2020-02-21 05:13:00,359 [salt.state       :1951][INFO    ][7223] Completed state [maas.wait_for_machine_status] at time 05:13:00.359294 duration_in_ms=3136.578
2020-02-21 05:13:00,363 [salt.minion      :1711][INFO    ][7223] Returning information for job: 20200221051248602813
2020-02-21 05:13:01,147 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command state.apply with jid 20200221051301137414
2020-02-21 05:13:01,172 [salt.minion      :1432][INFO    ][7248] Starting a new job with PID 7248
2020-02-21 05:13:02,134 [salt.state       :915 ][INFO    ][7248] Loading fresh modules for state activity
2020-02-21 05:13:02,274 [salt.state       :1780][INFO    ][7248] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:02.274404
2020-02-21 05:13:02,274 [salt.state       :1813][INFO    ][7248] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-21 05:13:02,276 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7248] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-21 05:13:04,298 [salt.state       :300 ][INFO    ][7248] {'pid': 7255, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-21 05:13:04,299 [salt.state       :1951][INFO    ][7248] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:04.298967 duration_in_ms=2024.561
2020-02-21 05:13:04,301 [salt.state       :1780][INFO    ][7248] Running state [maas_machines_storage_cmp002_lvm] at time 05:13:04.301751
2020-02-21 05:13:04,302 [salt.state       :1813][INFO    ][7248] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2020-02-21 05:13:05,587 [salt.loaded.ext.module.maasng:610 ][INFO    ][7248] yhqde3
2020-02-21 05:13:05,588 [salt.loaded.ext.module.maasng:626 ][INFO    ][7248] sda
2020-02-21 05:13:06,176 [salt.loaded.ext.module.maasng:361 ][INFO    ][7248] yhqde3
2020-02-21 05:13:06,256 [salt.loaded.ext.module.maasng:367 ][INFO    ][7248] [{u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'448c6c18-0ec5-45a7-b53f-ac95262f38db', u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/blockdevices/1/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yhqde3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd6b10995-bd08-4db3-a04b-cd784fc6a42c', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 8, u'device_id': 1}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'yhqde3', 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/yhqde3/blockdevices/13/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'7cb879c3-8766-4049-806a-3c42ef9975c5', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'yhqde3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'6ef13be4-4025-430e-a48b-027b16d4bf64', 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': 13, u'serial': None, u'size': 800097042432}]
2020-02-21 05:13:06,256 [salt.loaded.ext.module.maasng:632 ][INFO    ][7248] vgroot
2020-02-21 05:13:06,257 [salt.loaded.ext.module.maasng:635 ][INFO    ][7248] lvroot
2020-02-21 05:13:06,257 [salt.loaded.ext.module.maasng:639 ][INFO    ][7248] 107374182400
2020-02-21 05:13:06,869 [salt.loaded.ext.module.maasng:645 ][INFO    ][7248] {u'node_type_name': u'Machine', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': None, u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 62, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 5, u'discovered': [], u'parents': [], u'system_id': u'yhqde3', u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/interfaces/5/'}, u'fqdn': u'cmp002.maas', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'88eedb11-ef92-4907-a99b-e62b04c0b945', u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/blockdevices/1/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yhqde3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'05530be9-2fc9-4444-bf05-d6981e144375', 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'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'yhqde3', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/blockdevices/15/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'8387888a-31e3-4312-8329-1564b65efc1b', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'yhqde3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'1c233e24-57f0-4bc7-843c-0c6033d481c0', 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': 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/yhqde3/', u'current_commissioning_result_id': 19, u'hardware_info': {u'mainboard_firmware_version': u'I31', u'mainboard_product': u'Unknown', u'system_vendor': u'HP', u'cpu_model': u'Intel(R) Xeon(R) CPU E5-2680 v2', u'mainboard_firmware_date': u'12/20/2013', u'mainboard_vendor': u'Unknown', u'system_serial': u'USE410T2Y0', u'system_version': u'Unknown', u'system_product': u'ProLiant BL460c Gen8 (735151-B21)'}, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'yhqde3', 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'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'uuid': None, u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/blockdevices/1/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'yhqde3', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'firmware_version': u'5.22', u'size': 800109715456, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'88eedb11-ef92-4907-a99b-e62b04c0b945', u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/blockdevices/1/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yhqde3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'05530be9-2fc9-4444-bf05-d6981e144375', 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'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'cpu_count': 40, 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'yhqde3', u'id': 10}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'memory_test_status_name': u'Unknown', 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'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'8387888a-31e3-4312-8329-1564b65efc1b', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/blockdevices/15/', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'yhqde3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'1c233e24-57f0-4bc7-843c-0c6033d481c0', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'type': u'virtual', u'id': 15, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 62, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 5, u'discovered': [], u'parents': [], u'system_id': u'yhqde3', u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/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'id': 11, u'discovered': None, u'parents': [], u'system_id': u'yhqde3', u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/interfaces/11/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 63, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 12, u'discovered': [], u'parents': [], u'system_id': u'yhqde3', u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/interfaces/12/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': None, u'vendor': u'HP', u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 13, u'discovered': None, u'parents': [], u'system_id': u'yhqde3', u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/interfaces/13/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 64, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 14, u'discovered': None, u'parents': [], u'system_id': u'yhqde3', u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/interfaces/14/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 65, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 15, u'discovered': None, u'parents': [], u'system_id': u'yhqde3', u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/interfaces/15/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'uuid': None, u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/blockdevices/1/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'yhqde3', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'firmware_version': u'5.22', u'size': 800109715456, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'88eedb11-ef92-4907-a99b-e62b04c0b945', u'resource_uri': u'/MAAS/api/2.0/nodes/yhqde3/blockdevices/1/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yhqde3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'05530be9-2fc9-4444-bf05-d6981e144375', 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}]}}
2020-02-21 05:13:06,871 [salt.state       :300 ][INFO    ][7248] {'new': {'storage_layout': 'lvm'}}
2020-02-21 05:13:06,871 [salt.state       :1951][INFO    ][7248] Completed state [maas_machines_storage_cmp002_lvm] at time 05:13:06.871635 duration_in_ms=2569.882
2020-02-21 05:13:06,872 [salt.state       :1780][INFO    ][7248] Running state [maas_machines_storage_cmp001_lvm] at time 05:13:06.872106
2020-02-21 05:13:06,872 [salt.state       :1813][INFO    ][7248] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2020-02-21 05:13:08,350 [salt.loaded.ext.module.maasng:610 ][INFO    ][7248] xmhcy8
2020-02-21 05:13:08,351 [salt.loaded.ext.module.maasng:626 ][INFO    ][7248] sda
2020-02-21 05:13:08,946 [salt.loaded.ext.module.maasng:361 ][INFO    ][7248] xmhcy8
2020-02-21 05:13:09,035 [salt.loaded.ext.module.maasng:367 ][INFO    ][7248] [{u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'f21d2ae4-ae6b-458e-baff-a41559d069cf', u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/2/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xmhcy8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'abae3a7e-8351-4279-b262-f9b5110cc090', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 9, u'device_id': 2}], u'uuid': None, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'xmhcy8', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/14/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'uuid': u'9f0d075e-f9f7-473c-9a1a-7d8c57d5f1b2', u'id': 14, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xmhcy8', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'56e96399-c8f1-4870-b10c-d240d179615b', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'block_size': 4096, u'type': u'virtual', u'model': None, u'serial': None, u'size': 800097042432}]
2020-02-21 05:13:09,036 [salt.loaded.ext.module.maasng:632 ][INFO    ][7248] vgroot
2020-02-21 05:13:09,037 [salt.loaded.ext.module.maasng:635 ][INFO    ][7248] lvroot
2020-02-21 05:13:09,037 [salt.loaded.ext.module.maasng:639 ][INFO    ][7248] 107374182400
2020-02-21 05:13:09,644 [salt.loaded.ext.module.maasng:645 ][INFO    ][7248] {u'node_type_name': u'Machine', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'is_default': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': None, u'boot_interface': {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 70, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 7, u'discovered': [], u'parents': [], u'system_id': u'xmhcy8', u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/interfaces/7/'}, u'fqdn': u'cmp001.maas', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'cpu_speed': 2800, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'f81c3b21-2377-46b1-b9bc-aaa44fa0b083', u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/2/partition/11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xmhcy8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0796d2c5-2791-42ff-be1f-ed4fb74f9b8b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 11, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'xmhcy8', 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/xmhcy8/blockdevices/16/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'c500eadd-c9b7-4050-bbee-7b55ef877f19', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'xmhcy8', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'1887e316-346c-4d16-91ab-cb5e272d0d98', 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'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/xmhcy8/', u'current_commissioning_result_id': 21, 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'xmhcy8', 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'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'uuid': None, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'xmhcy8', 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'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'f81c3b21-2377-46b1-b9bc-aaa44fa0b083', u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/2/partition/11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xmhcy8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0796d2c5-2791-42ff-be1f-ed4fb74f9b8b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 11, u'size': 800101236736}]}], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'cpu_count': 40, 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'xmhcy8', u'id': 11}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'memory_test_status_name': u'Unknown', u'current_testing_result_id': 22, u'cpu_test_status': -1, u'pool': {u'id': 0, u'resource_uri': u'/MAAS/api/2.0/resourcepool/0/', u'name': u'default', u'description': u'Default pool'}, u'storage_test_status': 2, u'locked': False, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'c500eadd-c9b7-4050-bbee-7b55ef877f19', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/16/', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'xmhcy8', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'1887e316-346c-4d16-91ab-cb5e272d0d98', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'type': u'virtual', u'id': 16, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'vendor': u'HP', u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 70, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 7, u'discovered': [], u'parents': [], u'system_id': u'xmhcy8', u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/interfaces/7/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': None, u'vendor': u'HP', u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 16, u'discovered': None, u'parents': [], u'system_id': u'xmhcy8', u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/interfaces/16/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'vendor': u'HP', u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ge4xfa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 71, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 17, u'discovered': [], u'parents': [], u'system_id': u'xmhcy8', u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/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'id': 18, u'discovered': None, u'parents': [], u'system_id': u'xmhcy8', u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/interfaces/18/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'vendor': u'HP', u'name': u'ens2f0', u'links': [{u'id': 72, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 19, u'discovered': None, u'parents': [], u'system_id': u'xmhcy8', u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/interfaces/19/'}, {u'product': u'ProLiant BL460c Gen8 (735151-B21)', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'vendor': u'HP', u'name': u'ens2f1', u'links': [{u'id': 73, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 20, u'discovered': None, u'parents': [], u'system_id': u'xmhcy8', u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'firmware_version': u'bc 7.8.24', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'uuid': None, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'xmhcy8', 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'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'f81c3b21-2377-46b1-b9bc-aaa44fa0b083', u'resource_uri': u'/MAAS/api/2.0/nodes/xmhcy8/blockdevices/2/partition/11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xmhcy8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0796d2c5-2791-42ff-be1f-ed4fb74f9b8b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 11, u'size': 800101236736}]}}
2020-02-21 05:13:09,646 [salt.state       :300 ][INFO    ][7248] {'new': {'storage_layout': 'lvm'}}
2020-02-21 05:13:09,646 [salt.state       :1951][INFO    ][7248] Completed state [maas_machines_storage_cmp001_lvm] at time 05:13:09.646916 duration_in_ms=2774.809
2020-02-21 05:13:09,651 [salt.minion      :1711][INFO    ][7248] Returning information for job: 20200221051301137414
2020-02-21 05:13:10,433 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command state.apply with jid 20200221051310421918
2020-02-21 05:13:10,458 [salt.minion      :1432][INFO    ][7282] Starting a new job with PID 7282
2020-02-21 05:13:16,683 [salt.state       :915 ][INFO    ][7282] Loading fresh modules for state activity
2020-02-21 05:13:16,783 [salt.state       :1780][INFO    ][7282] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:16.783158
2020-02-21 05:13:16,783 [salt.state       :1813][INFO    ][7282] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-21 05:13:16,785 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7282] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-21 05:13:18,703 [salt.state       :300 ][INFO    ][7282] {'pid': 7296, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-21 05:13:18,704 [salt.state       :1951][INFO    ][7282] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:18.704437 duration_in_ms=1921.279
2020-02-21 05:13:18,707 [salt.state       :1780][INFO    ][7282] Running state [maas.deploy_machines] at time 05:13:18.706952
2020-02-21 05:13:18,707 [salt.state       :1813][INFO    ][7282] Executing state module.run for [maas.deploy_machines]
2020-02-21 05:13:18,710 [salt.utils.decorators:613 ][WARNING ][7282] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-21 05:13:19,319 [salt.loaded.ext.module.maas:684 ][INFO    ][7282] deploymachines hwe_kernel=ga-18.04 system_id=yhqde3 distro_series=bionic
2020-02-21 05:13:21,084 [salt.loaded.ext.module.maas:684 ][INFO    ][7282] deploymachines hwe_kernel=ga-18.04 system_id=xmhcy8 distro_series=bionic
2020-02-21 05:13:23,052 [salt.state       :300 ][INFO    ][7282] {'ret': {'updated': ['gtw01', 'odl01', 'ctl01'], 'errors': {}, 'success': ['cmp002', 'cmp001']}}
2020-02-21 05:13:23,055 [salt.state       :1951][INFO    ][7282] Completed state [maas.deploy_machines] at time 05:13:23.055115 duration_in_ms=4348.163
2020-02-21 05:13:23,057 [salt.minion      :1711][INFO    ][7282] Returning information for job: 20200221051310421918
2020-02-21 05:13:23,839 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command state.apply with jid 20200221051323829421
2020-02-21 05:13:23,863 [salt.minion      :1432][INFO    ][7316] Starting a new job with PID 7316
2020-02-21 05:13:24,719 [salt.state       :915 ][INFO    ][7316] Loading fresh modules for state activity
2020-02-21 05:13:24,828 [salt.state       :1780][INFO    ][7316] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:24.828025
2020-02-21 05:13:24,829 [salt.state       :1813][INFO    ][7316] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2020-02-21 05:13:24,831 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7316] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2020-02-21 05:13:27,000 [salt.state       :300 ][INFO    ][7316] {'pid': 7323, 'retcode': 0, 'stderr': '', 'stdout': ''}
2020-02-21 05:13:27,001 [salt.state       :1951][INFO    ][7316] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:27.001490 duration_in_ms=2173.464
2020-02-21 05:13:27,005 [salt.state       :1780][INFO    ][7316] Running state [maas.wait_for_machine_status] at time 05:13:27.004966
2020-02-21 05:13:27,005 [salt.state       :1813][INFO    ][7316] Executing state module.run for [maas.wait_for_machine_status]
2020-02-21 05:13:27,006 [salt.utils.decorators:613 ][WARNING ][7316] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2020-02-21 05:13:30,225 [salt.loaded.ext.module.maas:1024][INFO    ][7316] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2246.79289699s left)
2020-02-21 05:13:38,946 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221051338878917
2020-02-21 05:13:38,974 [salt.minion      :1432][INFO    ][7353] Starting a new job with PID 7353
2020-02-21 05:13:38,996 [salt.minion      :1711][INFO    ][7353] Returning information for job: 20200221051338878917
2020-02-21 05:14:03,736 [salt.loaded.ext.module.maas:1024][INFO    ][7316] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2213.28244495s left)
2020-02-21 05:14:09,030 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221051409018040
2020-02-21 05:14:09,055 [salt.minion      :1432][INFO    ][7388] Starting a new job with PID 7388
2020-02-21 05:14:09,081 [salt.minion      :1711][INFO    ][7388] Returning information for job: 20200221051409018040
2020-02-21 05:14:36,886 [salt.loaded.ext.module.maas:1024][INFO    ][7316] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2180.13241696s left)
2020-02-21 05:14:39,090 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221051439079949
2020-02-21 05:14:39,116 [salt.minion      :1432][INFO    ][7424] Starting a new job with PID 7424
2020-02-21 05:14:39,139 [salt.minion      :1711][INFO    ][7424] Returning information for job: 20200221051439079949
2020-02-21 05:15:09,173 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221051509163187
2020-02-21 05:15:09,189 [salt.minion      :1432][INFO    ][7452] Starting a new job with PID 7452
2020-02-21 05:15:09,213 [salt.minion      :1711][INFO    ][7452] Returning information for job: 20200221051509163187
2020-02-21 05:15:10,114 [salt.loaded.ext.module.maas:1024][INFO    ][7316] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2146.90414095s left)
2020-02-21 05:15:39,239 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221051539227419
2020-02-21 05:15:39,267 [salt.minion      :1432][INFO    ][7477] Starting a new job with PID 7477
2020-02-21 05:15:39,287 [salt.minion      :1711][INFO    ][7477] Returning information for job: 20200221051539227419
2020-02-21 05:15:43,225 [salt.loaded.ext.module.maas:1024][INFO    ][7316] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2113.79299307s left)
2020-02-21 05:16:09,288 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221051609278962
2020-02-21 05:16:09,309 [salt.minion      :1432][INFO    ][7515] Starting a new job with PID 7515
2020-02-21 05:16:09,331 [salt.minion      :1711][INFO    ][7515] Returning information for job: 20200221051609278962
2020-02-21 05:16:16,569 [salt.loaded.ext.module.maas:1024][INFO    ][7316] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2080.44869304s left)
2020-02-21 05:16:39,357 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221051639342706
2020-02-21 05:16:39,381 [salt.minion      :1432][INFO    ][7572] Starting a new job with PID 7572
2020-02-21 05:16:39,405 [salt.minion      :1711][INFO    ][7572] Returning information for job: 20200221051639342706
2020-02-21 05:16:49,925 [salt.loaded.ext.module.maas:1024][INFO    ][7316] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2047.09295011s left)
2020-02-21 05:17:09,450 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221051709436800
2020-02-21 05:17:09,472 [salt.minion      :1432][INFO    ][7632] Starting a new job with PID 7632
2020-02-21 05:17:09,496 [salt.minion      :1711][INFO    ][7632] Returning information for job: 20200221051709436800
2020-02-21 05:17:23,534 [salt.loaded.ext.module.maas:1024][INFO    ][7316] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (2013.48440289s left)
2020-02-21 05:17:39,533 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221051739520707
2020-02-21 05:17:39,554 [salt.minion      :1432][INFO    ][7660] Starting a new job with PID 7660
2020-02-21 05:17:39,574 [salt.minion      :1711][INFO    ][7660] Returning information for job: 20200221051739520707
2020-02-21 05:17:57,145 [salt.loaded.ext.module.maas:1024][INFO    ][7316] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1979.87266302s left)
2020-02-21 05:18:09,619 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221051809605444
2020-02-21 05:18:09,645 [salt.minion      :1432][INFO    ][7688] Starting a new job with PID 7688
2020-02-21 05:18:09,670 [salt.minion      :1711][INFO    ][7688] Returning information for job: 20200221051809605444
2020-02-21 05:18:30,584 [salt.loaded.ext.module.maas:1024][INFO    ][7316] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1946.43399s left)
2020-02-21 05:18:39,707 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221051839700190
2020-02-21 05:18:39,735 [salt.minion      :1432][INFO    ][7716] Starting a new job with PID 7716
2020-02-21 05:18:39,766 [salt.minion      :1711][INFO    ][7716] Returning information for job: 20200221051839700190
2020-02-21 05:19:04,227 [salt.loaded.ext.module.maas:1024][INFO    ][7316] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1912.790874s left)
2020-02-21 05:19:09,803 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221051909793321
2020-02-21 05:19:09,832 [salt.minion      :1432][INFO    ][7745] Starting a new job with PID 7745
2020-02-21 05:19:09,856 [salt.minion      :1711][INFO    ][7745] Returning information for job: 20200221051909793321
2020-02-21 05:19:37,747 [salt.loaded.ext.module.maas:1024][INFO    ][7316] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1879.27076602s left)
2020-02-21 05:19:39,934 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221051939920276
2020-02-21 05:19:39,958 [salt.minion      :1432][INFO    ][7771] Starting a new job with PID 7771
2020-02-21 05:19:39,983 [salt.minion      :1711][INFO    ][7771] Returning information for job: 20200221051939920276
2020-02-21 05:20:10,039 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221052010029715
2020-02-21 05:20:10,065 [salt.minion      :1432][INFO    ][7798] Starting a new job with PID 7798
2020-02-21 05:20:10,088 [salt.minion      :1711][INFO    ][7798] Returning information for job: 20200221052010029715
2020-02-21 05:20:11,483 [salt.loaded.ext.module.maas:1024][INFO    ][7316] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1845.534971s left)
2020-02-21 05:20:40,138 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221052040131513
2020-02-21 05:20:40,158 [salt.minion      :1432][INFO    ][7824] Starting a new job with PID 7824
2020-02-21 05:20:40,178 [salt.minion      :1711][INFO    ][7824] Returning information for job: 20200221052040131513
2020-02-21 05:20:45,091 [salt.loaded.ext.module.maas:1024][INFO    ][7316] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1811.92719102s left)
2020-02-21 05:21:10,240 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221052110231260
2020-02-21 05:21:10,258 [salt.minion      :1432][INFO    ][7850] Starting a new job with PID 7850
2020-02-21 05:21:10,283 [salt.minion      :1711][INFO    ][7850] Returning information for job: 20200221052110231260
2020-02-21 05:21:18,425 [salt.loaded.ext.module.maas:1024][INFO    ][7316] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1778.59344101s left)
2020-02-21 05:21:40,353 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221052140343934
2020-02-21 05:21:40,377 [salt.minion      :1432][INFO    ][7877] Starting a new job with PID 7877
2020-02-21 05:21:40,397 [salt.minion      :1711][INFO    ][7877] Returning information for job: 20200221052140343934
2020-02-21 05:21:51,931 [salt.loaded.ext.module.maas:1024][INFO    ][7316] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1745.08729196s left)
2020-02-21 05:22:10,462 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221052210451750
2020-02-21 05:22:10,482 [salt.minion      :1432][INFO    ][7907] Starting a new job with PID 7907
2020-02-21 05:22:10,505 [salt.minion      :1711][INFO    ][7907] Returning information for job: 20200221052210451750
2020-02-21 05:22:25,395 [salt.loaded.ext.module.maas:1024][INFO    ][7316] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1711.62274909s left)
2020-02-21 05:22:40,577 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221052240567064
2020-02-21 05:22:40,601 [salt.minion      :1432][INFO    ][7936] Starting a new job with PID 7936
2020-02-21 05:22:40,622 [salt.minion      :1711][INFO    ][7936] Returning information for job: 20200221052240567064
2020-02-21 05:22:58,867 [salt.loaded.ext.module.maas:1024][INFO    ][7316] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1678.15124893s left)
2020-02-21 05:23:10,696 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221052310682245
2020-02-21 05:23:10,713 [salt.minion      :1432][INFO    ][7963] Starting a new job with PID 7963
2020-02-21 05:23:10,743 [salt.minion      :1711][INFO    ][7963] Returning information for job: 20200221052310682245
2020-02-21 05:23:32,265 [salt.loaded.ext.module.maas:1024][INFO    ][7316] Waiting status:Deployed for machines:['cmp002', 'cmp001']
sleep for:30s Timeout:2250s (1644.75266409s left)
2020-02-21 05:23:40,725 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221052340714337
2020-02-21 05:23:40,750 [salt.minion      :1432][INFO    ][8004] Starting a new job with PID 8004
2020-02-21 05:23:40,772 [salt.minion      :1711][INFO    ][8004] Returning information for job: 20200221052340714337
2020-02-21 05:24:05,879 [salt.loaded.ext.module.maas:1024][INFO    ][7316] Waiting status:Deployed for machines:['cmp001']
sleep for:30s Timeout:2250s (1611.13892698s left)
2020-02-21 05:24:10,870 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command saltutil.find_job with jid 20200221052410859634
2020-02-21 05:24:10,894 [salt.minion      :1432][INFO    ][8049] Starting a new job with PID 8049
2020-02-21 05:24:10,917 [salt.minion      :1711][INFO    ][8049] Returning information for job: 20200221052410859634
2020-02-21 05:24:39,470 [salt.state       :300 ][INFO    ][7316] {'ret': True}
2020-02-21 05:24:39,470 [salt.state       :1951][INFO    ][7316] Completed state [maas.wait_for_machine_status] at time 05:24:39.470388 duration_in_ms=672465.42
2020-02-21 05:24:39,474 [salt.minion      :1711][INFO    ][7316] Returning information for job: 20200221051323829421
2020-02-21 05:25:02,041 [salt.utils.schedule:1377][INFO    ][3380] Running scheduled job: __mine_interval
2020-02-21 06:14:02,254 [salt.minion      :1308][INFO    ][3380] User sudo_ubuntu Executing command cp.push_dir with jid 20200221061402244069
2020-02-21 06:14:02,283 [salt.minion      :1432][INFO    ][10350] Starting a new job with PID 10350
