2019-05-18 02:06:20,040 [salt.utils.decorators:613 ][WARNING ][2097] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 02:06:20,939 [salt.utils.decorators:613 ][WARNING ][2097] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 02:06:23,949 [salt.loaded.int.states.file:2298][WARNING ][2566] 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.
2019-05-18 02:06:52,539 [salt.state       :2022][WARNING ][3069] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-18 02:06:55,621 [salt.utils.decorators:613 ][WARNING ][3069] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 02:07:08,579 [salt.utils.decorators:613 ][WARNING ][3069] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 02:07:35,675 [salt.utils.decorators:613 ][WARNING ][3069] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 02:07:36,730 [salt.utils.decorators:613 ][WARNING ][3069] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 02:07:38,734 [salt.loaded.ext.module.maasng:1008][WARNING ][3069] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-05-18 02:07:38,734 [salt.loaded.ext.module.maasng:1011][WARNING ][3069] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-05-18 02:07:39,522 [salt.loaded.ext.module.maasng:1235][WARNING ][3069] Ignoring parameter vlan:0
2019-05-18 02:07:43,280 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5541] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-18 02:07:43,316 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5541] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-18 02:07:43,366 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-05-18 02:07:44,375 [salt.cli.daemons :293 ][INFO    ][5630] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-05-18 02:07:44,496 [salt.cli.daemons :82  ][INFO    ][5630] Starting up the Salt Minion
2019-05-18 02:07:44,496 [salt.utils.event :1017][INFO    ][5630] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-05-18 02:07:45,654 [salt.minion      :976 ][INFO    ][5630] Creating minion process manager
2019-05-18 02:07:47,598 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5630] Executing command ['date', '+%z'] in directory '/root'
2019-05-18 02:07:47,625 [salt.utils.schedule:568 ][INFO    ][5630] Updating job settings for scheduled job: __mine_interval
2019-05-18 02:07:47,627 [salt.minion      :1108][INFO    ][5630] Added mine.update to scheduler
2019-05-18 02:07:47,633 [salt.minion      :1975][INFO    ][5630] Minion is starting as user 'root'
2019-05-18 02:07:47,646 [salt.minion      :2336][INFO    ][5630] Minion is ready to receive requests!
2019-05-18 02:07:49,397 [salt.utils.decorators:613 ][WARNING ][5547] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 02:07:49,469 [salt.loaded.ext.module.maas:412 ][WARNING ][5547] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-18 02:07:50,880 [salt.loaded.ext.module.maas:412 ][WARNING ][5547] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-18 02:07:52,224 [salt.loaded.ext.module.maas:412 ][WARNING ][5547] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-18 02:07:53,579 [salt.loaded.ext.module.maas:412 ][WARNING ][5547] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-18 02:07:54,865 [salt.loaded.ext.module.maas:412 ][WARNING ][5547] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-18 02:07:56,108 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518020756094736
2019-05-18 02:07:56,131 [salt.minion      :1432][INFO    ][5996] Starting a new job with PID 5996
2019-05-18 02:07:56,154 [salt.minion      :1711][INFO    ][5996] Returning information for job: 20190518020756094736
2019-05-18 02:08:27,564 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command state.apply with jid 20190518020827551830
2019-05-18 02:08:27,588 [salt.minion      :1432][INFO    ][6056] Starting a new job with PID 6056
2019-05-18 02:08:33,559 [salt.state       :915 ][INFO    ][6056] Loading fresh modules for state activity
2019-05-18 02:08:33,620 [salt.fileclient  :1219][INFO    ][6056] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-18 02:08:33,671 [salt.state       :1780][INFO    ][6056] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:08:33.671521
2019-05-18 02:08:33,671 [salt.state       :1813][INFO    ][6056] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-18 02:08:33,673 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6056] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-18 02:08:35,433 [salt.state       :300 ][INFO    ][6056] {'pid': 6063, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-18 02:08:35,434 [salt.state       :1951][INFO    ][6056] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:08:35.434723 duration_in_ms=1763.203
2019-05-18 02:08:35,436 [salt.state       :1780][INFO    ][6056] Running state [maas.wait_for_machine_status] at time 02:08:35.436875
2019-05-18 02:08:35,437 [salt.state       :1813][INFO    ][6056] Executing state module.run for [maas.wait_for_machine_status]
2019-05-18 02:08:35,437 [salt.utils.decorators:613 ][WARNING ][6056] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 02:08:36,701 [salt.loaded.ext.module.maas:1023][INFO    ][6056] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.74599314s left)
2019-05-18 02:08:42,644 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518020842633207
2019-05-18 02:08:42,659 [salt.minion      :1432][INFO    ][6100] Starting a new job with PID 6100
2019-05-18 02:08:42,685 [salt.minion      :1711][INFO    ][6100] Returning information for job: 20190518020842633207
2019-05-18 02:09:07,565 [salt.loaded.ext.module.maas:1023][INFO    ][6056] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.88193917s left)
2019-05-18 02:09:12,704 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518020912693386
2019-05-18 02:09:12,732 [salt.minion      :1432][INFO    ][6160] Starting a new job with PID 6160
2019-05-18 02:09:12,752 [salt.minion      :1711][INFO    ][6160] Returning information for job: 20190518020912693386
2019-05-18 02:09:38,419 [salt.loaded.ext.module.maas:1023][INFO    ][6056] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.027812s left)
2019-05-18 02:09:42,790 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518020942776133
2019-05-18 02:09:42,814 [salt.minion      :1432][INFO    ][6194] Starting a new job with PID 6194
2019-05-18 02:09:42,835 [salt.minion      :1711][INFO    ][6194] Returning information for job: 20190518020942776133
2019-05-18 02:10:09,299 [salt.loaded.ext.module.maas:1023][INFO    ][6056] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.14825916s left)
2019-05-18 02:10:12,860 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518021012845771
2019-05-18 02:10:12,889 [salt.minion      :1432][INFO    ][6241] Starting a new job with PID 6241
2019-05-18 02:10:12,913 [salt.minion      :1711][INFO    ][6241] Returning information for job: 20190518021012845771
2019-05-18 02:10:40,367 [salt.loaded.ext.module.maas:1023][INFO    ][6056] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.07999611s left)
2019-05-18 02:10:42,945 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518021042929488
2019-05-18 02:10:42,972 [salt.minion      :1432][INFO    ][6329] Starting a new job with PID 6329
2019-05-18 02:10:43,003 [salt.minion      :1711][INFO    ][6329] Returning information for job: 20190518021042929488
2019-05-18 02:11:11,519 [salt.loaded.ext.module.maas:1023][INFO    ][6056] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.92752814s left)
2019-05-18 02:11:13,044 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518021113032255
2019-05-18 02:11:13,073 [salt.minion      :1432][INFO    ][6515] Starting a new job with PID 6515
2019-05-18 02:11:13,096 [salt.minion      :1711][INFO    ][6515] Returning information for job: 20190518021113032255
2019-05-18 02:11:42,911 [salt.loaded.ext.module.maas:1023][INFO    ][6056] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.53598499s left)
2019-05-18 02:11:43,151 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518021143138396
2019-05-18 02:11:43,179 [salt.minion      :1432][INFO    ][6639] Starting a new job with PID 6639
2019-05-18 02:11:43,200 [salt.minion      :1711][INFO    ][6639] Returning information for job: 20190518021143138396
2019-05-18 02:12:13,259 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518021213251266
2019-05-18 02:12:13,278 [salt.minion      :1432][INFO    ][6913] Starting a new job with PID 6913
2019-05-18 02:12:13,300 [salt.minion      :1711][INFO    ][6913] Returning information for job: 20190518021213251266
2019-05-18 02:12:14,429 [salt.loaded.ext.module.maas:1023][INFO    ][6056] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.01786709s left)
2019-05-18 02:12:43,362 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518021243346662
2019-05-18 02:12:43,389 [salt.minion      :1432][INFO    ][7030] Starting a new job with PID 7030
2019-05-18 02:12:43,410 [salt.minion      :1711][INFO    ][7030] Returning information for job: 20190518021243346662
2019-05-18 02:12:46,614 [salt.loaded.ext.module.maas:1023][INFO    ][6056] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1248.83264804s left)
2019-05-18 02:13:13,544 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518021313531710
2019-05-18 02:13:13,571 [salt.minion      :1432][INFO    ][7467] Starting a new job with PID 7467
2019-05-18 02:13:13,593 [salt.minion      :1711][INFO    ][7467] Returning information for job: 20190518021313531710
2019-05-18 02:13:18,702 [salt.loaded.ext.module.maas:1023][INFO    ][6056] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1216.74513507s left)
2019-05-18 02:13:43,640 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518021343628301
2019-05-18 02:13:43,670 [salt.minion      :1432][INFO    ][7578] Starting a new job with PID 7578
2019-05-18 02:13:43,693 [salt.minion      :1711][INFO    ][7578] Returning information for job: 20190518021343628301
2019-05-18 02:13:50,951 [salt.loaded.ext.module.maas:1023][INFO    ][6056] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.49635315s left)
2019-05-18 02:14:13,780 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518021413760810
2019-05-18 02:14:13,804 [salt.minion      :1432][INFO    ][7716] Starting a new job with PID 7716
2019-05-18 02:14:13,827 [salt.minion      :1711][INFO    ][7716] Returning information for job: 20190518021413760810
2019-05-18 02:14:23,979 [salt.state       :300 ][INFO    ][6056] {'ret': True}
2019-05-18 02:14:23,979 [salt.state       :1951][INFO    ][6056] Completed state [maas.wait_for_machine_status] at time 02:14:23.979391 duration_in_ms=348542.515
2019-05-18 02:14:23,983 [salt.minion      :1711][INFO    ][6056] Returning information for job: 20190518020827551830
2019-05-18 02:14:24,589 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command state.apply with jid 20190518021424580413
2019-05-18 02:14:24,611 [salt.minion      :1432][INFO    ][7741] Starting a new job with PID 7741
2019-05-18 02:14:30,647 [salt.state       :915 ][INFO    ][7741] Loading fresh modules for state activity
2019-05-18 02:14:30,715 [salt.fileclient  :1219][INFO    ][7741] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-18 02:14:30,816 [salt.state       :1780][INFO    ][7741] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:30.816005
2019-05-18 02:14:30,816 [salt.state       :1813][INFO    ][7741] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-18 02:14:30,818 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7741] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-18 02:14:32,527 [salt.state       :300 ][INFO    ][7741] {'pid': 7771, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-18 02:14:32,528 [salt.state       :1951][INFO    ][7741] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:32.528308 duration_in_ms=1712.302
2019-05-18 02:14:32,532 [salt.state       :1780][INFO    ][7741] Running state [maas_machines_storage_cmp002_lvm] at time 02:14:32.532486
2019-05-18 02:14:32,533 [salt.state       :1813][INFO    ][7741] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-18 02:14:33,684 [salt.loaded.ext.module.maasng:610 ][INFO    ][7741] 4ananf
2019-05-18 02:14:33,685 [salt.loaded.ext.module.maasng:626 ][INFO    ][7741] sda
2019-05-18 02:14:34,252 [salt.loaded.ext.module.maasng:361 ][INFO    ][7741] 4ananf
2019-05-18 02:14:34,350 [salt.loaded.ext.module.maasng:367 ][INFO    ][7741] [{u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'4ananf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'2d471d45-c347-4115-92ec-29adf5fd8ded', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4ananf', u'device_id': 1, u'filesystem': {u'uuid': u'f780defd-b2e3-47ad-b086-568f80ecfec5', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/4ananf/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}], u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/4ananf/blockdevices/1/'}, {u'size': 800097042432, u'block_size': 4096, u'uuid': u'fa7dabff-5dd7-4712-a7cc-4654b692e3e8', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'id': 3, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'4ananf', u'partition_table_type': None, u'filesystem': {u'uuid': u'f3c48818-2568-4fa5-9d1e-418670814f94', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/4ananf/blockdevices/3/'}]
2019-05-18 02:14:34,351 [salt.loaded.ext.module.maasng:632 ][INFO    ][7741] vgroot
2019-05-18 02:14:34,351 [salt.loaded.ext.module.maasng:635 ][INFO    ][7741] lvroot
2019-05-18 02:14:34,352 [salt.loaded.ext.module.maasng:639 ][INFO    ][7741] 107374182400
2019-05-18 02:14:34,968 [salt.loaded.ext.module.maasng:645 ][INFO    ][7741] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': 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.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fernra', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fernra', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fernra', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'4ananf', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4ananf/interfaces/4/'}, u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'4ananf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'd010c91a-23f6-412d-81fe-b12be8e3960a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4ananf', u'device_id': 1, u'filesystem': {u'uuid': u'3e5381e8-0db1-46ea-b072-35f153dfc28f', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/4ananf/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/4ananf/blockdevices/1/'}, {u'size': 107374182400, u'block_size': 4096, u'uuid': u'2c004ae7-c5ad-4c8c-9b27-63c1c9166934', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 11, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'4ananf', u'partition_table_type': None, u'filesystem': {u'uuid': u'31ba8dfb-b9f1-4d80-b6b2-8238353f095c', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/4ananf/blockdevices/11/'}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/4ananf/', u'current_commissioning_result_id': 2, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'4ananf', 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'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'4ananf', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'4ananf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'd010c91a-23f6-412d-81fe-b12be8e3960a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4ananf', u'device_id': 1, u'filesystem': {u'uuid': u'3e5381e8-0db1-46ea-b072-35f153dfc28f', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/4ananf/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/4ananf/blockdevices/1/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'2c004ae7-c5ad-4c8c-9b27-63c1c9166934', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'4ananf', u'partition_table_type': None, u'filesystem': {u'uuid': u'31ba8dfb-b9f1-4d80-b6b2-8238353f095c', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/4ananf/blockdevices/11/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fernra', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fernra', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fernra', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'4ananf', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4ananf/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 15, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'4ananf', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4ananf/interfaces/15/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 12, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'4ananf', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4ananf/interfaces/12/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fernra', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fernra', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 14, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fernra', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'4ananf', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4ananf/interfaces/14/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 11, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'4ananf', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4ananf/interfaces/11/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 13, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'4ananf', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4ananf/interfaces/13/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'4ananf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'd010c91a-23f6-412d-81fe-b12be8e3960a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4ananf', u'device_id': 1, u'filesystem': {u'uuid': u'3e5381e8-0db1-46ea-b072-35f153dfc28f', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/4ananf/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/4ananf/blockdevices/1/'}}
2019-05-18 02:14:34,972 [salt.state       :300 ][INFO    ][7741] {'new': {'storage_layout': 'lvm'}}
2019-05-18 02:14:34,972 [salt.state       :1951][INFO    ][7741] Completed state [maas_machines_storage_cmp002_lvm] at time 02:14:34.972707 duration_in_ms=2440.22
2019-05-18 02:14:34,973 [salt.state       :1780][INFO    ][7741] Running state [maas_machines_storage_cmp001_lvm] at time 02:14:34.973355
2019-05-18 02:14:34,974 [salt.state       :1813][INFO    ][7741] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-18 02:14:36,277 [salt.loaded.ext.module.maasng:610 ][INFO    ][7741] q83qwf
2019-05-18 02:14:36,277 [salt.loaded.ext.module.maasng:626 ][INFO    ][7741] sda
2019-05-18 02:14:36,845 [salt.loaded.ext.module.maasng:361 ][INFO    ][7741] q83qwf
2019-05-18 02:14:36,940 [salt.loaded.ext.module.maasng:367 ][INFO    ][7741] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'filesystem': None, u'name': u'sda', u'system_id': u'q83qwf', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/q83qwf/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'bfd41870-b738-4271-b2fc-443b6520faa3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'q83qwf', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'f59f2415-0eed-4170-b854-bb5844729740', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/q83qwf/blockdevices/2/partition/2'}]}, {u'size': 800097042432, u'model': None, u'block_size': 4096, u'uuid': u'11aef4a1-3b2d-4ca8-bbd9-dac8120a6061', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'927ef7a8-a483-43f9-98bd-181f82db5713', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'q83qwf', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/q83qwf/blockdevices/4/', u'used_size': 800097042432, u'id': 4, u'partitions': []}]
2019-05-18 02:14:36,941 [salt.loaded.ext.module.maasng:632 ][INFO    ][7741] vgroot
2019-05-18 02:14:36,941 [salt.loaded.ext.module.maasng:635 ][INFO    ][7741] lvroot
2019-05-18 02:14:36,942 [salt.loaded.ext.module.maasng:639 ][INFO    ][7741] 107374182400
2019-05-18 02:14:37,540 [salt.loaded.ext.module.maasng:645 ][INFO    ][7741] {u'hwe_kernel': u'', 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'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {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'fernra', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 24, 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'fernra', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'fernra', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'q83qwf', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/q83qwf/interfaces/5/'}, u'fqdn': u'cmp001.maas', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/q83qwf/blockdevices/2/', u'system_id': u'q83qwf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'fd7e4417-c956-4c90-a36c-5b23c871923a', u'resource_uri': u'/MAAS/api/2.0/nodes/q83qwf/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'q83qwf', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'e080a332-1a7d-4af1-996a-709ff5998501'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'block_size': 4096, u'available_size': 0, u'uuid': u'2b27b645-d6c6-4cc0-8c04-4318ebd2914c', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/q83qwf/blockdevices/12/', u'system_id': u'q83qwf', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'549df443-0931-4ee7-8379-11c8c465d656'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'partitions': [], u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'q83qwf', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'min_hwe_kernel': u'ga-16.04', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/q83qwf/blockdevices/2/', u'system_id': u'q83qwf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'fd7e4417-c956-4c90-a36c-5b23c871923a', u'resource_uri': u'/MAAS/api/2.0/nodes/q83qwf/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'q83qwf', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'e080a332-1a7d-4af1-996a-709ff5998501'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'q83qwf', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'interface_set': [{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'fernra', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 24, 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'fernra', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'fernra', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'q83qwf', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/q83qwf/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'q83qwf', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/q83qwf/interfaces/18/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'q83qwf', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/q83qwf/interfaces/20/'}, {u'name': u'ens2f1', u'links': [{u'id': 25, 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'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'q83qwf', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/q83qwf/interfaces/16/'}, {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'fernra', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 26, 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'fernra', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'fernra', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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.41'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'q83qwf', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/q83qwf/interfaces/17/'}, {u'name': u'ens2f0', u'links': [{u'id': 27, 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'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'q83qwf', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/q83qwf/interfaces/19/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', 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'available_size': 0, u'uuid': u'2b27b645-d6c6-4cc0-8c04-4318ebd2914c', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/q83qwf/blockdevices/12/', u'system_id': u'q83qwf', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'549df443-0931-4ee7-8379-11c8c465d656'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'partitions': [], u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/q83qwf/', u'distro_series': u'', u'boot_disk': {u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/q83qwf/blockdevices/2/', u'system_id': u'q83qwf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'fd7e4417-c956-4c90-a36c-5b23c871923a', u'resource_uri': u'/MAAS/api/2.0/nodes/q83qwf/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'q83qwf', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'e080a332-1a7d-4af1-996a-709ff5998501'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}}
2019-05-18 02:14:37,542 [salt.state       :300 ][INFO    ][7741] {'new': {'storage_layout': 'lvm'}}
2019-05-18 02:14:37,542 [salt.state       :1951][INFO    ][7741] Completed state [maas_machines_storage_cmp001_lvm] at time 02:14:37.542455 duration_in_ms=2569.099
2019-05-18 02:14:37,547 [salt.minion      :1711][INFO    ][7741] Returning information for job: 20190518021424580413
2019-05-18 02:14:38,119 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command state.apply with jid 20190518021438105134
2019-05-18 02:14:38,146 [salt.minion      :1432][INFO    ][7789] Starting a new job with PID 7789
2019-05-18 02:14:39,091 [salt.state       :915 ][INFO    ][7789] Loading fresh modules for state activity
2019-05-18 02:14:39,151 [salt.fileclient  :1219][INFO    ][7789] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-18 02:14:39,208 [salt.state       :1780][INFO    ][7789] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:39.208293
2019-05-18 02:14:39,208 [salt.state       :1813][INFO    ][7789] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-18 02:14:39,210 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7789] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-18 02:14:40,928 [salt.state       :300 ][INFO    ][7789] {'pid': 7796, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-18 02:14:40,929 [salt.state       :1951][INFO    ][7789] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:40.929184 duration_in_ms=1720.891
2019-05-18 02:14:40,932 [salt.state       :1780][INFO    ][7789] Running state [maas.deploy_machines] at time 02:14:40.932823
2019-05-18 02:14:40,933 [salt.state       :1813][INFO    ][7789] Executing state module.run for [maas.deploy_machines]
2019-05-18 02:14:40,936 [salt.utils.decorators:613 ][WARNING ][7789] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 02:14:41,506 [salt.loaded.ext.module.maas:684 ][INFO    ][7789] deploymachines hwe_kernel=ga-16.04 system_id=4ananf distro_series=xenial
2019-05-18 02:14:44,084 [salt.loaded.ext.module.maas:684 ][INFO    ][7789] deploymachines hwe_kernel=ga-16.04 system_id=q83qwf distro_series=xenial
2019-05-18 02:14:46,579 [salt.loaded.ext.module.maas:684 ][INFO    ][7789] deploymachines hwe_kernel=ga-16.04 system_id=mx3sfb distro_series=xenial
2019-05-18 02:14:49,129 [salt.loaded.ext.module.maas:684 ][INFO    ][7789] deploymachines hwe_kernel=ga-16.04 system_id=kqq6cn distro_series=xenial
2019-05-18 02:14:51,661 [salt.loaded.ext.module.maas:684 ][INFO    ][7789] deploymachines hwe_kernel=ga-16.04 system_id=6em64b distro_series=xenial
2019-05-18 02:14:53,177 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518021453163232
2019-05-18 02:14:53,202 [salt.minion      :1432][INFO    ][8066] Starting a new job with PID 8066
2019-05-18 02:14:53,223 [salt.minion      :1711][INFO    ][8066] Returning information for job: 20190518021453163232
2019-05-18 02:14:54,209 [salt.state       :300 ][INFO    ][7789] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-18 02:14:54,212 [salt.state       :1951][INFO    ][7789] Completed state [maas.deploy_machines] at time 02:14:54.212143 duration_in_ms=13279.32
2019-05-18 02:14:54,215 [salt.minion      :1711][INFO    ][7789] Returning information for job: 20190518021438105134
2019-05-18 02:14:54,791 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command state.apply with jid 20190518021454777466
2019-05-18 02:14:54,816 [salt.minion      :1432][INFO    ][8082] Starting a new job with PID 8082
2019-05-18 02:15:00,921 [salt.state       :915 ][INFO    ][8082] Loading fresh modules for state activity
2019-05-18 02:15:00,984 [salt.fileclient  :1219][INFO    ][8082] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-18 02:15:01,034 [salt.state       :1780][INFO    ][8082] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:01.034258
2019-05-18 02:15:01,034 [salt.state       :1813][INFO    ][8082] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-18 02:15:01,036 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8082] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-18 02:15:02,772 [salt.state       :300 ][INFO    ][8082] {'pid': 8128, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-18 02:15:02,773 [salt.state       :1951][INFO    ][8082] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:02.773393 duration_in_ms=1739.134
2019-05-18 02:15:02,777 [salt.state       :1780][INFO    ][8082] Running state [maas.wait_for_machine_status] at time 02:15:02.777654
2019-05-18 02:15:02,778 [salt.state       :1813][INFO    ][8082] Executing state module.run for [maas.wait_for_machine_status]
2019-05-18 02:15:02,779 [salt.utils.decorators:613 ][WARNING ][8082] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 02:15:05,632 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.16156292s left)
2019-05-18 02:15:09,904 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518021509881452
2019-05-18 02:15:09,929 [salt.minion      :1432][INFO    ][8144] Starting a new job with PID 8144
2019-05-18 02:15:09,951 [salt.minion      :1711][INFO    ][8144] Returning information for job: 20190518021509881452
2019-05-18 02:15:38,739 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.05137086s left)
2019-05-18 02:15:39,968 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518021539955433
2019-05-18 02:15:39,996 [salt.minion      :1432][INFO    ][8168] Starting a new job with PID 8168
2019-05-18 02:15:40,016 [salt.minion      :1711][INFO    ][8168] Returning information for job: 20190518021539955433
2019-05-18 02:16:10,057 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518021610046950
2019-05-18 02:16:10,077 [salt.minion      :1432][INFO    ][8219] Starting a new job with PID 8219
2019-05-18 02:16:10,100 [salt.minion      :1711][INFO    ][8219] Returning information for job: 20190518021610046950
2019-05-18 02:16:11,740 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.0512929s left)
2019-05-18 02:16:40,114 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518021640106621
2019-05-18 02:16:40,136 [salt.minion      :1432][INFO    ][8246] Starting a new job with PID 8246
2019-05-18 02:16:40,153 [salt.minion      :1711][INFO    ][8246] Returning information for job: 20190518021640106621
2019-05-18 02:16:44,728 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.06311297s left)
2019-05-18 02:17:10,194 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518021710182896
2019-05-18 02:17:10,221 [salt.minion      :1432][INFO    ][8323] Starting a new job with PID 8323
2019-05-18 02:17:10,243 [salt.minion      :1711][INFO    ][8323] Returning information for job: 20190518021710182896
2019-05-18 02:17:17,559 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.23182797s left)
2019-05-18 02:17:40,296 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518021740276530
2019-05-18 02:17:40,325 [salt.minion      :1432][INFO    ][8367] Starting a new job with PID 8367
2019-05-18 02:17:40,347 [salt.minion      :1711][INFO    ][8367] Returning information for job: 20190518021740276530
2019-05-18 02:17:50,614 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.17719197s left)
2019-05-18 02:18:10,400 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518021810388603
2019-05-18 02:18:10,426 [salt.minion      :1432][INFO    ][8554] Starting a new job with PID 8554
2019-05-18 02:18:10,448 [salt.minion      :1711][INFO    ][8554] Returning information for job: 20190518021810388603
2019-05-18 02:18:23,579 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.21136284s left)
2019-05-18 02:18:40,523 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518021840505014
2019-05-18 02:18:40,554 [salt.minion      :1432][INFO    ][8640] Starting a new job with PID 8640
2019-05-18 02:18:40,575 [salt.minion      :1711][INFO    ][8640] Returning information for job: 20190518021840505014
2019-05-18 02:18:57,536 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.25456882s left)
2019-05-18 02:19:10,637 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518021910621439
2019-05-18 02:19:10,663 [salt.minion      :1432][INFO    ][8917] Starting a new job with PID 8917
2019-05-18 02:19:10,685 [salt.minion      :1711][INFO    ][8917] Returning information for job: 20190518021910621439
2019-05-18 02:19:30,918 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1981.87249994s left)
2019-05-18 02:19:40,750 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518021940735554
2019-05-18 02:19:40,776 [salt.minion      :1432][INFO    ][9019] Starting a new job with PID 9019
2019-05-18 02:19:40,803 [salt.minion      :1711][INFO    ][9019] Returning information for job: 20190518021940735554
2019-05-18 02:20:03,916 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1948.8744998s left)
2019-05-18 02:20:10,888 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518022010875186
2019-05-18 02:20:10,912 [salt.minion      :1432][INFO    ][9220] Starting a new job with PID 9220
2019-05-18 02:20:10,931 [salt.minion      :1711][INFO    ][9220] Returning information for job: 20190518022010875186
2019-05-18 02:20:37,159 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1915.63149691s left)
2019-05-18 02:20:41,028 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518022041016256
2019-05-18 02:20:41,051 [salt.minion      :1432][INFO    ][9364] Starting a new job with PID 9364
2019-05-18 02:20:41,071 [salt.minion      :1711][INFO    ][9364] Returning information for job: 20190518022041016256
2019-05-18 02:21:10,349 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1882.44185901s left)
2019-05-18 02:21:11,167 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518022111151413
2019-05-18 02:21:11,192 [salt.minion      :1432][INFO    ][9652] Starting a new job with PID 9652
2019-05-18 02:21:11,220 [salt.minion      :1711][INFO    ][9652] Returning information for job: 20190518022111151413
2019-05-18 02:21:41,311 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518022141298881
2019-05-18 02:21:41,335 [salt.minion      :1432][INFO    ][9748] Starting a new job with PID 9748
2019-05-18 02:21:41,359 [salt.minion      :1711][INFO    ][9748] Returning information for job: 20190518022141298881
2019-05-18 02:21:43,510 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1849.28099799s left)
2019-05-18 02:22:11,491 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518022211475002
2019-05-18 02:22:11,515 [salt.minion      :1432][INFO    ][10022] Starting a new job with PID 10022
2019-05-18 02:22:11,537 [salt.minion      :1711][INFO    ][10022] Returning information for job: 20190518022211475002
2019-05-18 02:22:16,602 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1816.18863297s left)
2019-05-18 02:22:41,650 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518022241634961
2019-05-18 02:22:41,680 [salt.minion      :1432][INFO    ][10143] Starting a new job with PID 10143
2019-05-18 02:22:41,701 [salt.minion      :1711][INFO    ][10143] Returning information for job: 20190518022241634961
2019-05-18 02:22:49,480 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1783.31104898s left)
2019-05-18 02:23:11,826 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518022311815277
2019-05-18 02:23:11,845 [salt.minion      :1432][INFO    ][10397] Starting a new job with PID 10397
2019-05-18 02:23:11,872 [salt.minion      :1711][INFO    ][10397] Returning information for job: 20190518022311815277
2019-05-18 02:23:22,523 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1750.26740479s left)
2019-05-18 02:23:41,995 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518022341980683
2019-05-18 02:23:42,022 [salt.minion      :1432][INFO    ][10495] Starting a new job with PID 10495
2019-05-18 02:23:42,048 [salt.minion      :1711][INFO    ][10495] Returning information for job: 20190518022341980683
2019-05-18 02:23:55,327 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1717.46457887s left)
2019-05-18 02:24:12,189 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518022412172843
2019-05-18 02:24:12,215 [salt.minion      :1432][INFO    ][10661] Starting a new job with PID 10661
2019-05-18 02:24:12,237 [salt.minion      :1711][INFO    ][10661] Returning information for job: 20190518022412172843
2019-05-18 02:24:28,188 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1684.60271597s left)
2019-05-18 02:24:42,352 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518022442340982
2019-05-18 02:24:42,375 [salt.minion      :1432][INFO    ][10700] Starting a new job with PID 10700
2019-05-18 02:24:42,395 [salt.minion      :1711][INFO    ][10700] Returning information for job: 20190518022442340982
2019-05-18 02:25:01,319 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1651.47154093s left)
2019-05-18 02:25:12,528 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518022512515405
2019-05-18 02:25:12,556 [salt.minion      :1432][INFO    ][10784] Starting a new job with PID 10784
2019-05-18 02:25:12,590 [salt.minion      :1711][INFO    ][10784] Returning information for job: 20190518022512515405
2019-05-18 02:25:34,103 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1618.68754792s left)
2019-05-18 02:25:42,634 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518022542624280
2019-05-18 02:25:42,660 [salt.minion      :1432][INFO    ][10876] Starting a new job with PID 10876
2019-05-18 02:25:42,684 [salt.minion      :1711][INFO    ][10876] Returning information for job: 20190518022542624280
2019-05-18 02:26:07,167 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1585.62558699s left)
2019-05-18 02:26:12,848 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518022612835211
2019-05-18 02:26:12,876 [salt.minion      :1432][INFO    ][11115] Starting a new job with PID 11115
2019-05-18 02:26:12,902 [salt.minion      :1711][INFO    ][11115] Returning information for job: 20190518022612835211
2019-05-18 02:26:40,016 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1552.77511597s left)
2019-05-18 02:26:43,031 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518022643017590
2019-05-18 02:26:43,057 [salt.minion      :1432][INFO    ][11300] Starting a new job with PID 11300
2019-05-18 02:26:43,085 [salt.minion      :1711][INFO    ][11300] Returning information for job: 20190518022643017590
2019-05-18 02:27:13,005 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1519.78565001s left)
2019-05-18 02:27:13,231 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518022713215098
2019-05-18 02:27:13,256 [salt.minion      :1432][INFO    ][11358] Starting a new job with PID 11358
2019-05-18 02:27:13,284 [salt.minion      :1711][INFO    ][11358] Returning information for job: 20190518022713215098
2019-05-18 02:27:43,432 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518022743421673
2019-05-18 02:27:43,453 [salt.minion      :1432][INFO    ][11432] Starting a new job with PID 11432
2019-05-18 02:27:43,482 [salt.minion      :1711][INFO    ][11432] Returning information for job: 20190518022743421673
2019-05-18 02:27:45,872 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1486.91844988s left)
2019-05-18 02:28:13,546 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518022813535758
2019-05-18 02:28:13,572 [salt.minion      :1432][INFO    ][11605] Starting a new job with PID 11605
2019-05-18 02:28:13,599 [salt.minion      :1711][INFO    ][11605] Returning information for job: 20190518022813535758
2019-05-18 02:28:18,763 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1454.027565s left)
2019-05-18 02:28:43,762 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518022843753671
2019-05-18 02:28:43,782 [salt.minion      :1432][INFO    ][11698] Starting a new job with PID 11698
2019-05-18 02:28:43,801 [salt.minion      :1711][INFO    ][11698] Returning information for job: 20190518022843753671
2019-05-18 02:28:51,852 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1420.93909478s left)
2019-05-18 02:29:13,801 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518022913787002
2019-05-18 02:29:13,831 [salt.minion      :1432][INFO    ][11855] Starting a new job with PID 11855
2019-05-18 02:29:13,861 [salt.minion      :1711][INFO    ][11855] Returning information for job: 20190518022913787002
2019-05-18 02:29:24,915 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1387.87557697s left)
2019-05-18 02:29:43,867 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518022943853847
2019-05-18 02:29:43,894 [salt.minion      :1432][INFO    ][11888] Starting a new job with PID 11888
2019-05-18 02:29:43,924 [salt.minion      :1711][INFO    ][11888] Returning information for job: 20190518022943853847
2019-05-18 02:29:57,894 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1354.89682388s left)
2019-05-18 02:30:13,917 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518023013905486
2019-05-18 02:30:13,942 [salt.minion      :1432][INFO    ][11930] Starting a new job with PID 11930
2019-05-18 02:30:13,980 [salt.minion      :1711][INFO    ][11930] Returning information for job: 20190518023013905486
2019-05-18 02:30:30,842 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1321.94964695s left)
2019-05-18 02:30:44,012 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518023044000087
2019-05-18 02:30:44,039 [salt.minion      :1432][INFO    ][11965] Starting a new job with PID 11965
2019-05-18 02:30:44,067 [salt.minion      :1711][INFO    ][11965] Returning information for job: 20190518023044000087
2019-05-18 02:31:03,728 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1289.06329989s left)
2019-05-18 02:31:14,084 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518023114071602
2019-05-18 02:31:14,110 [salt.minion      :1432][INFO    ][12015] Starting a new job with PID 12015
2019-05-18 02:31:14,142 [salt.minion      :1711][INFO    ][12015] Returning information for job: 20190518023114071602
2019-05-18 02:31:36,561 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1256.23026299s left)
2019-05-18 02:31:44,200 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518023144183561
2019-05-18 02:31:44,226 [salt.minion      :1432][INFO    ][12052] Starting a new job with PID 12052
2019-05-18 02:31:44,257 [salt.minion      :1711][INFO    ][12052] Returning information for job: 20190518023144183561
2019-05-18 02:32:09,274 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1223.51696897s left)
2019-05-18 02:32:14,285 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518023214269101
2019-05-18 02:32:14,311 [salt.minion      :1432][INFO    ][12096] Starting a new job with PID 12096
2019-05-18 02:32:14,349 [salt.minion      :1711][INFO    ][12096] Returning information for job: 20190518023214269101
2019-05-18 02:32:42,224 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1190.56679082s left)
2019-05-18 02:32:44,422 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518023244406216
2019-05-18 02:32:44,452 [salt.minion      :1432][INFO    ][12129] Starting a new job with PID 12129
2019-05-18 02:32:44,483 [salt.minion      :1711][INFO    ][12129] Returning information for job: 20190518023244406216
2019-05-18 02:33:14,527 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518023314516584
2019-05-18 02:33:14,552 [salt.minion      :1432][INFO    ][12180] Starting a new job with PID 12180
2019-05-18 02:33:14,587 [salt.minion      :1711][INFO    ][12180] Returning information for job: 20190518023314516584
2019-05-18 02:33:15,212 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1157.57891488s left)
2019-05-18 02:33:44,679 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518023344669688
2019-05-18 02:33:44,698 [salt.minion      :1432][INFO    ][12210] Starting a new job with PID 12210
2019-05-18 02:33:44,730 [salt.minion      :1711][INFO    ][12210] Returning information for job: 20190518023344669688
2019-05-18 02:33:47,960 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1124.830369s left)
2019-05-18 02:34:14,809 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518023414795363
2019-05-18 02:34:14,838 [salt.minion      :1432][INFO    ][12257] Starting a new job with PID 12257
2019-05-18 02:34:14,867 [salt.minion      :1711][INFO    ][12257] Returning information for job: 20190518023414795363
2019-05-18 02:34:20,814 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1091.97676301s left)
2019-05-18 02:34:44,994 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518023444977149
2019-05-18 02:34:45,022 [salt.minion      :1432][INFO    ][12292] Starting a new job with PID 12292
2019-05-18 02:34:45,057 [salt.minion      :1711][INFO    ][12292] Returning information for job: 20190518023444977149
2019-05-18 02:34:53,530 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1059.26065683s left)
2019-05-18 02:35:15,185 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518023515172010
2019-05-18 02:35:15,204 [salt.minion      :1432][INFO    ][12337] Starting a new job with PID 12337
2019-05-18 02:35:15,236 [salt.minion      :1711][INFO    ][12337] Returning information for job: 20190518023515172010
2019-05-18 02:35:26,358 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1026.43263793s left)
2019-05-18 02:35:45,357 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518023545345535
2019-05-18 02:35:45,386 [salt.minion      :1432][INFO    ][12370] Starting a new job with PID 12370
2019-05-18 02:35:45,419 [salt.minion      :1711][INFO    ][12370] Returning information for job: 20190518023545345535
2019-05-18 02:35:59,185 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (993.605908871s left)
2019-05-18 02:36:15,583 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518023615570566
2019-05-18 02:36:15,604 [salt.minion      :1432][INFO    ][12419] Starting a new job with PID 12419
2019-05-18 02:36:15,638 [salt.minion      :1711][INFO    ][12419] Returning information for job: 20190518023615570566
2019-05-18 02:36:31,960 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (960.831280947s left)
2019-05-18 02:36:45,809 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518023645791729
2019-05-18 02:36:45,833 [salt.minion      :1432][INFO    ][12455] Starting a new job with PID 12455
2019-05-18 02:36:45,864 [salt.minion      :1711][INFO    ][12455] Returning information for job: 20190518023645791729
2019-05-18 02:37:04,814 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (927.976453781s left)
2019-05-18 02:37:15,883 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518023715870159
2019-05-18 02:37:15,910 [salt.minion      :1432][INFO    ][12517] Starting a new job with PID 12517
2019-05-18 02:37:15,943 [salt.minion      :1711][INFO    ][12517] Returning information for job: 20190518023715870159
2019-05-18 02:37:37,536 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (895.25484395s left)
2019-05-18 02:37:45,932 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518023745922990
2019-05-18 02:37:45,958 [salt.minion      :1432][INFO    ][12550] Starting a new job with PID 12550
2019-05-18 02:37:45,989 [salt.minion      :1711][INFO    ][12550] Returning information for job: 20190518023745922990
2019-05-18 02:38:10,443 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (862.348498821s left)
2019-05-18 02:38:16,025 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518023816010558
2019-05-18 02:38:16,052 [salt.minion      :1432][INFO    ][12594] Starting a new job with PID 12594
2019-05-18 02:38:16,083 [salt.minion      :1711][INFO    ][12594] Returning information for job: 20190518023816010558
2019-05-18 02:38:43,223 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (829.568034887s left)
2019-05-18 02:38:46,096 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518023846086219
2019-05-18 02:38:46,116 [salt.minion      :1432][INFO    ][12634] Starting a new job with PID 12634
2019-05-18 02:38:46,152 [salt.minion      :1711][INFO    ][12634] Returning information for job: 20190518023846086219
2019-05-18 02:39:15,964 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (796.827286959s left)
2019-05-18 02:39:16,215 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518023916202794
2019-05-18 02:39:16,238 [salt.minion      :1432][INFO    ][12678] Starting a new job with PID 12678
2019-05-18 02:39:16,271 [salt.minion      :1711][INFO    ][12678] Returning information for job: 20190518023916202794
2019-05-18 02:39:46,315 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518023946300679
2019-05-18 02:39:46,338 [salt.minion      :1432][INFO    ][12706] Starting a new job with PID 12706
2019-05-18 02:39:46,368 [salt.minion      :1711][INFO    ][12706] Returning information for job: 20190518023946300679
2019-05-18 02:39:48,687 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (764.103313923s left)
2019-05-18 02:40:16,484 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518024016469170
2019-05-18 02:40:16,512 [salt.minion      :1432][INFO    ][12752] Starting a new job with PID 12752
2019-05-18 02:40:16,543 [salt.minion      :1711][INFO    ][12752] Returning information for job: 20190518024016469170
2019-05-18 02:40:21,551 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (731.239622831s left)
2019-05-18 02:40:46,633 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518024046624759
2019-05-18 02:40:46,655 [salt.minion      :1432][INFO    ][12784] Starting a new job with PID 12784
2019-05-18 02:40:46,684 [salt.minion      :1711][INFO    ][12784] Returning information for job: 20190518024046624759
2019-05-18 02:40:54,268 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (698.522794008s left)
2019-05-18 02:41:16,800 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518024116791609
2019-05-18 02:41:16,826 [salt.minion      :1432][INFO    ][12827] Starting a new job with PID 12827
2019-05-18 02:41:16,859 [salt.minion      :1711][INFO    ][12827] Returning information for job: 20190518024116791609
2019-05-18 02:41:27,017 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (665.774200916s left)
2019-05-18 02:41:46,831 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518024146816733
2019-05-18 02:41:46,859 [salt.minion      :1432][INFO    ][12864] Starting a new job with PID 12864
2019-05-18 02:41:46,893 [salt.minion      :1711][INFO    ][12864] Returning information for job: 20190518024146816733
2019-05-18 02:42:00,034 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (632.756413937s left)
2019-05-18 02:42:17,023 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518024217010891
2019-05-18 02:42:17,053 [salt.minion      :1432][INFO    ][12908] Starting a new job with PID 12908
2019-05-18 02:42:17,080 [salt.minion      :1711][INFO    ][12908] Returning information for job: 20190518024217010891
2019-05-18 02:42:32,801 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (599.989923954s left)
2019-05-18 02:42:47,084 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518024247072185
2019-05-18 02:42:47,109 [salt.minion      :1432][INFO    ][12940] Starting a new job with PID 12940
2019-05-18 02:42:47,141 [salt.minion      :1711][INFO    ][12940] Returning information for job: 20190518024247072185
2019-05-18 02:43:05,529 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (567.261781931s left)
2019-05-18 02:43:17,107 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518024317088593
2019-05-18 02:43:17,136 [salt.minion      :1432][INFO    ][12986] Starting a new job with PID 12986
2019-05-18 02:43:17,167 [salt.minion      :1711][INFO    ][12986] Returning information for job: 20190518024317088593
2019-05-18 02:43:38,266 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (534.524643898s left)
2019-05-18 02:43:47,213 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518024347195982
2019-05-18 02:43:47,241 [salt.minion      :1432][INFO    ][13020] Starting a new job with PID 13020
2019-05-18 02:43:47,273 [salt.minion      :1711][INFO    ][13020] Returning information for job: 20190518024347195982
2019-05-18 02:44:11,142 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (501.6489079s left)
2019-05-18 02:44:17,267 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518024417251007
2019-05-18 02:44:17,297 [salt.minion      :1432][INFO    ][13062] Starting a new job with PID 13062
2019-05-18 02:44:17,339 [salt.minion      :1711][INFO    ][13062] Returning information for job: 20190518024417251007
2019-05-18 02:44:44,085 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (468.7054739s left)
2019-05-18 02:44:47,432 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518024447416017
2019-05-18 02:44:47,459 [salt.minion      :1432][INFO    ][13097] Starting a new job with PID 13097
2019-05-18 02:44:47,491 [salt.minion      :1711][INFO    ][13097] Returning information for job: 20190518024447416017
2019-05-18 02:45:16,808 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (435.98248601s left)
2019-05-18 02:45:17,545 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518024517533535
2019-05-18 02:45:17,577 [salt.minion      :1432][INFO    ][13139] Starting a new job with PID 13139
2019-05-18 02:45:17,615 [salt.minion      :1711][INFO    ][13139] Returning information for job: 20190518024517533535
2019-05-18 02:45:47,724 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518024547713044
2019-05-18 02:45:47,744 [salt.minion      :1432][INFO    ][13168] Starting a new job with PID 13168
2019-05-18 02:45:47,773 [salt.minion      :1711][INFO    ][13168] Returning information for job: 20190518024547713044
2019-05-18 02:45:49,621 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (403.170121908s left)
2019-05-18 02:46:17,872 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518024617859305
2019-05-18 02:46:17,897 [salt.minion      :1432][INFO    ][13216] Starting a new job with PID 13216
2019-05-18 02:46:17,934 [salt.minion      :1711][INFO    ][13216] Returning information for job: 20190518024617859305
2019-05-18 02:46:22,585 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (370.205913782s left)
2019-05-18 02:46:48,088 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518024648073552
2019-05-18 02:46:48,117 [salt.minion      :1432][INFO    ][13383] Starting a new job with PID 13383
2019-05-18 02:46:48,145 [salt.minion      :1711][INFO    ][13383] Returning information for job: 20190518024648073552
2019-05-18 02:46:55,287 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (337.505549908s left)
2019-05-18 02:47:18,292 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518024718275315
2019-05-18 02:47:18,309 [salt.minion      :1432][INFO    ][13441] Starting a new job with PID 13441
2019-05-18 02:47:18,337 [salt.minion      :1711][INFO    ][13441] Returning information for job: 20190518024718275315
2019-05-18 02:47:28,159 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (304.631704807s left)
2019-05-18 02:47:48,490 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518024748474729
2019-05-18 02:47:48,517 [salt.minion      :1432][INFO    ][13475] Starting a new job with PID 13475
2019-05-18 02:47:48,548 [salt.minion      :1711][INFO    ][13475] Returning information for job: 20190518024748474729
2019-05-18 02:48:00,919 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (271.871747017s left)
2019-05-18 02:48:18,580 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518024818564141
2019-05-18 02:48:18,605 [salt.minion      :1432][INFO    ][13518] Starting a new job with PID 13518
2019-05-18 02:48:18,634 [salt.minion      :1711][INFO    ][13518] Returning information for job: 20190518024818564141
2019-05-18 02:48:33,775 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (239.015619993s left)
2019-05-18 02:48:48,810 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518024848795032
2019-05-18 02:48:48,837 [salt.minion      :1432][INFO    ][13562] Starting a new job with PID 13562
2019-05-18 02:48:48,875 [salt.minion      :1711][INFO    ][13562] Returning information for job: 20190518024848795032
2019-05-18 02:49:06,521 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (206.269883871s left)
2019-05-18 02:49:18,909 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518024918894630
2019-05-18 02:49:18,936 [salt.minion      :1432][INFO    ][13604] Starting a new job with PID 13604
2019-05-18 02:49:18,969 [salt.minion      :1711][INFO    ][13604] Returning information for job: 20190518024918894630
2019-05-18 02:49:39,267 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (173.523500919s left)
2019-05-18 02:49:49,067 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518024949051926
2019-05-18 02:49:49,098 [salt.minion      :1432][INFO    ][13637] Starting a new job with PID 13637
2019-05-18 02:49:49,127 [salt.minion      :1711][INFO    ][13637] Returning information for job: 20190518024949051926
2019-05-18 02:50:12,244 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (140.546397924s left)
2019-05-18 02:50:19,224 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518025019209506
2019-05-18 02:50:19,254 [salt.minion      :1432][INFO    ][13677] Starting a new job with PID 13677
2019-05-18 02:50:19,289 [salt.minion      :1711][INFO    ][13677] Returning information for job: 20190518025019209506
2019-05-18 02:50:45,008 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (107.782884836s left)
2019-05-18 02:50:49,372 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518025049359165
2019-05-18 02:50:49,399 [salt.minion      :1432][INFO    ][13711] Starting a new job with PID 13711
2019-05-18 02:50:49,434 [salt.minion      :1711][INFO    ][13711] Returning information for job: 20190518025049359165
2019-05-18 02:51:17,751 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (75.039618969s left)
2019-05-18 02:51:19,555 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518025119543145
2019-05-18 02:51:19,577 [salt.minion      :1432][INFO    ][13755] Starting a new job with PID 13755
2019-05-18 02:51:19,607 [salt.minion      :1711][INFO    ][13755] Returning information for job: 20190518025119543145
2019-05-18 02:51:49,699 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518025149687116
2019-05-18 02:51:49,721 [salt.minion      :1432][INFO    ][13794] Starting a new job with PID 13794
2019-05-18 02:51:49,750 [salt.minion      :1711][INFO    ][13794] Returning information for job: 20190518025149687116
2019-05-18 02:51:50,769 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (42.0219957829s left)
2019-05-18 02:52:19,915 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518025219899780
2019-05-18 02:52:19,935 [salt.minion      :1432][INFO    ][13835] Starting a new job with PID 13835
2019-05-18 02:52:19,964 [salt.minion      :1711][INFO    ][13835] Returning information for job: 20190518025219899780
2019-05-18 02:52:23,469 [salt.loaded.ext.module.maas:1023][INFO    ][8082] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (9.32173991203s left)
2019-05-18 02:52:50,117 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518025250103464
2019-05-18 02:52:50,142 [salt.minion      :1432][INFO    ][13871] Starting a new job with PID 13871
2019-05-18 02:52:50,172 [salt.minion      :1711][INFO    ][13871] Returning information for job: 20190518025250103464
2019-05-18 02:52:56,493 [salt.state       :302 ][ERROR   ][8082] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-05-18 02:52:56,494 [salt.state       :1951][INFO    ][8082] Completed state [maas.wait_for_machine_status] at time 02:52:56.494786 duration_in_ms=2273717.112
2019-05-18 02:52:56,502 [salt.minion      :1711][INFO    ][8082] Returning information for job: 20190518021454777466
2019-05-18 02:53:07,493 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command pillar.get with jid 20190518025307479464
2019-05-18 02:53:07,520 [salt.minion      :1432][INFO    ][13912] Starting a new job with PID 13912
2019-05-18 02:53:07,527 [salt.minion      :1711][INFO    ][13912] Returning information for job: 20190518025307479464
2019-05-18 02:53:08,261 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command service.status with jid 20190518025308244982
2019-05-18 02:53:08,287 [salt.minion      :1432][INFO    ][13918] Starting a new job with PID 13918
2019-05-18 02:53:09,029 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13918] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-18 02:53:09,072 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13918] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-18 02:53:09,093 [salt.minion      :1711][INFO    ][13918] Returning information for job: 20190518025308244982
2019-05-18 02:53:09,790 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command state.apply with jid 20190518025309774128
2019-05-18 02:53:09,822 [salt.minion      :1432][INFO    ][13929] Starting a new job with PID 13929
2019-05-18 02:53:15,857 [salt.state       :915 ][INFO    ][13929] Loading fresh modules for state activity
2019-05-18 02:53:16,517 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command 'salt-minion --version' in directory '/root'
2019-05-18 02:53:16,871 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command 'salt-minion --version' in directory '/root'
2019-05-18 02:53:17,943 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command 'salt-minion --version' in directory '/root'
2019-05-18 02:53:18,309 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command 'salt-minion --version' in directory '/root'
2019-05-18 02:53:20,552 [salt.state       :1780][INFO    ][13929] Running state [salt-minion] at time 02:53:20.552432
2019-05-18 02:53:20,552 [salt.state       :1813][INFO    ][13929] Executing state pkg.installed for [salt-minion]
2019-05-18 02:53:20,553 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-18 02:53:20,682 [salt.state       :300 ][INFO    ][13929] All specified packages are already installed
2019-05-18 02:53:20,683 [salt.state       :1951][INFO    ][13929] Completed state [salt-minion] at time 02:53:20.682924 duration_in_ms=130.493
2019-05-18 02:53:20,683 [salt.state       :1780][INFO    ][13929] Running state [salt_minion_dependency_packages] at time 02:53:20.683295
2019-05-18 02:53:20,683 [salt.state       :1813][INFO    ][13929] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-18 02:53:20,692 [salt.state       :300 ][INFO    ][13929] All specified packages are already installed
2019-05-18 02:53:20,693 [salt.state       :1951][INFO    ][13929] Completed state [salt_minion_dependency_packages] at time 02:53:20.692978 duration_in_ms=9.683
2019-05-18 02:53:20,696 [salt.state       :1780][INFO    ][13929] Running state [/etc/salt/minion.d/minion.conf] at time 02:53:20.696525
2019-05-18 02:53:20,696 [salt.state       :1813][INFO    ][13929] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-18 02:53:20,987 [salt.state       :300 ][INFO    ][13929] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-18 02:53:20,987 [salt.state       :1951][INFO    ][13929] Completed state [/etc/salt/minion.d/minion.conf] at time 02:53:20.987605 duration_in_ms=291.08
2019-05-18 02:53:20,987 [salt.state       :1780][INFO    ][13929] Running state [python-netaddr] at time 02:53:20.987918
2019-05-18 02:53:20,988 [salt.state       :1813][INFO    ][13929] Executing state pkg.installed for [python-netaddr]
2019-05-18 02:53:20,997 [salt.state       :300 ][INFO    ][13929] All specified packages are already installed
2019-05-18 02:53:20,997 [salt.state       :1951][INFO    ][13929] Completed state [python-netaddr] at time 02:53:20.997371 duration_in_ms=9.453
2019-05-18 02:53:21,000 [salt.state       :1780][INFO    ][13929] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:53:21.000599
2019-05-18 02:53:21,000 [salt.state       :1813][INFO    ][13929] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-18 02:53:21,018 [salt.state       :300 ][INFO    ][13929] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-18 02:53:21,019 [salt.state       :1951][INFO    ][13929] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:53:21.019198 duration_in_ms=18.599
2019-05-18 02:53:21,020 [salt.state       :1780][INFO    ][13929] Running state [salt-minion] at time 02:53:21.020800
2019-05-18 02:53:21,021 [salt.state       :1813][INFO    ][13929] Executing state service.running for [salt-minion]
2019-05-18 02:53:21,022 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-18 02:53:21,067 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-18 02:53:21,089 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-18 02:53:21,111 [salt.state       :300 ][INFO    ][13929] The service salt-minion is already running
2019-05-18 02:53:21,112 [salt.state       :1951][INFO    ][13929] Completed state [salt-minion] at time 02:53:21.112222 duration_in_ms=91.422
2019-05-18 02:53:21,115 [salt.state       :1780][INFO    ][13929] Running state [/etc/salt/grains.d] at time 02:53:21.115622
2019-05-18 02:53:21,116 [salt.state       :1813][INFO    ][13929] Executing state file.directory for [/etc/salt/grains.d]
2019-05-18 02:53:21,118 [salt.state       :300 ][INFO    ][13929] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-18 02:53:21,118 [salt.state       :1951][INFO    ][13929] Completed state [/etc/salt/grains.d] at time 02:53:21.118384 duration_in_ms=2.761
2019-05-18 02:53:21,119 [salt.state       :1780][INFO    ][13929] Running state [/etc/salt/grains] at time 02:53:21.119538
2019-05-18 02:53:21,120 [salt.state       :1813][INFO    ][13929] Executing state file.managed for [/etc/salt/grains]
2019-05-18 02:53:21,121 [salt.state       :300 ][INFO    ][13929] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-18 02:53:21,121 [salt.state       :1951][INFO    ][13929] Completed state [/etc/salt/grains] at time 02:53:21.121332 duration_in_ms=1.795
2019-05-18 02:53:21,124 [salt.state       :1780][INFO    ][13929] Running state [/etc/salt/grains.d/placeholder] at time 02:53:21.123981
2019-05-18 02:53:21,124 [salt.state       :1813][INFO    ][13929] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-18 02:53:21,124 [salt.state       :300 ][INFO    ][13929] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-18 02:53:21,125 [salt.state       :1951][INFO    ][13929] Completed state [/etc/salt/grains.d/placeholder] at time 02:53:21.125078 duration_in_ms=1.097
2019-05-18 02:53:21,125 [salt.state       :1780][INFO    ][13929] Running state [/etc/salt/grains.d/sphinx] at time 02:53:21.125604
2019-05-18 02:53:21,126 [salt.state       :1813][INFO    ][13929] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-18 02:53:21,127 [salt.state       :300 ][INFO    ][13929] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-18 02:53:21,127 [salt.state       :1951][INFO    ][13929] Completed state [/etc/salt/grains.d/sphinx] at time 02:53:21.127690 duration_in_ms=2.086
2019-05-18 02:53:21,129 [salt.state       :1780][INFO    ][13929] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:53:21.129795
2019-05-18 02:53:21,130 [salt.state       :1813][INFO    ][13929] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-18 02:53:21,130 [salt.state       :300 ][INFO    ][13929] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-18 02:53:21,130 [salt.state       :1951][INFO    ][13929] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:53:21.130657 duration_in_ms=0.863
2019-05-18 02:53:21,131 [salt.state       :1780][INFO    ][13929] Running state [/etc/salt/grains.d/dns_records] at time 02:53:21.131179
2019-05-18 02:53:21,131 [salt.state       :1813][INFO    ][13929] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-18 02:53:21,132 [salt.state       :300 ][INFO    ][13929] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-18 02:53:21,132 [salt.state       :1951][INFO    ][13929] Completed state [/etc/salt/grains.d/dns_records] at time 02:53:21.132771 duration_in_ms=1.592
2019-05-18 02:53:21,135 [salt.state       :1780][INFO    ][13929] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:53:21.135478
2019-05-18 02:53:21,135 [salt.state       :1813][INFO    ][13929] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-18 02:53:21,136 [salt.state       :300 ][INFO    ][13929] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-18 02:53:21,136 [salt.state       :1951][INFO    ][13929] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:53:21.136326 duration_in_ms=0.848
2019-05-18 02:53:21,136 [salt.state       :1780][INFO    ][13929] Running state [/etc/salt/grains.d/salt] at time 02:53:21.136848
2019-05-18 02:53:21,137 [salt.state       :1813][INFO    ][13929] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-18 02:53:21,138 [salt.state       :300 ][INFO    ][13929] File /etc/salt/grains.d/salt is in the correct state
2019-05-18 02:53:21,138 [salt.state       :1951][INFO    ][13929] Completed state [/etc/salt/grains.d/salt] at time 02:53:21.138747 duration_in_ms=1.898
2019-05-18 02:53:21,139 [salt.state       :1780][INFO    ][13929] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:53:21.139657
2019-05-18 02:53:21,139 [salt.state       :1813][INFO    ][13929] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-18 02:53:21,140 [salt.state       :300 ][INFO    ][13929] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-18 02:53:21,140 [salt.state       :1951][INFO    ][13929] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:53:21.140491 duration_in_ms=0.835
2019-05-18 02:53:21,142 [salt.state       :1780][INFO    ][13929] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:53:21.142810
2019-05-18 02:53:21,143 [salt.state       :1813][INFO    ][13929] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-18 02:53:21,143 [salt.state       :300 ][INFO    ][13929] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-18 02:53:21,143 [salt.state       :1951][INFO    ][13929] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:53:21.143666 duration_in_ms=0.856
2019-05-18 02:53:21,144 [salt.state       :1780][INFO    ][13929] Running state [mine.update] at time 02:53:21.144364
2019-05-18 02:53:21,144 [salt.state       :1813][INFO    ][13929] Executing state module.wait for [mine.update]
2019-05-18 02:53:21,144 [salt.state       :300 ][INFO    ][13929] No changes made for mine.update
2019-05-18 02:53:21,145 [salt.state       :1951][INFO    ][13929] Completed state [mine.update] at time 02:53:21.145154 duration_in_ms=0.79
2019-05-18 02:53:21,145 [salt.state       :1780][INFO    ][13929] Running state [ca-certificates] at time 02:53:21.145435
2019-05-18 02:53:21,147 [salt.state       :1813][INFO    ][13929] Executing state pkg.installed for [ca-certificates]
2019-05-18 02:53:21,156 [salt.state       :300 ][INFO    ][13929] All specified packages are already installed
2019-05-18 02:53:21,156 [salt.state       :1951][INFO    ][13929] Completed state [ca-certificates] at time 02:53:21.156543 duration_in_ms=11.108
2019-05-18 02:53:21,157 [salt.state       :1780][INFO    ][13929] Running state [update-ca-certificates] at time 02:53:21.157266
2019-05-18 02:53:21,157 [salt.state       :1813][INFO    ][13929] Executing state cmd.wait for [update-ca-certificates]
2019-05-18 02:53:21,158 [salt.state       :300 ][INFO    ][13929] No changes made for update-ca-certificates
2019-05-18 02:53:21,158 [salt.state       :1951][INFO    ][13929] Completed state [update-ca-certificates] at time 02:53:21.158658 duration_in_ms=1.392
2019-05-18 02:53:21,159 [salt.state       :1780][INFO    ][13929] Running state [iptables] at time 02:53:21.158929
2019-05-18 02:53:21,159 [salt.state       :1813][INFO    ][13929] Executing state pkg.installed for [iptables]
2019-05-18 02:53:21,168 [salt.state       :300 ][INFO    ][13929] All specified packages are already installed
2019-05-18 02:53:21,168 [salt.state       :1951][INFO    ][13929] Completed state [iptables] at time 02:53:21.168696 duration_in_ms=9.767
2019-05-18 02:53:21,169 [salt.state       :1780][INFO    ][13929] Running state [iptables-persistent] at time 02:53:21.168980
2019-05-18 02:53:21,169 [salt.state       :1813][INFO    ][13929] Executing state pkg.installed for [iptables-persistent]
2019-05-18 02:53:21,178 [salt.state       :300 ][INFO    ][13929] All specified packages are already installed
2019-05-18 02:53:21,179 [salt.state       :1951][INFO    ][13929] Completed state [iptables-persistent] at time 02:53:21.179114 duration_in_ms=10.134
2019-05-18 02:53:21,180 [salt.state       :1780][INFO    ][13929] Running state [iptables_modules_v4_load] at time 02:53:21.180152
2019-05-18 02:53:21,180 [salt.state       :1813][INFO    ][13929] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-18 02:53:21,181 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command 'lsmod' in directory '/root'
2019-05-18 02:53:21,207 [salt.state       :300 ][INFO    ][13929] Kernel modules iptable_filter, ip_tables are already present
2019-05-18 02:53:21,208 [salt.state       :1951][INFO    ][13929] Completed state [iptables_modules_v4_load] at time 02:53:21.208266 duration_in_ms=28.113
2019-05-18 02:53:21,209 [salt.state       :1780][INFO    ][13929] Running state [/etc/iptables/rules.v4] at time 02:53:21.209374
2019-05-18 02:53:21,210 [salt.state       :1813][INFO    ][13929] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-18 02:53:21,319 [salt.state       :300 ][INFO    ][13929] File /etc/iptables/rules.v4 is in the correct state
2019-05-18 02:53:21,319 [salt.state       :1951][INFO    ][13929] Completed state [/etc/iptables/rules.v4] at time 02:53:21.319353 duration_in_ms=109.979
2019-05-18 02:53:21,320 [salt.state       :1780][INFO    ][13929] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:53:21.320475
2019-05-18 02:53:21,320 [salt.state       :1813][INFO    ][13929] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-18 02:53:21,321 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-18 02:53:21,342 [salt.state       :300 ][INFO    ][13929] onlyif execution failed
2019-05-18 02:53:21,343 [salt.state       :1951][INFO    ][13929] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:53:21.343219 duration_in_ms=22.743
2019-05-18 02:53:21,345 [salt.state       :1780][INFO    ][13929] Running state [netfilter-persistent] at time 02:53:21.345366
2019-05-18 02:53:21,345 [salt.state       :1813][INFO    ][13929] Executing state service.running for [netfilter-persistent]
2019-05-18 02:53:21,347 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-18 02:53:21,373 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-18 02:53:21,400 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-18 02:53:21,424 [salt.state       :300 ][INFO    ][13929] The service netfilter-persistent is already running
2019-05-18 02:53:21,425 [salt.state       :1951][INFO    ][13929] Completed state [netfilter-persistent] at time 02:53:21.425446 duration_in_ms=80.079
2019-05-18 02:53:21,427 [salt.state       :1780][INFO    ][13929] Running state [iptables_extra.remove_stale_tables] at time 02:53:21.427105
2019-05-18 02:53:21,427 [salt.state       :1813][INFO    ][13929] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-18 02:53:21,428 [salt.state       :300 ][INFO    ][13929] No changes made for iptables_extra.remove_stale_tables
2019-05-18 02:53:21,428 [salt.state       :1951][INFO    ][13929] Completed state [iptables_extra.remove_stale_tables] at time 02:53:21.428722 duration_in_ms=1.617
2019-05-18 02:53:21,429 [salt.state       :1780][INFO    ][13929] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:53:21.429236
2019-05-18 02:53:21,429 [salt.state       :1813][INFO    ][13929] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-18 02:53:21,430 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13929] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-18 02:53:21,452 [salt.state       :300 ][INFO    ][13929] onlyif execution failed
2019-05-18 02:53:21,452 [salt.state       :1951][INFO    ][13929] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:53:21.452577 duration_in_ms=23.341
2019-05-18 02:53:21,454 [salt.state       :1780][INFO    ][13929] Running state [/etc/iptables/rules.v6] at time 02:53:21.454239
2019-05-18 02:53:21,454 [salt.state       :1813][INFO    ][13929] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-18 02:53:21,455 [salt.state       :300 ][INFO    ][13929] File /etc/iptables/rules.v6 is not present
2019-05-18 02:53:21,456 [salt.state       :1951][INFO    ][13929] Completed state [/etc/iptables/rules.v6] at time 02:53:21.455949 duration_in_ms=1.71
2019-05-18 02:53:21,457 [salt.state       :1780][INFO    ][13929] Running state [iptables_extra.flush_all] at time 02:53:21.457157
2019-05-18 02:53:21,457 [salt.state       :1813][INFO    ][13929] Executing state module.wait for [iptables_extra.flush_all]
2019-05-18 02:53:21,461 [salt.state       :300 ][INFO    ][13929] No changes made for iptables_extra.flush_all
2019-05-18 02:53:21,461 [salt.state       :1951][INFO    ][13929] Completed state [iptables_extra.flush_all] at time 02:53:21.461374 duration_in_ms=4.217
2019-05-18 02:53:21,466 [salt.minion      :1711][INFO    ][13929] Returning information for job: 20190518025309774128
2019-05-18 02:53:22,169 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command state.apply with jid 20190518025322156162
2019-05-18 02:53:22,199 [salt.minion      :1432][INFO    ][14008] Starting a new job with PID 14008
2019-05-18 02:53:23,154 [salt.state       :915 ][INFO    ][14008] Loading fresh modules for state activity
2019-05-18 02:53:24,395 [salt.state       :1780][INFO    ][14008] Running state [maas-rack-controller] at time 02:53:24.395073
2019-05-18 02:53:24,395 [salt.state       :1813][INFO    ][14008] Executing state pkg.installed for [maas-rack-controller]
2019-05-18 02:53:24,396 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14008] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-18 02:53:24,513 [salt.state       :300 ][INFO    ][14008] All specified packages are already installed
2019-05-18 02:53:24,513 [salt.state       :1951][INFO    ][14008] Completed state [maas-rack-controller] at time 02:53:24.513521 duration_in_ms=118.448
2019-05-18 02:53:24,513 [salt.state       :1780][INFO    ][14008] Running state [ipmitool] at time 02:53:24.513905
2019-05-18 02:53:24,514 [salt.state       :1813][INFO    ][14008] Executing state pkg.installed for [ipmitool]
2019-05-18 02:53:24,524 [salt.state       :300 ][INFO    ][14008] All specified packages are already installed
2019-05-18 02:53:24,524 [salt.state       :1951][INFO    ][14008] Completed state [ipmitool] at time 02:53:24.524357 duration_in_ms=10.452
2019-05-18 02:53:24,527 [salt.state       :1780][INFO    ][14008] Running state [/etc/maas/rackd.conf] at time 02:53:24.527419
2019-05-18 02:53:24,527 [salt.state       :1813][INFO    ][14008] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-18 02:53:24,528 [salt.state       :300 ][INFO    ][14008] No changes needed to be made
2019-05-18 02:53:24,528 [salt.state       :1951][INFO    ][14008] Completed state [/etc/maas/rackd.conf] at time 02:53:24.528902 duration_in_ms=1.484
2019-05-18 02:53:24,529 [salt.state       :1780][INFO    ][14008] Running state [/etc/maas/rackd.conf] at time 02:53:24.529182
2019-05-18 02:53:24,529 [salt.state       :1813][INFO    ][14008] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-18 02:53:24,530 [salt.loaded.int.states.file:2298][WARNING ][14008] 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.
2019-05-18 02:53:24,531 [salt.state       :300 ][INFO    ][14008] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-18 02:53:24,531 [salt.state       :1951][INFO    ][14008] Completed state [/etc/maas/rackd.conf] at time 02:53:24.531891 duration_in_ms=2.709
2019-05-18 02:53:24,532 [salt.state       :1780][INFO    ][14008] Running state [maas-rackd] at time 02:53:24.532936
2019-05-18 02:53:24,533 [salt.state       :1813][INFO    ][14008] Executing state service.running for [maas-rackd]
2019-05-18 02:53:24,533 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14008] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-18 02:53:24,577 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14008] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-18 02:53:24,598 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14008] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-18 02:53:24,620 [salt.state       :300 ][INFO    ][14008] The service maas-rackd is already running
2019-05-18 02:53:24,620 [salt.state       :1951][INFO    ][14008] Completed state [maas-rackd] at time 02:53:24.620710 duration_in_ms=87.773
2019-05-18 02:53:24,622 [salt.minion      :1711][INFO    ][14008] Returning information for job: 20190518025322156162
2019-05-18 02:53:25,360 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command state.apply with jid 20190518025325348238
2019-05-18 02:53:25,385 [salt.minion      :1432][INFO    ][14035] Starting a new job with PID 14035
2019-05-18 02:53:26,413 [salt.state       :915 ][INFO    ][14035] Loading fresh modules for state activity
2019-05-18 02:53:27,862 [salt.state       :1780][INFO    ][14035] Running state [maas-region-controller] at time 02:53:27.861674
2019-05-18 02:53:27,862 [salt.state       :1813][INFO    ][14035] Executing state pkg.installed for [maas-region-controller]
2019-05-18 02:53:27,863 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14035] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-18 02:53:27,983 [salt.state       :300 ][INFO    ][14035] All specified packages are already installed
2019-05-18 02:53:27,983 [salt.state       :1951][INFO    ][14035] Completed state [maas-region-controller] at time 02:53:27.983554 duration_in_ms=121.88
2019-05-18 02:53:27,983 [salt.state       :1780][INFO    ][14035] Running state [python-oauth] at time 02:53:27.983917
2019-05-18 02:53:27,984 [salt.state       :1813][INFO    ][14035] Executing state pkg.installed for [python-oauth]
2019-05-18 02:53:27,993 [salt.state       :300 ][INFO    ][14035] All specified packages are already installed
2019-05-18 02:53:27,993 [salt.state       :1951][INFO    ][14035] Completed state [python-oauth] at time 02:53:27.993836 duration_in_ms=9.919
2019-05-18 02:53:27,997 [salt.state       :1780][INFO    ][14035] Running state [/etc/maas/regiond.conf] at time 02:53:27.997279
2019-05-18 02:53:27,997 [salt.state       :1813][INFO    ][14035] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-18 02:53:28,002 [salt.state       :300 ][INFO    ][14035] No changes needed to be made
2019-05-18 02:53:28,003 [salt.state       :1951][INFO    ][14035] Completed state [/etc/maas/regiond.conf] at time 02:53:28.003203 duration_in_ms=5.924
2019-05-18 02:53:28,003 [salt.state       :1780][INFO    ][14035] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:53:28.003728
2019-05-18 02:53:28,004 [salt.state       :1813][INFO    ][14035] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-18 02:53:28,064 [salt.state       :300 ][INFO    ][14035] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-18 02:53:28,064 [salt.state       :1951][INFO    ][14035] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:53:28.064466 duration_in_ms=60.738
2019-05-18 02:53:28,065 [salt.state       :1780][INFO    ][14035] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:53:28.064988
2019-05-18 02:53:28,065 [salt.state       :1813][INFO    ][14035] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-18 02:53:28,069 [salt.state       :300 ][INFO    ][14035] No changes needed to be made
2019-05-18 02:53:28,070 [salt.state       :1951][INFO    ][14035] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:53:28.070050 duration_in_ms=5.062
2019-05-18 02:53:28,070 [salt.state       :1780][INFO    ][14035] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:53:28.070862
2019-05-18 02:53:28,071 [salt.state       :1813][INFO    ][14035] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-18 02:53:28,075 [salt.state       :300 ][INFO    ][14035] No changes needed to be made
2019-05-18 02:53:28,075 [salt.state       :1951][INFO    ][14035] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:53:28.075872 duration_in_ms=5.01
2019-05-18 02:53:28,076 [salt.state       :1780][INFO    ][14035] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:53:28.076385
2019-05-18 02:53:28,076 [salt.state       :1813][INFO    ][14035] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-18 02:53:28,101 [salt.state       :300 ][INFO    ][14035] No changes needed to be made
2019-05-18 02:53:28,101 [salt.state       :1951][INFO    ][14035] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:53:28.101621 duration_in_ms=25.236
2019-05-18 02:53:28,102 [salt.state       :1780][INFO    ][14035] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:53:28.102913
2019-05-18 02:53:28,103 [salt.state       :1813][INFO    ][14035] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-18 02:53:28,123 [salt.state       :300 ][INFO    ][14035] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-18 02:53:28,123 [salt.state       :1951][INFO    ][14035] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:53:28.123580 duration_in_ms=20.666
2019-05-18 02:53:28,125 [salt.state       :1780][INFO    ][14035] Running state [a2enmod headers] at time 02:53:28.125122
2019-05-18 02:53:28,125 [salt.state       :1813][INFO    ][14035] Executing state cmd.run for [a2enmod headers]
2019-05-18 02:53:28,126 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14035] Executing command 'a2enmod headers' in directory '/root'
2019-05-18 02:53:28,197 [salt.state       :300 ][INFO    ][14035] {'pid': 14060, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-18 02:53:28,198 [salt.state       :1951][INFO    ][14035] Completed state [a2enmod headers] at time 02:53:28.198365 duration_in_ms=73.243
2019-05-18 02:53:28,199 [salt.state       :1780][INFO    ][14035] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:53:28.198919
2019-05-18 02:53:28,199 [salt.state       :1813][INFO    ][14035] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-18 02:53:28,224 [salt.state       :300 ][INFO    ][14035] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-18 02:53:28,225 [salt.state       :1951][INFO    ][14035] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:53:28.225025 duration_in_ms=26.105
2019-05-18 02:53:28,228 [salt.state       :1780][INFO    ][14035] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:53:28.228578
2019-05-18 02:53:28,228 [salt.state       :1813][INFO    ][14035] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-18 02:53:28,288 [salt.state       :300 ][INFO    ][14035] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-18 02:53:28,288 [salt.state       :1951][INFO    ][14035] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:53:28.288598 duration_in_ms=60.019
2019-05-18 02:53:28,289 [salt.state       :1780][INFO    ][14035] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:53:28.289246
2019-05-18 02:53:28,290 [salt.state       :1813][INFO    ][14035] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-18 02:53:28,358 [salt.state       :300 ][INFO    ][14035] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-18 02:53:28,358 [salt.state       :1951][INFO    ][14035] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:53:28.358772 duration_in_ms=69.527
2019-05-18 02:53:28,359 [salt.state       :1780][INFO    ][14035] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:53:28.359300
2019-05-18 02:53:28,359 [salt.state       :1813][INFO    ][14035] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-18 02:53:28,441 [salt.state       :300 ][INFO    ][14035] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-18 02:53:28,442 [salt.state       :1951][INFO    ][14035] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:53:28.442672 duration_in_ms=83.371
2019-05-18 02:53:28,443 [salt.state       :1780][INFO    ][14035] Running state [/root/.pgpass] at time 02:53:28.443284
2019-05-18 02:53:28,443 [salt.state       :1813][INFO    ][14035] Executing state file.managed for [/root/.pgpass]
2019-05-18 02:53:28,498 [salt.state       :300 ][INFO    ][14035] File /root/.pgpass is in the correct state
2019-05-18 02:53:28,499 [salt.state       :1951][INFO    ][14035] Completed state [/root/.pgpass] at time 02:53:28.499429 duration_in_ms=56.145
2019-05-18 02:53:28,509 [salt.state       :1780][INFO    ][14035] Running state [maas-region syncdb --noinput] at time 02:53:28.509821
2019-05-18 02:53:28,510 [salt.state       :1813][INFO    ][14035] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-18 02:53:28,511 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14035] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-18 02:53:31,208 [salt.state       :300 ][INFO    ][14035] {'pid': 14073, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sessions, maasserver, auth, metadataserver, piston3, contenttypes, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-18 02:53:31,209 [salt.state       :1951][INFO    ][14035] Completed state [maas-region syncdb --noinput] at time 02:53:31.208979 duration_in_ms=2699.157
2019-05-18 02:53:31,209 [salt.state       :2022][WARNING ][14035] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-18 02:53:31,214 [salt.state       :1780][INFO    ][14035] Running state [maas-regiond] at time 02:53:31.214797
2019-05-18 02:53:31,215 [salt.state       :1813][INFO    ][14035] Executing state service.running for [maas-regiond]
2019-05-18 02:53:31,217 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14035] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-18 02:53:31,265 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14035] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-18 02:53:31,282 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14035] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-18 02:53:31,306 [salt.state       :300 ][INFO    ][14035] The service maas-regiond is already running
2019-05-18 02:53:31,307 [salt.state       :1951][INFO    ][14035] Completed state [maas-regiond] at time 02:53:31.307199 duration_in_ms=92.401
2019-05-18 02:53:31,312 [salt.state       :1780][INFO    ][14035] Running state [bind9] at time 02:53:31.311977
2019-05-18 02:53:31,312 [salt.state       :1813][INFO    ][14035] Executing state service.running for [bind9]
2019-05-18 02:53:31,313 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14035] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-18 02:53:31,331 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14035] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-18 02:53:31,356 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14035] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-18 02:53:31,381 [salt.state       :300 ][INFO    ][14035] The service bind9 is already running
2019-05-18 02:53:31,383 [salt.state       :1951][INFO    ][14035] Completed state [bind9] at time 02:53:31.383785 duration_in_ms=71.806
2019-05-18 02:53:31,387 [salt.state       :1780][INFO    ][14035] Running state [apache2] at time 02:53:31.387028
2019-05-18 02:53:31,387 [salt.state       :1813][INFO    ][14035] Executing state service.running for [apache2]
2019-05-18 02:53:31,389 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14035] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-18 02:53:31,414 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14035] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-18 02:53:31,437 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14035] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-18 02:53:31,470 [salt.state       :300 ][INFO    ][14035] The service apache2 is already running
2019-05-18 02:53:31,470 [salt.state       :1951][INFO    ][14035] Completed state [apache2] at time 02:53:31.470861 duration_in_ms=83.833
2019-05-18 02:53:31,473 [salt.state       :1780][INFO    ][14035] Running state [maasng.wait_for_http_code] at time 02:53:31.473297
2019-05-18 02:53:31,474 [salt.state       :1813][INFO    ][14035] Executing state module.run for [maasng.wait_for_http_code]
2019-05-18 02:53:31,475 [salt.utils.decorators:613 ][WARNING ][14035] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 02:53:31,639 [salt.state       :300 ][INFO    ][14035] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-18 02:53:31,639 [salt.state       :1951][INFO    ][14035] Completed state [maasng.wait_for_http_code] at time 02:53:31.639702 duration_in_ms=166.405
2019-05-18 02:53:31,641 [salt.state       :1780][INFO    ][14035] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:53:31.641154
2019-05-18 02:53:31,642 [salt.state       :1813][INFO    ][14035] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-18 02:53:31,642 [salt.state       :300 ][INFO    ][14035] /var/lib/maas/.setup_admin exists
2019-05-18 02:53:31,643 [salt.state       :1951][INFO    ][14035] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:53:31.643288 duration_in_ms=2.134
2019-05-18 02:53:31,644 [salt.state       :1780][INFO    ][14035] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:31.644602
2019-05-18 02:53:31,645 [salt.state       :1813][INFO    ][14035] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-18 02:53:31,647 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14035] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-18 02:53:33,525 [salt.state       :300 ][INFO    ][14035] {'pid': 14094, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-18 02:53:33,527 [salt.state       :1951][INFO    ][14035] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:33.526964 duration_in_ms=1882.361
2019-05-18 02:53:33,537 [salt.state       :1780][INFO    ][14035] Running state [maas_region_boot_source_resources_mirror] at time 02:53:33.536942
2019-05-18 02:53:33,537 [salt.state       :1813][INFO    ][14035] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-18 02:53:33,634 [salt.state       :300 ][INFO    ][14035] {'changes': {}}
2019-05-18 02:53:33,635 [salt.state       :1951][INFO    ][14035] Completed state [maas_region_boot_source_resources_mirror] at time 02:53:33.635069 duration_in_ms=98.127
2019-05-18 02:53:33,636 [salt.state       :1780][INFO    ][14035] Running state [maasng.boot_resources_import] at time 02:53:33.636282
2019-05-18 02:53:33,636 [salt.state       :1813][INFO    ][14035] Executing state module.run for [maasng.boot_resources_import]
2019-05-18 02:53:33,637 [salt.utils.decorators:613 ][WARNING ][14035] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 02:53:33,756 [salt.loaded.ext.module.maasng:1600][INFO    ][14035] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-18 02:53:38,814 [salt.loaded.ext.module.maasng:1600][INFO    ][14035] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-18 02:53:40,463 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518025340452676
2019-05-18 02:53:40,487 [salt.minion      :1432][INFO    ][14122] Starting a new job with PID 14122
2019-05-18 02:53:40,518 [salt.minion      :1711][INFO    ][14122] Returning information for job: 20190518025340452676
2019-05-18 02:53:43,919 [salt.state       :300 ][INFO    ][14035] {'ret': True}
2019-05-18 02:53:43,920 [salt.state       :1951][INFO    ][14035] Completed state [maasng.boot_resources_import] at time 02:53:43.920169 duration_in_ms=10283.887
2019-05-18 02:53:43,923 [salt.state       :1780][INFO    ][14035] Running state [maas_region_boot_sources_selection_xenial] at time 02:53:43.921774
2019-05-18 02:53:43,924 [salt.state       :1813][INFO    ][14035] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-18 02:53:44,097 [salt.state       :300 ][INFO    ][14035] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-18 02:53:44,097 [salt.state       :1951][INFO    ][14035] Completed state [maas_region_boot_sources_selection_xenial] at time 02:53:44.097683 duration_in_ms=175.909
2019-05-18 02:53:44,099 [salt.state       :1780][INFO    ][14035] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:53:44.099427
2019-05-18 02:53:44,100 [salt.state       :1813][INFO    ][14035] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-18 02:53:44,100 [salt.utils.decorators:613 ][WARNING ][14035] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 02:53:44,101 [salt.loaded.ext.module.maasng:1771][INFO    ][14035] boot-sources sync initiated for ALL Rack's
2019-05-18 02:53:45,163 [salt.state       :300 ][INFO    ][14035] {'ret': True}
2019-05-18 02:53:45,164 [salt.state       :1951][INFO    ][14035] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:53:45.164079 duration_in_ms=1064.652
2019-05-18 02:53:45,167 [salt.state       :1780][INFO    ][14035] Running state [maas.process_maas_config] at time 02:53:45.167159
2019-05-18 02:53:45,167 [salt.state       :1813][INFO    ][14035] Executing state module.run for [maas.process_maas_config]
2019-05-18 02:53:45,168 [salt.utils.decorators:613 ][WARNING ][14035] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 02:53:45,169 [salt.loaded.ext.module.maas:92  ][INFO    ][14035] maasconfig name=enable_http_proxy value=True
2019-05-18 02:53:45,223 [salt.loaded.ext.module.maas:92  ][INFO    ][14035] maasconfig name=upstream_dns value=8.8.8.8
2019-05-18 02:53:45,279 [salt.loaded.ext.module.maas:92  ][INFO    ][14035] maasconfig name=commissioning_distro_series value=xenial
2019-05-18 02:53:45,343 [salt.loaded.ext.module.maas:92  ][INFO    ][14035] maasconfig name=default_osystem value=ubuntu
2019-05-18 02:53:45,404 [salt.loaded.ext.module.maas:92  ][INFO    ][14035] maasconfig name=active_discovery_interval value=600
2019-05-18 02:53:46,759 [salt.loaded.ext.module.maas:92  ][INFO    ][14035] maasconfig name=dnssec_validation value=no
2019-05-18 02:53:46,818 [salt.loaded.ext.module.maas:92  ][INFO    ][14035] maasconfig name=maas_name value=mas01
2019-05-18 02:53:46,860 [salt.loaded.ext.module.maas:92  ][INFO    ][14035] maasconfig name=network_discovery value=enabled
2019-05-18 02:53:46,954 [salt.loaded.ext.module.maas:92  ][INFO    ][14035] maasconfig name=enable_third_party_drivers value=True
2019-05-18 02:53:47,000 [salt.loaded.ext.module.maas:92  ][INFO    ][14035] maasconfig name=default_storage_layout value=lvm
2019-05-18 02:53:47,042 [salt.loaded.ext.module.maas:92  ][INFO    ][14035] maasconfig name=ntp_external_only value=True
2019-05-18 02:53:47,095 [salt.loaded.ext.module.maas:92  ][INFO    ][14035] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-18 02:53:47,153 [salt.loaded.ext.module.maas:92  ][INFO    ][14035] maasconfig name=default_distro_series value=xenial
2019-05-18 02:53:47,211 [salt.loaded.ext.module.maas:92  ][INFO    ][14035] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-05-18 02:53:47,345 [salt.state       :300 ][INFO    ][14035] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-18 02:53:47,346 [salt.state       :1951][INFO    ][14035] Completed state [maas.process_maas_config] at time 02:53:47.346654 duration_in_ms=2179.495
2019-05-18 02:53:47,347 [salt.state       :1780][INFO    ][14035] Running state [pxe_admin] at time 02:53:47.347696
2019-05-18 02:53:47,348 [salt.state       :1813][INFO    ][14035] Executing state maasng.fabric_present for [pxe_admin]
2019-05-18 02:53:47,405 [salt.loaded.ext.module.maasng:945 ][INFO    ][14035] [{u'class_type': None, 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'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, 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'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', 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'fernra', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-18 02:53:47,465 [salt.loaded.ext.module.maasng:1008][WARNING ][14035] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-18 02:53:47,466 [salt.loaded.ext.module.maasng:1011][WARNING ][14035] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-18 02:53:47,529 [salt.state       :300 ][INFO    ][14035] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-18 02:53:47,529 [salt.state       :1951][INFO    ][14035] Completed state [pxe_admin] at time 02:53:47.529468 duration_in_ms=181.772
2019-05-18 02:53:47,530 [salt.state       :1780][INFO    ][14035] Running state [vlan 0] at time 02:53:47.530300
2019-05-18 02:53:47,530 [salt.state       :1813][INFO    ][14035] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-18 02:53:47,587 [salt.loaded.ext.module.maasng:945 ][INFO    ][14035] [{u'class_type': None, 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'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, 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'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', 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'fernra', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-18 02:53:47,699 [salt.loaded.ext.module.maasng:945 ][INFO    ][14035] [{u'class_type': None, 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'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, 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'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', 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'fernra', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-18 02:53:47,947 [salt.loaded.ext.module.maasng:945 ][INFO    ][14035] [{u'class_type': None, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fernra', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-18 02:53:48,049 [salt.state       :300 ][INFO    ][14035] {'new': 'Vlan untagged was updated'}
2019-05-18 02:53:48,051 [salt.state       :1951][INFO    ][14035] Completed state [vlan 0] at time 02:53:48.049645 duration_in_ms=519.344
2019-05-18 02:53:48,053 [salt.state       :1780][INFO    ][14035] Running state [192.168.11.0/24] at time 02:53:48.052902
2019-05-18 02:53:48,053 [salt.state       :1813][INFO    ][14035] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-18 02:53:48,271 [salt.loaded.ext.module.maasng:945 ][INFO    ][14035] [{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'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], 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'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], 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'fernra', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-05-18 02:53:48,272 [salt.loaded.ext.module.maasng:1235][WARNING ][14035] Ignoring parameter vlan:0
2019-05-18 02:53:48,364 [salt.state       :300 ][INFO    ][14035] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-18 02:53:48,364 [salt.state       :1951][INFO    ][14035] Completed state [192.168.11.0/24] at time 02:53:48.364624 duration_in_ms=311.722
2019-05-18 02:53:48,365 [salt.state       :1780][INFO    ][14035] Running state [maas_create_iprange_1] at time 02:53:48.365461
2019-05-18 02:53:48,366 [salt.state       :1813][INFO    ][14035] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-18 02:53:48,412 [salt.state       :300 ][INFO    ][14035] Iprange maas_create_iprange_1 already exist.
2019-05-18 02:53:48,413 [salt.state       :1951][INFO    ][14035] Completed state [maas_create_iprange_1] at time 02:53:48.413171 duration_in_ms=47.71
2019-05-18 02:53:48,413 [salt.state       :1780][INFO    ][14035] Running state [vlan 0] at time 02:53:48.413493
2019-05-18 02:53:48,414 [salt.state       :1813][INFO    ][14035] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-18 02:53:48,475 [salt.loaded.ext.module.maasng:945 ][INFO    ][14035] [{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'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], 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'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], 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'fernra', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-05-18 02:53:48,584 [salt.loaded.ext.module.maasng:945 ][INFO    ][14035] [{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'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], 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'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], 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'fernra', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-05-18 02:53:48,856 [salt.loaded.ext.module.maasng:945 ][INFO    ][14035] [{u'class_type': None, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fernra', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-18 02:53:48,977 [salt.state       :300 ][INFO    ][14035] {'new': 'Vlan untagged was updated'}
2019-05-18 02:53:48,977 [salt.state       :1951][INFO    ][14035] Completed state [vlan 0] at time 02:53:48.977449 duration_in_ms=563.955
2019-05-18 02:53:48,978 [salt.state       :1780][INFO    ][14035] Running state [opnfv] at time 02:53:48.978848
2019-05-18 02:53:48,979 [salt.state       :1813][INFO    ][14035] Executing state maasng.sshkey_present for [opnfv]
2019-05-18 02:53:49,022 [salt.loaded.ext.module.maasng:1903][INFO    ][14035] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-05-18 02:53:49,023 [salt.state       :300 ][INFO    ][14035] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-18 02:53:49,023 [salt.state       :1951][INFO    ][14035] Completed state [opnfv] at time 02:53:49.023411 duration_in_ms=44.563
2019-05-18 02:53:49,027 [salt.minion      :1711][INFO    ][14035] Returning information for job: 20190518025325348238
2019-05-18 02:53:49,775 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command state.apply with jid 20190518025349768099
2019-05-18 02:53:49,793 [salt.minion      :1432][INFO    ][14485] Starting a new job with PID 14485
2019-05-18 02:53:56,072 [salt.state       :915 ][INFO    ][14485] Loading fresh modules for state activity
2019-05-18 02:53:56,187 [salt.state       :1780][INFO    ][14485] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:56.187829
2019-05-18 02:53:56,188 [salt.state       :1813][INFO    ][14485] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-18 02:53:56,190 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14485] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-18 02:53:58,144 [salt.state       :300 ][INFO    ][14485] {'pid': 14533, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-18 02:53:58,144 [salt.state       :1951][INFO    ][14485] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:58.144644 duration_in_ms=1956.815
2019-05-18 02:53:58,147 [salt.state       :1780][INFO    ][14485] Running state [maas.process_machines] at time 02:53:58.147325
2019-05-18 02:53:58,147 [salt.state       :1813][INFO    ][14485] Executing state module.run for [maas.process_machines]
2019-05-18 02:53:58,148 [salt.utils.decorators:613 ][WARNING ][14485] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 02:53:58,731 [salt.loaded.ext.module.maas:412 ][WARNING ][14485] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-18 02:53:58,731 [salt.loaded.ext.module.maas:92  ][INFO    ][14485] 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=4ananf architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-18 02:53:59,910 [salt.loaded.ext.module.maas:412 ][WARNING ][14485] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-18 02:53:59,911 [salt.loaded.ext.module.maas:92  ][INFO    ][14485] 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=q83qwf architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-18 02:54:01,160 [salt.loaded.ext.module.maas:412 ][WARNING ][14485] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-18 02:54:01,162 [salt.loaded.ext.module.maas:92  ][INFO    ][14485] machine hostname=kvm01 power_type=ipmi mac_addresses=['14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=mx3sfb architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-18 02:54:02,396 [salt.loaded.ext.module.maas:412 ][WARNING ][14485] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-18 02:54:02,397 [salt.loaded.ext.module.maas:92  ][INFO    ][14485] machine hostname=kvm03 power_type=ipmi mac_addresses=['14:58:d0:54:7a:28'] power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 system_id=kqq6cn architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-18 02:54:03,606 [salt.loaded.ext.module.maas:412 ][WARNING ][14485] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-18 02:54:03,607 [salt.loaded.ext.module.maas:92  ][INFO    ][14485] machine hostname=kvm02 power_type=ipmi mac_addresses=['14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=6em64b architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-18 02:54:04,823 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518025404808869
2019-05-18 02:54:04,831 [salt.state       :300 ][INFO    ][14485] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-18 02:54:04,831 [salt.state       :1951][INFO    ][14485] Completed state [maas.process_machines] at time 02:54:04.831874 duration_in_ms=6684.547
2019-05-18 02:54:04,835 [salt.minion      :1711][INFO    ][14485] Returning information for job: 20190518025349768099
2019-05-18 02:54:04,848 [salt.minion      :1432][INFO    ][14752] Starting a new job with PID 14752
2019-05-18 02:54:04,884 [salt.minion      :1711][INFO    ][14752] Returning information for job: 20190518025404808869
2019-05-18 02:54:38,815 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command state.apply with jid 20190518025438801181
2019-05-18 02:54:38,841 [salt.minion      :1432][INFO    ][14772] Starting a new job with PID 14772
2019-05-18 02:54:44,780 [salt.state       :915 ][INFO    ][14772] Loading fresh modules for state activity
2019-05-18 02:54:44,887 [salt.state       :1780][INFO    ][14772] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:44.887247
2019-05-18 02:54:44,887 [salt.state       :1813][INFO    ][14772] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-18 02:54:44,889 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14772] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-18 02:54:46,759 [salt.state       :300 ][INFO    ][14772] {'pid': 14792, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-18 02:54:46,760 [salt.state       :1951][INFO    ][14772] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:46.760620 duration_in_ms=1873.373
2019-05-18 02:54:46,764 [salt.state       :1780][INFO    ][14772] Running state [maas.wait_for_machine_status] at time 02:54:46.764480
2019-05-18 02:54:46,765 [salt.state       :1813][INFO    ][14772] Executing state module.run for [maas.wait_for_machine_status]
2019-05-18 02:54:46,766 [salt.utils.decorators:613 ][WARNING ][14772] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 02:54:49,499 [salt.state       :300 ][INFO    ][14772] {'ret': True}
2019-05-18 02:54:49,500 [salt.state       :1951][INFO    ][14772] Completed state [maas.wait_for_machine_status] at time 02:54:49.500155 duration_in_ms=2735.674
2019-05-18 02:54:49,506 [salt.minion      :1711][INFO    ][14772] Returning information for job: 20190518025438801181
2019-05-18 02:54:50,220 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command state.apply with jid 20190518025450206964
2019-05-18 02:54:50,249 [salt.minion      :1432][INFO    ][14806] Starting a new job with PID 14806
2019-05-18 02:54:51,223 [salt.state       :915 ][INFO    ][14806] Loading fresh modules for state activity
2019-05-18 02:54:51,385 [salt.state       :1780][INFO    ][14806] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:51.385180
2019-05-18 02:54:51,387 [salt.state       :1813][INFO    ][14806] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-18 02:54:51,389 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14806] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-18 02:54:53,308 [salt.state       :300 ][INFO    ][14806] {'pid': 14813, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-18 02:54:53,309 [salt.state       :1951][INFO    ][14806] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:53.308986 duration_in_ms=1923.807
2019-05-18 02:54:53,313 [salt.state       :1780][INFO    ][14806] Running state [maas_machines_storage_cmp002_lvm] at time 02:54:53.313791
2019-05-18 02:54:53,314 [salt.state       :1813][INFO    ][14806] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-18 02:54:53,884 [salt.state       :300 ][INFO    ][14806] Machine cmp002 is not in Ready state.
2019-05-18 02:54:53,884 [salt.state       :1951][INFO    ][14806] Completed state [maas_machines_storage_cmp002_lvm] at time 02:54:53.884852 duration_in_ms=571.061
2019-05-18 02:54:53,885 [salt.state       :1780][INFO    ][14806] Running state [maas_machines_storage_cmp001_lvm] at time 02:54:53.885272
2019-05-18 02:54:53,885 [salt.state       :1813][INFO    ][14806] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-18 02:54:54,433 [salt.state       :300 ][INFO    ][14806] Machine cmp001 is not in Ready state.
2019-05-18 02:54:54,435 [salt.state       :1951][INFO    ][14806] Completed state [maas_machines_storage_cmp001_lvm] at time 02:54:54.435824 duration_in_ms=550.552
2019-05-18 02:54:54,439 [salt.minion      :1711][INFO    ][14806] Returning information for job: 20190518025450206964
2019-05-18 02:54:55,173 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command state.apply with jid 20190518025455159478
2019-05-18 02:54:55,200 [salt.minion      :1432][INFO    ][14823] Starting a new job with PID 14823
2019-05-18 02:54:56,292 [salt.state       :915 ][INFO    ][14823] Loading fresh modules for state activity
2019-05-18 02:54:56,384 [salt.state       :1780][INFO    ][14823] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:56.384448
2019-05-18 02:54:56,384 [salt.state       :1813][INFO    ][14823] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-18 02:54:56,387 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14823] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-18 02:54:58,264 [salt.state       :300 ][INFO    ][14823] {'pid': 14856, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-18 02:54:58,265 [salt.state       :1951][INFO    ][14823] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:58.264963 duration_in_ms=1880.514
2019-05-18 02:54:58,268 [salt.state       :1780][INFO    ][14823] Running state [maas.deploy_machines] at time 02:54:58.268718
2019-05-18 02:54:58,269 [salt.state       :1813][INFO    ][14823] Executing state module.run for [maas.deploy_machines]
2019-05-18 02:54:58,270 [salt.utils.decorators:613 ][WARNING ][14823] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 02:54:58,836 [salt.loaded.ext.module.maas:684 ][INFO    ][14823] deploymachines hwe_kernel=ga-16.04 system_id=6em64b distro_series=xenial
2019-05-18 02:55:01,109 [salt.state       :300 ][INFO    ][14823] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-05-18 02:55:01,110 [salt.state       :1951][INFO    ][14823] Completed state [maas.deploy_machines] at time 02:55:01.109661 duration_in_ms=2840.943
2019-05-18 02:55:01,112 [salt.minion      :1711][INFO    ][14823] Returning information for job: 20190518025455159478
2019-05-18 02:55:01,872 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command state.apply with jid 20190518025501815508
2019-05-18 02:55:01,895 [salt.minion      :1432][INFO    ][14922] Starting a new job with PID 14922
2019-05-18 02:55:07,869 [salt.state       :915 ][INFO    ][14922] Loading fresh modules for state activity
2019-05-18 02:55:07,987 [salt.state       :1780][INFO    ][14922] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:07.987870
2019-05-18 02:55:07,988 [salt.state       :1813][INFO    ][14922] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-18 02:55:07,990 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14922] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-18 02:55:09,882 [salt.state       :300 ][INFO    ][14922] {'pid': 14933, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-18 02:55:09,883 [salt.state       :1951][INFO    ][14922] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:09.883559 duration_in_ms=1895.688
2019-05-18 02:55:09,887 [salt.state       :1780][INFO    ][14922] Running state [maas.wait_for_machine_status] at time 02:55:09.887113
2019-05-18 02:55:09,887 [salt.state       :1813][INFO    ][14922] Executing state module.run for [maas.wait_for_machine_status]
2019-05-18 02:55:09,888 [salt.utils.decorators:613 ][WARNING ][14922] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-18 02:55:12,919 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2246.99194908s left)
2019-05-18 02:55:16,951 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518025516938466
2019-05-18 02:55:16,978 [salt.minion      :1432][INFO    ][14951] Starting a new job with PID 14951
2019-05-18 02:55:17,013 [salt.minion      :1711][INFO    ][14951] Returning information for job: 20190518025516938466
2019-05-18 02:55:45,633 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.27790403s left)
2019-05-18 02:55:47,052 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518025547034146
2019-05-18 02:55:47,081 [salt.minion      :1432][INFO    ][14986] Starting a new job with PID 14986
2019-05-18 02:55:47,113 [salt.minion      :1711][INFO    ][14986] Returning information for job: 20190518025547034146
2019-05-18 02:56:17,177 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518025617163025
2019-05-18 02:56:17,201 [salt.minion      :1432][INFO    ][15030] Starting a new job with PID 15030
2019-05-18 02:56:17,230 [salt.minion      :1711][INFO    ][15030] Returning information for job: 20190518025617163025
2019-05-18 02:56:18,462 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.44910812s left)
2019-05-18 02:56:47,261 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518025647244941
2019-05-18 02:56:47,291 [salt.minion      :1432][INFO    ][15068] Starting a new job with PID 15068
2019-05-18 02:56:47,326 [salt.minion      :1711][INFO    ][15068] Returning information for job: 20190518025647244941
2019-05-18 02:56:51,256 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.65585208s left)
2019-05-18 02:57:17,361 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518025717345290
2019-05-18 02:57:17,392 [salt.minion      :1432][INFO    ][15129] Starting a new job with PID 15129
2019-05-18 02:57:17,421 [salt.minion      :1711][INFO    ][15129] Returning information for job: 20190518025717345290
2019-05-18 02:57:24,017 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.89478898s left)
2019-05-18 02:57:47,444 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518025747434832
2019-05-18 02:57:47,472 [salt.minion      :1432][INFO    ][15161] Starting a new job with PID 15161
2019-05-18 02:57:47,501 [salt.minion      :1711][INFO    ][15161] Returning information for job: 20190518025747434832
2019-05-18 02:57:57,015 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2082.89598608s left)
2019-05-18 02:58:17,533 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518025817519823
2019-05-18 02:58:17,553 [salt.minion      :1432][INFO    ][15205] Starting a new job with PID 15205
2019-05-18 02:58:17,580 [salt.minion      :1711][INFO    ][15205] Returning information for job: 20190518025817519823
2019-05-18 02:58:29,863 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.04879904s left)
2019-05-18 02:58:47,628 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518025847608620
2019-05-18 02:58:47,656 [salt.minion      :1432][INFO    ][15241] Starting a new job with PID 15241
2019-05-18 02:58:47,689 [salt.minion      :1711][INFO    ][15241] Returning information for job: 20190518025847608620
2019-05-18 02:59:02,748 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.16293001s left)
2019-05-18 02:59:17,744 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518025917730211
2019-05-18 02:59:17,765 [salt.minion      :1432][INFO    ][15309] Starting a new job with PID 15309
2019-05-18 02:59:17,792 [salt.minion      :1711][INFO    ][15309] Returning information for job: 20190518025917730211
2019-05-18 02:59:35,556 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.35575891s left)
2019-05-18 02:59:47,840 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518025947822619
2019-05-18 02:59:47,872 [salt.minion      :1432][INFO    ][15371] Starting a new job with PID 15371
2019-05-18 02:59:47,907 [salt.minion      :1711][INFO    ][15371] Returning information for job: 20190518025947822619
2019-05-18 03:00:08,416 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.49556994s left)
2019-05-18 03:00:17,982 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518030017970808
2019-05-18 03:00:18,011 [salt.minion      :1432][INFO    ][15458] Starting a new job with PID 15458
2019-05-18 03:00:18,056 [salt.minion      :1711][INFO    ][15458] Returning information for job: 20190518030017970808
2019-05-18 03:00:41,242 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.66942501s left)
2019-05-18 03:00:48,115 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518030048098455
2019-05-18 03:00:48,151 [salt.minion      :1432][INFO    ][15523] Starting a new job with PID 15523
2019-05-18 03:00:48,185 [salt.minion      :1711][INFO    ][15523] Returning information for job: 20190518030048098455
2019-05-18 03:01:14,269 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.64249206s left)
2019-05-18 03:01:18,253 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518030118240432
2019-05-18 03:01:18,282 [salt.minion      :1432][INFO    ][15640] Starting a new job with PID 15640
2019-05-18 03:01:18,312 [salt.minion      :1711][INFO    ][15640] Returning information for job: 20190518030118240432
2019-05-18 03:01:47,111 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.80013204s left)
2019-05-18 03:01:48,392 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518030148375453
2019-05-18 03:01:48,418 [salt.minion      :1432][INFO    ][15688] Starting a new job with PID 15688
2019-05-18 03:01:48,459 [salt.minion      :1711][INFO    ][15688] Returning information for job: 20190518030148375453
2019-05-18 03:02:18,547 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518030218537468
2019-05-18 03:02:18,573 [salt.minion      :1432][INFO    ][15769] Starting a new job with PID 15769
2019-05-18 03:02:18,601 [salt.minion      :1711][INFO    ][15769] Returning information for job: 20190518030218537468
2019-05-18 03:02:20,024 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.8872509s left)
2019-05-18 03:02:48,700 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518030248681184
2019-05-18 03:02:48,728 [salt.minion      :1432][INFO    ][15818] Starting a new job with PID 15818
2019-05-18 03:02:48,765 [salt.minion      :1711][INFO    ][15818] Returning information for job: 20190518030248681184
2019-05-18 03:02:53,112 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.79944491s left)
2019-05-18 03:03:18,889 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518030318869698
2019-05-18 03:03:18,918 [salt.minion      :1432][INFO    ][15943] Starting a new job with PID 15943
2019-05-18 03:03:18,949 [salt.minion      :1711][INFO    ][15943] Returning information for job: 20190518030318869698
2019-05-18 03:03:26,039 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1753.87278295s left)
2019-05-18 03:03:49,041 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518030349027087
2019-05-18 03:03:49,068 [salt.minion      :1432][INFO    ][15990] Starting a new job with PID 15990
2019-05-18 03:03:49,102 [salt.minion      :1711][INFO    ][15990] Returning information for job: 20190518030349027087
2019-05-18 03:03:59,100 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.81186891s left)
2019-05-18 03:04:19,229 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518030419211238
2019-05-18 03:04:19,258 [salt.minion      :1432][INFO    ][16034] Starting a new job with PID 16034
2019-05-18 03:04:19,293 [salt.minion      :1711][INFO    ][16034] Returning information for job: 20190518030419211238
2019-05-18 03:04:32,070 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.84115911s left)
2019-05-18 03:04:49,408 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518030449395403
2019-05-18 03:04:49,426 [salt.minion      :1432][INFO    ][16072] Starting a new job with PID 16072
2019-05-18 03:04:49,458 [salt.minion      :1711][INFO    ][16072] Returning information for job: 20190518030449395403
2019-05-18 03:05:04,998 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1654.9141469s left)
2019-05-18 03:05:19,577 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518030519564167
2019-05-18 03:05:19,602 [salt.minion      :1432][INFO    ][16114] Starting a new job with PID 16114
2019-05-18 03:05:19,632 [salt.minion      :1711][INFO    ][16114] Returning information for job: 20190518030519564167
2019-05-18 03:05:37,789 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1622.1228621s left)
2019-05-18 03:05:49,759 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518030549746976
2019-05-18 03:05:49,786 [salt.minion      :1432][INFO    ][16150] Starting a new job with PID 16150
2019-05-18 03:05:49,813 [salt.minion      :1711][INFO    ][16150] Returning information for job: 20190518030549746976
2019-05-18 03:06:10,740 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1589.17163205s left)
2019-05-18 03:06:19,939 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518030619928714
2019-05-18 03:06:19,967 [salt.minion      :1432][INFO    ][16192] Starting a new job with PID 16192
2019-05-18 03:06:19,999 [salt.minion      :1711][INFO    ][16192] Returning information for job: 20190518030619928714
2019-05-18 03:06:43,620 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1556.29162502s left)
2019-05-18 03:06:50,137 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518030650120862
2019-05-18 03:06:50,168 [salt.minion      :1432][INFO    ][16236] Starting a new job with PID 16236
2019-05-18 03:06:50,200 [salt.minion      :1711][INFO    ][16236] Returning information for job: 20190518030650120862
2019-05-18 03:07:16,478 [salt.loaded.ext.module.maas:1023][INFO    ][14922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1523.43308592s left)
2019-05-18 03:07:20,338 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command saltutil.find_job with jid 20190518030720321575
2019-05-18 03:07:20,363 [salt.minion      :1432][INFO    ][16488] Starting a new job with PID 16488
2019-05-18 03:07:20,396 [salt.minion      :1711][INFO    ][16488] Returning information for job: 20190518030720321575
2019-05-18 03:07:48,651 [salt.utils.schedule:1377][INFO    ][5630] Running scheduled job: __mine_interval
2019-05-18 03:07:49,584 [salt.state       :300 ][INFO    ][14922] {'ret': True}
2019-05-18 03:07:49,585 [salt.state       :1951][INFO    ][14922] Completed state [maas.wait_for_machine_status] at time 03:07:49.585523 duration_in_ms=759698.404
2019-05-18 03:07:49,590 [salt.minion      :1711][INFO    ][14922] Returning information for job: 20190518025501815508
2019-05-18 04:07:48,649 [salt.utils.schedule:1377][INFO    ][5630] Running scheduled job: __mine_interval
2019-05-18 04:36:15,464 [salt.minion      :1308][INFO    ][5630] User sudo_ubuntu Executing command cp.push_dir with jid 20190518043615447495
2019-05-18 04:36:15,491 [salt.minion      :1432][INFO    ][22722] Starting a new job with PID 22722
