2019-03-28 07:12:21,818 [salt.utils.decorators:613 ][WARNING ][2059] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-28 07:12:22,799 [salt.utils.decorators:613 ][WARNING ][2059] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-28 07:12:26,057 [salt.loaded.int.states.file:2298][WARNING ][2385] 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-03-28 07:12:52,088 [salt.state       :2022][WARNING ][2837] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-28 07:12:55,126 [salt.utils.decorators:613 ][WARNING ][2837] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-28 07:13:04,914 [salt.utils.decorators:613 ][WARNING ][2837] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-28 07:13:32,538 [salt.utils.decorators:613 ][WARNING ][2837] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-28 07:13:33,396 [salt.utils.decorators:613 ][WARNING ][2837] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-28 07:13:35,543 [salt.loaded.ext.module.maasng:1008][WARNING ][2837] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-03-28 07:13:35,544 [salt.loaded.ext.module.maasng:1011][WARNING ][2837] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-03-28 07:13:36,137 [salt.loaded.ext.module.maasng:1235][WARNING ][2837] Ignoring parameter vlan:0
2019-03-28 07:13:39,661 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5168] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-28 07:13:39,682 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5168] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-03-28 07:13:39,720 [salt.utils.parsers:1051][WARNING ][383] Minion received a SIGTERM. Exiting.
2019-03-28 07:13:40,713 [salt.cli.daemons :293 ][INFO    ][5242] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-03-28 07:13:40,831 [salt.cli.daemons :82  ][INFO    ][5242] Starting up the Salt Minion
2019-03-28 07:13:40,832 [salt.utils.event :1017][INFO    ][5242] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-03-28 07:13:41,877 [salt.minion      :976 ][INFO    ][5242] Creating minion process manager
2019-03-28 07:13:43,753 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5242] Executing command ['date', '+%z'] in directory '/root'
2019-03-28 07:13:43,772 [salt.utils.schedule:568 ][INFO    ][5242] Updating job settings for scheduled job: __mine_interval
2019-03-28 07:13:43,774 [salt.minion      :1108][INFO    ][5242] Added mine.update to scheduler
2019-03-28 07:13:43,780 [salt.minion      :1975][INFO    ][5242] Minion is starting as user 'root'
2019-03-28 07:13:43,793 [salt.minion      :2336][INFO    ][5242] Minion is ready to receive requests!
2019-03-28 07:13:45,977 [salt.utils.decorators:613 ][WARNING ][5176] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-28 07:13:46,031 [salt.loaded.ext.module.maas:412 ][WARNING ][5176] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-28 07:13:47,390 [salt.loaded.ext.module.maas:412 ][WARNING ][5176] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-28 07:13:48,572 [salt.loaded.ext.module.maas:412 ][WARNING ][5176] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-28 07:13:49,646 [salt.loaded.ext.module.maas:412 ][WARNING ][5176] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-28 07:13:50,745 [salt.loaded.ext.module.maas:412 ][WARNING ][5176] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-28 07:14:22,867 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command state.apply with jid 20190328071422856226
2019-03-28 07:14:22,884 [salt.minion      :1432][INFO    ][5682] Starting a new job with PID 5682
2019-03-28 07:14:28,736 [salt.state       :915 ][INFO    ][5682] Loading fresh modules for state activity
2019-03-28 07:14:28,790 [salt.fileclient  :1219][INFO    ][5682] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-03-28 07:14:28,836 [salt.state       :1780][INFO    ][5682] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:14:28.836220
2019-03-28 07:14:28,836 [salt.state       :1813][INFO    ][5682] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-28 07:14:28,838 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5682] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-28 07:14:30,702 [salt.state       :300 ][INFO    ][5682] {'pid': 5694, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-28 07:14:30,702 [salt.state       :1951][INFO    ][5682] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:14:30.702743 duration_in_ms=1866.523
2019-03-28 07:14:30,704 [salt.state       :1780][INFO    ][5682] Running state [maas.wait_for_machine_status] at time 07:14:30.704876
2019-03-28 07:14:30,705 [salt.state       :1813][INFO    ][5682] Executing state module.run for [maas.wait_for_machine_status]
2019-03-28 07:14:30,705 [salt.utils.decorators:613 ][WARNING ][5682] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-28 07:14:31,496 [salt.loaded.ext.module.maas:1023][INFO    ][5682] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.21778703s left)
2019-03-28 07:14:37,936 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328071437922134
2019-03-28 07:14:37,954 [salt.minion      :1432][INFO    ][5706] Starting a new job with PID 5706
2019-03-28 07:14:37,976 [salt.minion      :1711][INFO    ][5706] Returning information for job: 20190328071437922134
2019-03-28 07:15:02,160 [salt.loaded.ext.module.maas:1023][INFO    ][5682] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.55296898s left)
2019-03-28 07:15:08,124 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328071508115789
2019-03-28 07:15:08,147 [salt.minion      :1432][INFO    ][5781] Starting a new job with PID 5781
2019-03-28 07:15:08,169 [salt.minion      :1711][INFO    ][5781] Returning information for job: 20190328071508115789
2019-03-28 07:15:32,952 [salt.loaded.ext.module.maas:1023][INFO    ][5682] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.76157212s left)
2019-03-28 07:15:38,317 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328071538310173
2019-03-28 07:15:38,337 [salt.minion      :1432][INFO    ][5799] Starting a new job with PID 5799
2019-03-28 07:15:38,358 [salt.minion      :1711][INFO    ][5799] Returning information for job: 20190328071538310173
2019-03-28 07:16:03,601 [salt.loaded.ext.module.maas:1023][INFO    ][5682] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1407.11251903s left)
2019-03-28 07:16:08,509 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328071608500917
2019-03-28 07:16:08,530 [salt.minion      :1432][INFO    ][5878] Starting a new job with PID 5878
2019-03-28 07:16:08,550 [salt.minion      :1711][INFO    ][5878] Returning information for job: 20190328071608500917
2019-03-28 07:16:34,744 [salt.loaded.ext.module.maas:1023][INFO    ][5682] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.96977401s left)
2019-03-28 07:16:38,700 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328071638691850
2019-03-28 07:16:38,722 [salt.minion      :1432][INFO    ][5921] Starting a new job with PID 5921
2019-03-28 07:16:38,742 [salt.minion      :1711][INFO    ][5921] Returning information for job: 20190328071638691850
2019-03-28 07:17:05,618 [salt.loaded.ext.module.maas:1023][INFO    ][5682] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1345.09564805s left)
2019-03-28 07:17:08,914 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328071708905302
2019-03-28 07:17:08,928 [salt.minion      :1432][INFO    ][6098] Starting a new job with PID 6098
2019-03-28 07:17:08,950 [salt.minion      :1711][INFO    ][6098] Returning information for job: 20190328071708905302
2019-03-28 07:17:36,770 [salt.loaded.ext.module.maas:1023][INFO    ][5682] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.94325805s left)
2019-03-28 07:17:39,106 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328071739096222
2019-03-28 07:17:39,127 [salt.minion      :1432][INFO    ][6171] Starting a new job with PID 6171
2019-03-28 07:17:39,149 [salt.minion      :1711][INFO    ][6171] Returning information for job: 20190328071739096222
2019-03-28 07:18:07,980 [salt.loaded.ext.module.maas:1023][INFO    ][5682] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.73308897s left)
2019-03-28 07:18:09,134 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328071809126732
2019-03-28 07:18:09,149 [salt.minion      :1432][INFO    ][6540] Starting a new job with PID 6540
2019-03-28 07:18:09,167 [salt.minion      :1711][INFO    ][6540] Returning information for job: 20190328071809126732
2019-03-28 07:18:39,165 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328071839153918
2019-03-28 07:18:39,181 [salt.minion      :1432][INFO    ][6629] Starting a new job with PID 6629
2019-03-28 07:18:39,204 [salt.minion      :1711][INFO    ][6629] Returning information for job: 20190328071839153918
2019-03-28 07:18:39,886 [salt.loaded.ext.module.maas:1023][INFO    ][5682] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.82735801s left)
2019-03-28 07:19:09,193 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328071909172413
2019-03-28 07:19:09,213 [salt.minion      :1432][INFO    ][7034] Starting a new job with PID 7034
2019-03-28 07:19:09,236 [salt.minion      :1711][INFO    ][7034] Returning information for job: 20190328071909172413
2019-03-28 07:19:11,854 [salt.loaded.ext.module.maas:1023][INFO    ][5682] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.85900307s left)
2019-03-28 07:19:39,398 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328071939389771
2019-03-28 07:19:39,418 [salt.minion      :1432][INFO    ][7178] Starting a new job with PID 7178
2019-03-28 07:19:39,439 [salt.minion      :1711][INFO    ][7178] Returning information for job: 20190328071939389771
2019-03-28 07:19:44,169 [salt.loaded.ext.module.maas:1023][INFO    ][5682] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1186.54423118s left)
2019-03-28 07:20:09,510 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328072009502317
2019-03-28 07:20:09,530 [salt.minion      :1432][INFO    ][7403] Starting a new job with PID 7403
2019-03-28 07:20:09,556 [salt.minion      :1711][INFO    ][7403] Returning information for job: 20190328072009502317
2019-03-28 07:20:16,242 [salt.loaded.ext.module.maas:1023][INFO    ][5682] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1154.47145009s left)
2019-03-28 07:20:39,614 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328072039600124
2019-03-28 07:20:39,635 [salt.minion      :1432][INFO    ][7533] Starting a new job with PID 7533
2019-03-28 07:20:39,656 [salt.minion      :1711][INFO    ][7533] Returning information for job: 20190328072039600124
2019-03-28 07:20:49,001 [salt.state       :300 ][INFO    ][5682] {'ret': True}
2019-03-28 07:20:49,001 [salt.state       :1951][INFO    ][5682] Completed state [maas.wait_for_machine_status] at time 07:20:49.001500 duration_in_ms=378296.623
2019-03-28 07:20:49,003 [salt.minion      :1711][INFO    ][5682] Returning information for job: 20190328071422856226
2019-03-28 07:20:49,581 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command state.apply with jid 20190328072049570596
2019-03-28 07:20:49,601 [salt.minion      :1432][INFO    ][7567] Starting a new job with PID 7567
2019-03-28 07:20:55,821 [salt.state       :915 ][INFO    ][7567] Loading fresh modules for state activity
2019-03-28 07:20:55,874 [salt.fileclient  :1219][INFO    ][7567] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-03-28 07:20:55,968 [salt.state       :1780][INFO    ][7567] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:20:55.968276
2019-03-28 07:20:55,968 [salt.state       :1813][INFO    ][7567] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-28 07:20:55,970 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7567] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-28 07:20:57,847 [salt.state       :300 ][INFO    ][7567] {'pid': 7657, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-28 07:20:57,848 [salt.state       :1951][INFO    ][7567] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:20:57.848079 duration_in_ms=1879.804
2019-03-28 07:20:57,849 [salt.state       :1780][INFO    ][7567] Running state [maas_machines_storage_cmp002_lvm] at time 07:20:57.849703
2019-03-28 07:20:57,850 [salt.state       :1813][INFO    ][7567] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-28 07:20:58,987 [salt.loaded.ext.module.maasng:610 ][INFO    ][7567] sg4ya8
2019-03-28 07:20:58,987 [salt.loaded.ext.module.maasng:626 ][INFO    ][7567] sda
2019-03-28 07:20:59,545 [salt.loaded.ext.module.maasng:361 ][INFO    ][7567] sg4ya8
2019-03-28 07:20:59,632 [salt.loaded.ext.module.maasng:367 ][INFO    ][7567] [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'2a740c2d-7a85-4d13-964f-f1670391931f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sg4ya8', u'filesystem': {u'uuid': u'4ed362a3-1175-4ceb-8be8-39a279451dc6', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/sg4ya8/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'device_id': 1}], u'filesystem': None, u'uuid': None, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'sg4ya8', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/sg4ya8/blockdevices/1/', u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'5a652eaf-2a85-4e69-a646-c43e1d066374', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'9b74aafb-d52a-41e3-acd2-b01708df5b1c', u'id': 3, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sg4ya8', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sg4ya8/blockdevices/3/', u'used_size': 800097042432, u'model': None, u'size': 800097042432}]
2019-03-28 07:20:59,633 [salt.loaded.ext.module.maasng:632 ][INFO    ][7567] vgroot
2019-03-28 07:20:59,633 [salt.loaded.ext.module.maasng:635 ][INFO    ][7567] lvroot
2019-03-28 07:20:59,633 [salt.loaded.ext.module.maasng:639 ][INFO    ][7567] 107374182400
2019-03-28 07:21:00,226 [salt.loaded.ext.module.maasng:645 ][INFO    ][7567] {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'status_action': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'cpu_count': 40, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fq6qgd', u'fabric': u'pxe_admin', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fq6qgd', u'fabric': u'pxe_admin', 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'enabled': True, u'effective_mtu': 1500, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fq6qgd', u'fabric': u'pxe_admin', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'params': u'', u'system_id': u'sg4ya8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sg4ya8/interfaces/4/'}, u'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sg4ya8/blockdevices/1/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'86047069-08c3-4521-bfac-8e4b1f18cf35', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sg4ya8', u'device_id': 1, u'filesystem': {u'uuid': u'1421a7ff-5094-4ee7-a98a-d04649187614', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/sg4ya8/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'sg4ya8', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'tags': [u'ssd'], u'name': u'sda'}, u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/sg4ya8/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'sg4ya8', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'uuid': u'd23e08e5-0716-4e5a-8241-f964af2c9e07', u'resource_uri': u'/MAAS/api/2.0/nodes/sg4ya8/blockdevices/11/', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'ff2c77f2-fd55-4ead-9c7b-a2020d528263', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sg4ya8', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'tags': [], u'name': u'vgroot-lvroot'}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'available_size': 0, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/sg4ya8/blockdevices/1/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'86047069-08c3-4521-bfac-8e4b1f18cf35', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sg4ya8', u'device_id': 1, u'filesystem': {u'uuid': u'1421a7ff-5094-4ee7-a98a-d04649187614', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/sg4ya8/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'sg4ya8', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'available_size': 0, u'uuid': u'd23e08e5-0716-4e5a-8241-f964af2c9e07', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/sg4ya8/blockdevices/11/', u'used_size': 107374182400, u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sg4ya8', u'partition_table_type': None, u'filesystem': {u'uuid': u'ff2c77f2-fd55-4ead-9c7b-a2020d528263', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sg4ya8/blockdevices/1/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'86047069-08c3-4521-bfac-8e4b1f18cf35', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sg4ya8', u'device_id': 1, u'filesystem': {u'uuid': u'1421a7ff-5094-4ee7-a98a-d04649187614', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/sg4ya8/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'sg4ya8', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'tags': [u'ssd'], u'name': u'sda'}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'sg4ya8', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'commissioning_status_name': u'Passed', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'architecture': u'amd64/generic', 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': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fq6qgd', u'fabric': u'pxe_admin', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fq6qgd', u'fabric': u'pxe_admin', 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'enabled': True, u'effective_mtu': 1500, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fq6qgd', u'fabric': u'pxe_admin', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'params': u'', u'system_id': u'sg4ya8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sg4ya8/interfaces/4/'}, {u'name': u'ens2f0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 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'effective_mtu': 1500, u'id': 13, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'params': u'', u'system_id': u'sg4ya8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sg4ya8/interfaces/13/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fq6qgd', u'fabric': u'pxe_admin', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fq6qgd', u'fabric': u'pxe_admin', 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'enabled': True, u'effective_mtu': 1500, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fq6qgd', u'fabric': u'pxe_admin', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'params': u'', u'system_id': u'sg4ya8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sg4ya8/interfaces/14/'}, {u'name': u'ens2f1', u'links': [{u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'effective_mtu': 1500, u'id': 15, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'params': u'', u'system_id': u'sg4ya8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sg4ya8/interfaces/15/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 11, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'params': u'', u'system_id': u'sg4ya8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sg4ya8/interfaces/11/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 12, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'params': u'', u'system_id': u'sg4ya8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/sg4ya8/interfaces/12/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-03-28 07:21:00,229 [salt.state       :300 ][INFO    ][7567] {'new': {'storage_layout': 'lvm'}}
2019-03-28 07:21:00,230 [salt.state       :1951][INFO    ][7567] Completed state [maas_machines_storage_cmp002_lvm] at time 07:21:00.230158 duration_in_ms=2380.453
2019-03-28 07:21:00,232 [salt.state       :1780][INFO    ][7567] Running state [maas_machines_storage_cmp001_lvm] at time 07:21:00.230809
2019-03-28 07:21:00,232 [salt.state       :1813][INFO    ][7567] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-28 07:21:01,325 [salt.loaded.ext.module.maasng:610 ][INFO    ][7567] et7nb4
2019-03-28 07:21:01,325 [salt.loaded.ext.module.maasng:626 ][INFO    ][7567] sda
2019-03-28 07:21:01,870 [salt.loaded.ext.module.maasng:361 ][INFO    ][7567] et7nb4
2019-03-28 07:21:01,942 [salt.loaded.ext.module.maasng:367 ][INFO    ][7567] [{u'model': u'LOGICAL VOLUME', u'available_size': 0, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/et7nb4/blockdevices/2/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'5dfbbca0-1f1a-4d11-8c3e-2c47b689e786', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'et7nb4', u'device_id': 2, u'filesystem': {u'uuid': u'45f4ce2b-0096-4f49-b2ff-97a53070648e', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/et7nb4/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'size': 800101236736}], u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'et7nb4', 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'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'size': 800109715456}, {u'model': None, u'available_size': 0, u'uuid': u'2225a4fc-3ef9-4aa6-afad-27c4acb2d4eb', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/et7nb4/blockdevices/4/', u'used_size': 800097042432, u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'et7nb4', u'partition_table_type': None, u'filesystem': {u'uuid': u'c30117d8-ba27-41dc-a6e6-08e4cdbf6347', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 4, u'size': 800097042432}]
2019-03-28 07:21:01,943 [salt.loaded.ext.module.maasng:632 ][INFO    ][7567] vgroot
2019-03-28 07:21:01,943 [salt.loaded.ext.module.maasng:635 ][INFO    ][7567] lvroot
2019-03-28 07:21:01,943 [salt.loaded.ext.module.maasng:639 ][INFO    ][7567] 107374182400
2019-03-28 07:21:02,476 [salt.loaded.ext.module.maasng:645 ][INFO    ][7567] {u'hwe_kernel': u'', u'status_action': u'', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'cpu_count': 40, 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'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fq6qgd', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fq6qgd', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fq6qgd', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'et7nb4', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/et7nb4/interfaces/5/'}, u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/et7nb4/blockdevices/2/', u'used_size': 800106479616, u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'et7nb4', 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'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'36090ca0-f5af-4cff-a2d9-ae17ec6c7401', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'et7nb4', u'device_id': 2, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'3d88dadf-ae36-4651-b6e1-33d2820dfd2e', 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/et7nb4/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/et7nb4/blockdevices/2/', u'used_size': 800106479616, u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'et7nb4', 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'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'36090ca0-f5af-4cff-a2d9-ae17ec6c7401', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'et7nb4', u'device_id': 2, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'3d88dadf-ae36-4651-b6e1-33d2820dfd2e', 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/et7nb4/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}, {u'size': 107374182400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/et7nb4/blockdevices/12/', u'used_size': 107374182400, u'tags': [], u'uuid': u'93e5285d-7404-45bb-932e-721324053340', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'et7nb4', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'894e4201-fb9b-4c9a-a124-6b546be3c49b', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'partitions': []}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'et7nb4', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/et7nb4/blockdevices/2/', u'used_size': 800106479616, u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'et7nb4', 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'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'36090ca0-f5af-4cff-a2d9-ae17ec6c7401', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'et7nb4', u'device_id': 2, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'3d88dadf-ae36-4651-b6e1-33d2820dfd2e', 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/et7nb4/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', 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'et7nb4', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', 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'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/et7nb4/blockdevices/12/', u'used_size': 107374182400, u'tags': [], u'uuid': u'93e5285d-7404-45bb-932e-721324053340', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'et7nb4', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'894e4201-fb9b-4c9a-a124-6b546be3c49b', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fq6qgd', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fq6qgd', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fq6qgd', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'et7nb4', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/et7nb4/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 17, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'et7nb4', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/et7nb4/interfaces/17/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 20, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'et7nb4', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/et7nb4/interfaces/20/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 16, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'et7nb4', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/et7nb4/interfaces/16/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fq6qgd', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fq6qgd', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 18, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fq6qgd', u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.44'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'et7nb4', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/et7nb4/interfaces/18/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 19, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'et7nb4', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/et7nb4/interfaces/19/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/et7nb4/', u'distro_series': u'', u'memory_test_status': -1}
2019-03-28 07:21:02,477 [salt.state       :300 ][INFO    ][7567] {'new': {'storage_layout': 'lvm'}}
2019-03-28 07:21:02,477 [salt.state       :1951][INFO    ][7567] Completed state [maas_machines_storage_cmp001_lvm] at time 07:21:02.477685 duration_in_ms=2246.875
2019-03-28 07:21:02,480 [salt.minion      :1711][INFO    ][7567] Returning information for job: 20190328072049570596
2019-03-28 07:21:03,030 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command state.apply with jid 20190328072103014403
2019-03-28 07:21:03,048 [salt.minion      :1432][INFO    ][7697] Starting a new job with PID 7697
2019-03-28 07:21:04,034 [salt.state       :915 ][INFO    ][7697] Loading fresh modules for state activity
2019-03-28 07:21:04,093 [salt.fileclient  :1219][INFO    ][7697] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-03-28 07:21:04,149 [salt.state       :1780][INFO    ][7697] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:21:04.149247
2019-03-28 07:21:04,149 [salt.state       :1813][INFO    ][7697] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-28 07:21:04,151 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7697] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-28 07:21:05,991 [salt.state       :300 ][INFO    ][7697] {'pid': 7704, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-28 07:21:05,992 [salt.state       :1951][INFO    ][7697] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:21:05.992462 duration_in_ms=1843.215
2019-03-28 07:21:05,995 [salt.state       :1780][INFO    ][7697] Running state [maas.deploy_machines] at time 07:21:05.995828
2019-03-28 07:21:05,996 [salt.state       :1813][INFO    ][7697] Executing state module.run for [maas.deploy_machines]
2019-03-28 07:21:05,997 [salt.utils.decorators:613 ][WARNING ][7697] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-28 07:21:06,673 [salt.loaded.ext.module.maas:684 ][INFO    ][7697] deploymachines hwe_kernel=hwe-16.04 system_id=sg4ya8 distro_series=xenial
2019-03-28 07:21:08,939 [salt.loaded.ext.module.maas:684 ][INFO    ][7697] deploymachines hwe_kernel=hwe-16.04 system_id=et7nb4 distro_series=xenial
2019-03-28 07:21:11,169 [salt.loaded.ext.module.maas:684 ][INFO    ][7697] deploymachines hwe_kernel=hwe-16.04 system_id=wy8byb distro_series=xenial
2019-03-28 07:21:13,626 [salt.loaded.ext.module.maas:684 ][INFO    ][7697] deploymachines hwe_kernel=hwe-16.04 system_id=p3b88p distro_series=xenial
2019-03-28 07:21:16,031 [salt.loaded.ext.module.maas:684 ][INFO    ][7697] deploymachines hwe_kernel=hwe-16.04 system_id=yy7cx8 distro_series=xenial
2019-03-28 07:21:18,067 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328072118059311
2019-03-28 07:21:18,084 [salt.minion      :1432][INFO    ][7968] Starting a new job with PID 7968
2019-03-28 07:21:18,106 [salt.minion      :1711][INFO    ][7968] Returning information for job: 20190328072118059311
2019-03-28 07:21:18,408 [salt.state       :300 ][INFO    ][7697] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-28 07:21:18,409 [salt.state       :1951][INFO    ][7697] Completed state [maas.deploy_machines] at time 07:21:18.409215 duration_in_ms=12413.387
2019-03-28 07:21:18,414 [salt.minion      :1711][INFO    ][7697] Returning information for job: 20190328072103014403
2019-03-28 07:21:18,978 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command state.apply with jid 20190328072118965169
2019-03-28 07:21:18,993 [salt.minion      :1432][INFO    ][7984] Starting a new job with PID 7984
2019-03-28 07:21:24,903 [salt.state       :915 ][INFO    ][7984] Loading fresh modules for state activity
2019-03-28 07:21:24,960 [salt.fileclient  :1219][INFO    ][7984] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-03-28 07:21:25,005 [salt.state       :1780][INFO    ][7984] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:21:25.005621
2019-03-28 07:21:25,005 [salt.state       :1813][INFO    ][7984] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-28 07:21:25,007 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7984] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-28 07:21:26,840 [salt.state       :300 ][INFO    ][7984] {'pid': 8003, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-28 07:21:26,841 [salt.state       :1951][INFO    ][7984] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:21:26.841090 duration_in_ms=1835.47
2019-03-28 07:21:26,842 [salt.state       :1780][INFO    ][7984] Running state [maas.wait_for_machine_status] at time 07:21:26.842707
2019-03-28 07:21:26,843 [salt.state       :1813][INFO    ][7984] Executing state module.run for [maas.wait_for_machine_status]
2019-03-28 07:21:26,843 [salt.utils.decorators:613 ][WARNING ][7984] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-28 07:21:29,218 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.63389611s left)
2019-03-28 07:21:34,073 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328072134053307
2019-03-28 07:21:34,098 [salt.minion      :1432][INFO    ][8017] Starting a new job with PID 8017
2019-03-28 07:21:34,118 [salt.minion      :1711][INFO    ][8017] Returning information for job: 20190328072134053307
2019-03-28 07:22:02,206 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.64570498s left)
2019-03-28 07:22:04,279 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328072204262602
2019-03-28 07:22:04,296 [salt.minion      :1432][INFO    ][8109] Starting a new job with PID 8109
2019-03-28 07:22:04,319 [salt.minion      :1711][INFO    ][8109] Returning information for job: 20190328072204262602
2019-03-28 07:22:34,478 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328072234470483
2019-03-28 07:22:34,494 [salt.minion      :1432][INFO    ][8130] Starting a new job with PID 8130
2019-03-28 07:22:34,512 [salt.minion      :1711][INFO    ][8130] Returning information for job: 20190328072234470483
2019-03-28 07:22:34,664 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2182.18811607s left)
2019-03-28 07:23:04,657 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328072304649886
2019-03-28 07:23:04,674 [salt.minion      :1432][INFO    ][8220] Starting a new job with PID 8220
2019-03-28 07:23:04,693 [salt.minion      :1711][INFO    ][8220] Returning information for job: 20190328072304649886
2019-03-28 07:23:07,722 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2149.13007712s left)
2019-03-28 07:23:34,715 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328072334708403
2019-03-28 07:23:34,735 [salt.minion      :1432][INFO    ][8244] Starting a new job with PID 8244
2019-03-28 07:23:34,757 [salt.minion      :1711][INFO    ][8244] Returning information for job: 20190328072334708403
2019-03-28 07:23:40,194 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2116.65727496s left)
2019-03-28 07:24:04,757 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328072404743893
2019-03-28 07:24:04,775 [salt.minion      :1432][INFO    ][8344] Starting a new job with PID 8344
2019-03-28 07:24:04,796 [salt.minion      :1711][INFO    ][8344] Returning information for job: 20190328072404743893
2019-03-28 07:24:12,864 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.98751211s left)
2019-03-28 07:24:34,948 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328072434939254
2019-03-28 07:24:34,968 [salt.minion      :1432][INFO    ][8412] Starting a new job with PID 8412
2019-03-28 07:24:34,989 [salt.minion      :1711][INFO    ][8412] Returning information for job: 20190328072434939254
2019-03-28 07:24:45,473 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2051.37831712s left)
2019-03-28 07:25:04,997 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328072504989447
2019-03-28 07:25:05,016 [salt.minion      :1432][INFO    ][8736] Starting a new job with PID 8736
2019-03-28 07:25:05,042 [salt.minion      :1711][INFO    ][8736] Returning information for job: 20190328072504989447
2019-03-28 07:25:18,270 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2018.58193803s left)
2019-03-28 07:25:35,197 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328072535190809
2019-03-28 07:25:35,209 [salt.minion      :1432][INFO    ][8797] Starting a new job with PID 8797
2019-03-28 07:25:35,230 [salt.minion      :1711][INFO    ][8797] Returning information for job: 20190328072535190809
2019-03-28 07:25:50,865 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1985.98664999s left)
2019-03-28 07:26:05,213 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328072605203436
2019-03-28 07:26:05,233 [salt.minion      :1432][INFO    ][9052] Starting a new job with PID 9052
2019-03-28 07:26:05,254 [salt.minion      :1711][INFO    ][9052] Returning information for job: 20190328072605203436
2019-03-28 07:26:23,861 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1952.99110603s left)
2019-03-28 07:26:35,421 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328072635407150
2019-03-28 07:26:35,445 [salt.minion      :1432][INFO    ][9120] Starting a new job with PID 9120
2019-03-28 07:26:35,467 [salt.minion      :1711][INFO    ][9120] Returning information for job: 20190328072635407150
2019-03-28 07:26:58,426 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1918.42611718s left)
2019-03-28 07:27:05,525 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328072705509295
2019-03-28 07:27:05,550 [salt.minion      :1432][INFO    ][9682] Starting a new job with PID 9682
2019-03-28 07:27:05,572 [salt.minion      :1711][INFO    ][9682] Returning information for job: 20190328072705509295
2019-03-28 07:27:31,200 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1885.65213919s left)
2019-03-28 07:27:35,735 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328072735727577
2019-03-28 07:27:35,756 [salt.minion      :1432][INFO    ][9705] Starting a new job with PID 9705
2019-03-28 07:27:35,777 [salt.minion      :1711][INFO    ][9705] Returning information for job: 20190328072735727577
2019-03-28 07:28:03,934 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1852.91772509s left)
2019-03-28 07:28:05,881 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328072805873837
2019-03-28 07:28:05,893 [salt.minion      :1432][INFO    ][10080] Starting a new job with PID 10080
2019-03-28 07:28:05,915 [salt.minion      :1711][INFO    ][10080] Returning information for job: 20190328072805873837
2019-03-28 07:28:35,990 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328072835982130
2019-03-28 07:28:36,008 [salt.minion      :1432][INFO    ][10105] Starting a new job with PID 10105
2019-03-28 07:28:36,032 [salt.minion      :1711][INFO    ][10105] Returning information for job: 20190328072835982130
2019-03-28 07:28:36,734 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1820.11810613s left)
2019-03-28 07:29:06,121 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328072906102581
2019-03-28 07:29:06,145 [salt.minion      :1432][INFO    ][10434] Starting a new job with PID 10434
2019-03-28 07:29:06,166 [salt.minion      :1711][INFO    ][10434] Returning information for job: 20190328072906102581
2019-03-28 07:29:09,470 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1787.38210297s left)
2019-03-28 07:29:36,139 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328072936130029
2019-03-28 07:29:36,154 [salt.minion      :1432][INFO    ][10487] Starting a new job with PID 10487
2019-03-28 07:29:36,172 [salt.minion      :1711][INFO    ][10487] Returning information for job: 20190328072936130029
2019-03-28 07:29:42,208 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1754.64348602s left)
2019-03-28 07:30:06,151 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328073006142358
2019-03-28 07:30:06,174 [salt.minion      :1432][INFO    ][10707] Starting a new job with PID 10707
2019-03-28 07:30:06,198 [salt.minion      :1711][INFO    ][10707] Returning information for job: 20190328073006142358
2019-03-28 07:30:14,719 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1722.132653s left)
2019-03-28 07:30:36,306 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328073036290737
2019-03-28 07:30:36,328 [salt.minion      :1432][INFO    ][10765] Starting a new job with PID 10765
2019-03-28 07:30:36,349 [salt.minion      :1711][INFO    ][10765] Returning information for job: 20190328073036290737
2019-03-28 07:30:47,706 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1689.14594507s left)
2019-03-28 07:31:06,486 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328073106473361
2019-03-28 07:31:06,511 [salt.minion      :1432][INFO    ][10963] Starting a new job with PID 10963
2019-03-28 07:31:06,533 [salt.minion      :1711][INFO    ][10963] Returning information for job: 20190328073106473361
2019-03-28 07:31:20,263 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1656.58897018s left)
2019-03-28 07:31:36,583 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328073136571399
2019-03-28 07:31:36,602 [salt.minion      :1432][INFO    ][11002] Starting a new job with PID 11002
2019-03-28 07:31:36,626 [salt.minion      :1711][INFO    ][11002] Returning information for job: 20190328073136571399
2019-03-28 07:31:53,036 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1623.81556416s left)
2019-03-28 07:32:06,715 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328073206707228
2019-03-28 07:32:06,738 [salt.minion      :1432][INFO    ][11202] Starting a new job with PID 11202
2019-03-28 07:32:06,758 [salt.minion      :1711][INFO    ][11202] Returning information for job: 20190328073206707228
2019-03-28 07:32:25,859 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1590.99299908s left)
2019-03-28 07:32:36,741 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328073236733794
2019-03-28 07:32:36,765 [salt.minion      :1432][INFO    ][11223] Starting a new job with PID 11223
2019-03-28 07:32:36,788 [salt.minion      :1711][INFO    ][11223] Returning information for job: 20190328073236733794
2019-03-28 07:32:58,628 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1558.22377515s left)
2019-03-28 07:33:06,930 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328073306916250
2019-03-28 07:33:06,954 [salt.minion      :1432][INFO    ][11449] Starting a new job with PID 11449
2019-03-28 07:33:06,978 [salt.minion      :1711][INFO    ][11449] Returning information for job: 20190328073306916250
2019-03-28 07:33:31,331 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1525.52077413s left)
2019-03-28 07:33:37,098 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328073337087989
2019-03-28 07:33:37,123 [salt.minion      :1432][INFO    ][11515] Starting a new job with PID 11515
2019-03-28 07:33:37,144 [salt.minion      :1711][INFO    ][11515] Returning information for job: 20190328073337087989
2019-03-28 07:34:04,193 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1492.65844798s left)
2019-03-28 07:34:07,204 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328073407189129
2019-03-28 07:34:07,220 [salt.minion      :1432][INFO    ][11765] Starting a new job with PID 11765
2019-03-28 07:34:07,241 [salt.minion      :1711][INFO    ][11765] Returning information for job: 20190328073407189129
2019-03-28 07:34:36,823 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1460.02933002s left)
2019-03-28 07:34:37,405 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328073437398786
2019-03-28 07:34:37,422 [salt.minion      :1432][INFO    ][11795] Starting a new job with PID 11795
2019-03-28 07:34:37,441 [salt.minion      :1711][INFO    ][11795] Returning information for job: 20190328073437398786
2019-03-28 07:35:07,620 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328073507611718
2019-03-28 07:35:07,633 [salt.minion      :1432][INFO    ][11982] Starting a new job with PID 11982
2019-03-28 07:35:07,652 [salt.minion      :1711][INFO    ][11982] Returning information for job: 20190328073507611718
2019-03-28 07:35:09,698 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1427.15381813s left)
2019-03-28 07:35:37,669 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328073537659716
2019-03-28 07:35:37,693 [salt.minion      :1432][INFO    ][12001] Starting a new job with PID 12001
2019-03-28 07:35:37,714 [salt.minion      :1711][INFO    ][12001] Returning information for job: 20190328073537659716
2019-03-28 07:35:42,262 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1394.59013605s left)
2019-03-28 07:36:07,738 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328073607729646
2019-03-28 07:36:07,759 [salt.minion      :1432][INFO    ][12080] Starting a new job with PID 12080
2019-03-28 07:36:07,781 [salt.minion      :1711][INFO    ][12080] Returning information for job: 20190328073607729646
2019-03-28 07:36:15,038 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1361.81357217s left)
2019-03-28 07:36:37,756 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328073637747876
2019-03-28 07:36:37,775 [salt.minion      :1432][INFO    ][12107] Starting a new job with PID 12107
2019-03-28 07:36:37,800 [salt.minion      :1711][INFO    ][12107] Returning information for job: 20190328073637747876
2019-03-28 07:36:47,614 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1329.23765516s left)
2019-03-28 07:37:07,782 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328073707770436
2019-03-28 07:37:07,799 [salt.minion      :1432][INFO    ][12189] Starting a new job with PID 12189
2019-03-28 07:37:07,825 [salt.minion      :1711][INFO    ][12189] Returning information for job: 20190328073707770436
2019-03-28 07:37:20,346 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1296.50575519s left)
2019-03-28 07:37:37,847 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328073737833678
2019-03-28 07:37:37,870 [salt.minion      :1432][INFO    ][12209] Starting a new job with PID 12209
2019-03-28 07:37:37,892 [salt.minion      :1711][INFO    ][12209] Returning information for job: 20190328073737833678
2019-03-28 07:37:52,762 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1264.09004617s left)
2019-03-28 07:38:07,882 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328073807872756
2019-03-28 07:38:07,904 [salt.minion      :1432][INFO    ][12290] Starting a new job with PID 12290
2019-03-28 07:38:07,928 [salt.minion      :1711][INFO    ][12290] Returning information for job: 20190328073807872756
2019-03-28 07:38:25,679 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1231.17338109s left)
2019-03-28 07:38:37,962 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328073837953730
2019-03-28 07:38:37,977 [salt.minion      :1432][INFO    ][12322] Starting a new job with PID 12322
2019-03-28 07:38:37,996 [salt.minion      :1711][INFO    ][12322] Returning information for job: 20190328073837953730
2019-03-28 07:38:58,163 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1198.68833613s left)
2019-03-28 07:39:08,016 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328073908009040
2019-03-28 07:39:08,036 [salt.minion      :1432][INFO    ][12402] Starting a new job with PID 12402
2019-03-28 07:39:08,054 [salt.minion      :1711][INFO    ][12402] Returning information for job: 20190328073908009040
2019-03-28 07:39:30,837 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1166.01476812s left)
2019-03-28 07:39:38,112 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328073938104379
2019-03-28 07:39:38,137 [salt.minion      :1432][INFO    ][12423] Starting a new job with PID 12423
2019-03-28 07:39:38,162 [salt.minion      :1711][INFO    ][12423] Returning information for job: 20190328073938104379
2019-03-28 07:40:03,486 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1133.36539102s left)
2019-03-28 07:40:08,213 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328074008200201
2019-03-28 07:40:08,231 [salt.minion      :1432][INFO    ][12508] Starting a new job with PID 12508
2019-03-28 07:40:08,251 [salt.minion      :1711][INFO    ][12508] Returning information for job: 20190328074008200201
2019-03-28 07:40:36,152 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1100.69967508s left)
2019-03-28 07:40:38,261 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328074038252907
2019-03-28 07:40:38,278 [salt.minion      :1432][INFO    ][12529] Starting a new job with PID 12529
2019-03-28 07:40:38,300 [salt.minion      :1711][INFO    ][12529] Returning information for job: 20190328074038252907
2019-03-28 07:41:08,384 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328074108376106
2019-03-28 07:41:08,398 [salt.minion      :1432][INFO    ][12610] Starting a new job with PID 12610
2019-03-28 07:41:08,422 [salt.minion      :1711][INFO    ][12610] Returning information for job: 20190328074108376106
2019-03-28 07:41:08,743 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1068.10899496s left)
2019-03-28 07:41:38,514 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328074138498821
2019-03-28 07:41:38,532 [salt.minion      :1432][INFO    ][12626] Starting a new job with PID 12626
2019-03-28 07:41:38,556 [salt.minion      :1711][INFO    ][12626] Returning information for job: 20190328074138498821
2019-03-28 07:41:41,495 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1035.35696197s left)
2019-03-28 07:42:08,686 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328074208677216
2019-03-28 07:42:08,708 [salt.minion      :1432][INFO    ][12706] Starting a new job with PID 12706
2019-03-28 07:42:08,732 [salt.minion      :1711][INFO    ][12706] Returning information for job: 20190328074208677216
2019-03-28 07:42:14,224 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1002.62751102s left)
2019-03-28 07:42:38,833 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328074238820370
2019-03-28 07:42:38,854 [salt.minion      :1432][INFO    ][12731] Starting a new job with PID 12731
2019-03-28 07:42:38,878 [salt.minion      :1711][INFO    ][12731] Returning information for job: 20190328074238820370
2019-03-28 07:42:46,975 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (969.876801014s left)
2019-03-28 07:43:09,028 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328074309018835
2019-03-28 07:43:09,048 [salt.minion      :1432][INFO    ][12834] Starting a new job with PID 12834
2019-03-28 07:43:09,074 [salt.minion      :1711][INFO    ][12834] Returning information for job: 20190328074309018835
2019-03-28 07:43:19,645 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (937.207184076s left)
2019-03-28 07:43:39,201 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328074339192225
2019-03-28 07:43:39,223 [salt.minion      :1432][INFO    ][12857] Starting a new job with PID 12857
2019-03-28 07:43:39,247 [salt.minion      :1711][INFO    ][12857] Returning information for job: 20190328074339192225
2019-03-28 07:43:52,295 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (904.557212114s left)
2019-03-28 07:44:09,416 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328074409400324
2019-03-28 07:44:09,438 [salt.minion      :1432][INFO    ][12938] Starting a new job with PID 12938
2019-03-28 07:44:09,460 [salt.minion      :1711][INFO    ][12938] Returning information for job: 20190328074409400324
2019-03-28 07:44:24,954 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (871.897736073s left)
2019-03-28 07:44:39,616 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328074439608138
2019-03-28 07:44:39,634 [salt.minion      :1432][INFO    ][12961] Starting a new job with PID 12961
2019-03-28 07:44:39,660 [salt.minion      :1711][INFO    ][12961] Returning information for job: 20190328074439608138
2019-03-28 07:44:57,790 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (839.062031984s left)
2019-03-28 07:45:09,656 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328074509644199
2019-03-28 07:45:09,674 [salt.minion      :1432][INFO    ][13041] Starting a new job with PID 13041
2019-03-28 07:45:09,696 [salt.minion      :1711][INFO    ][13041] Returning information for job: 20190328074509644199
2019-03-28 07:45:30,432 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (806.419723988s left)
2019-03-28 07:45:39,692 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328074539678283
2019-03-28 07:45:39,714 [salt.minion      :1432][INFO    ][13062] Starting a new job with PID 13062
2019-03-28 07:45:39,739 [salt.minion      :1711][INFO    ][13062] Returning information for job: 20190328074539678283
2019-03-28 07:46:03,142 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (773.709417105s left)
2019-03-28 07:46:09,794 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328074609781358
2019-03-28 07:46:09,815 [salt.minion      :1432][INFO    ][13144] Starting a new job with PID 13144
2019-03-28 07:46:09,839 [salt.minion      :1711][INFO    ][13144] Returning information for job: 20190328074609781358
2019-03-28 07:46:35,960 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (740.89154911s left)
2019-03-28 07:46:39,833 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328074639826585
2019-03-28 07:46:39,847 [salt.minion      :1432][INFO    ][13167] Starting a new job with PID 13167
2019-03-28 07:46:39,868 [salt.minion      :1711][INFO    ][13167] Returning information for job: 20190328074639826585
2019-03-28 07:47:08,665 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (708.187057972s left)
2019-03-28 07:47:09,917 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328074709909750
2019-03-28 07:47:09,933 [salt.minion      :1432][INFO    ][13250] Starting a new job with PID 13250
2019-03-28 07:47:09,956 [salt.minion      :1711][INFO    ][13250] Returning information for job: 20190328074709909750
2019-03-28 07:47:40,057 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328074740048910
2019-03-28 07:47:40,073 [salt.minion      :1432][INFO    ][13271] Starting a new job with PID 13271
2019-03-28 07:47:40,094 [salt.minion      :1711][INFO    ][13271] Returning information for job: 20190328074740048910
2019-03-28 07:47:41,310 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (675.541359186s left)
2019-03-28 07:48:10,156 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328074810147894
2019-03-28 07:48:10,178 [salt.minion      :1432][INFO    ][13350] Starting a new job with PID 13350
2019-03-28 07:48:10,205 [salt.minion      :1711][INFO    ][13350] Returning information for job: 20190328074810147894
2019-03-28 07:48:13,973 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (642.878700018s left)
2019-03-28 07:48:40,339 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328074840330361
2019-03-28 07:48:40,356 [salt.minion      :1432][INFO    ][13371] Starting a new job with PID 13371
2019-03-28 07:48:40,382 [salt.minion      :1711][INFO    ][13371] Returning information for job: 20190328074840330361
2019-03-28 07:48:46,715 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (610.136265993s left)
2019-03-28 07:49:10,459 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328074910447287
2019-03-28 07:49:10,482 [salt.minion      :1432][INFO    ][13455] Starting a new job with PID 13455
2019-03-28 07:49:10,506 [salt.minion      :1711][INFO    ][13455] Returning information for job: 20190328074910447287
2019-03-28 07:49:19,609 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (577.243098974s left)
2019-03-28 07:49:40,666 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328074940654470
2019-03-28 07:49:40,681 [salt.minion      :1432][INFO    ][13476] Starting a new job with PID 13476
2019-03-28 07:49:40,708 [salt.minion      :1711][INFO    ][13476] Returning information for job: 20190328074940654470
2019-03-28 07:49:52,271 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (544.580631018s left)
2019-03-28 07:50:10,854 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328075010839519
2019-03-28 07:50:10,871 [salt.minion      :1432][INFO    ][13556] Starting a new job with PID 13556
2019-03-28 07:50:10,897 [salt.minion      :1711][INFO    ][13556] Returning information for job: 20190328075010839519
2019-03-28 07:50:25,070 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (511.781849146s left)
2019-03-28 07:50:40,880 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328075040867349
2019-03-28 07:50:40,901 [salt.minion      :1432][INFO    ][13579] Starting a new job with PID 13579
2019-03-28 07:50:40,926 [salt.minion      :1711][INFO    ][13579] Returning information for job: 20190328075040867349
2019-03-28 07:50:57,913 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (478.939025164s left)
2019-03-28 07:51:11,109 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328075111098401
2019-03-28 07:51:11,130 [salt.minion      :1432][INFO    ][13664] Starting a new job with PID 13664
2019-03-28 07:51:11,152 [salt.minion      :1711][INFO    ][13664] Returning information for job: 20190328075111098401
2019-03-28 07:51:30,538 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (446.313785076s left)
2019-03-28 07:51:41,167 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328075141154252
2019-03-28 07:51:41,189 [salt.minion      :1432][INFO    ][13685] Starting a new job with PID 13685
2019-03-28 07:51:41,217 [salt.minion      :1711][INFO    ][13685] Returning information for job: 20190328075141154252
2019-03-28 07:52:03,167 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (413.684390068s left)
2019-03-28 07:52:11,203 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328075211194704
2019-03-28 07:52:11,218 [salt.minion      :1432][INFO    ][13767] Starting a new job with PID 13767
2019-03-28 07:52:11,241 [salt.minion      :1711][INFO    ][13767] Returning information for job: 20190328075211194704
2019-03-28 07:52:36,004 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (380.84773612s left)
2019-03-28 07:52:41,301 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328075241291033
2019-03-28 07:52:41,322 [salt.minion      :1432][INFO    ][13788] Starting a new job with PID 13788
2019-03-28 07:52:41,346 [salt.minion      :1711][INFO    ][13788] Returning information for job: 20190328075241291033
2019-03-28 07:53:08,718 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (348.133401155s left)
2019-03-28 07:53:11,381 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328075311372861
2019-03-28 07:53:11,395 [salt.minion      :1432][INFO    ][14015] Starting a new job with PID 14015
2019-03-28 07:53:11,416 [salt.minion      :1711][INFO    ][14015] Returning information for job: 20190328075311372861
2019-03-28 07:53:41,445 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (315.407098055s left)
2019-03-28 07:53:41,520 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328075341509980
2019-03-28 07:53:41,540 [salt.minion      :1432][INFO    ][14037] Starting a new job with PID 14037
2019-03-28 07:53:41,564 [salt.minion      :1711][INFO    ][14037] Returning information for job: 20190328075341509980
2019-03-28 07:54:11,702 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328075411693514
2019-03-28 07:54:11,722 [salt.minion      :1432][INFO    ][14116] Starting a new job with PID 14116
2019-03-28 07:54:11,744 [salt.minion      :1711][INFO    ][14116] Returning information for job: 20190328075411693514
2019-03-28 07:54:14,144 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (282.707477093s left)
2019-03-28 07:54:41,860 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328075441852270
2019-03-28 07:54:41,878 [salt.minion      :1432][INFO    ][14138] Starting a new job with PID 14138
2019-03-28 07:54:41,903 [salt.minion      :1711][INFO    ][14138] Returning information for job: 20190328075441852270
2019-03-28 07:54:46,886 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (249.965842962s left)
2019-03-28 07:55:12,077 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328075512064050
2019-03-28 07:55:12,095 [salt.minion      :1432][INFO    ][14225] Starting a new job with PID 14225
2019-03-28 07:55:12,121 [salt.minion      :1711][INFO    ][14225] Returning information for job: 20190328075512064050
2019-03-28 07:55:19,480 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (217.371297121s left)
2019-03-28 07:55:42,254 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328075542240271
2019-03-28 07:55:42,272 [salt.minion      :1432][INFO    ][14256] Starting a new job with PID 14256
2019-03-28 07:55:42,295 [salt.minion      :1711][INFO    ][14256] Returning information for job: 20190328075542240271
2019-03-28 07:55:52,283 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (184.568504095s left)
2019-03-28 07:56:12,294 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328075612280710
2019-03-28 07:56:12,311 [salt.minion      :1432][INFO    ][14331] Starting a new job with PID 14331
2019-03-28 07:56:12,331 [salt.minion      :1711][INFO    ][14331] Returning information for job: 20190328075612280710
2019-03-28 07:56:24,860 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (151.991707087s left)
2019-03-28 07:56:42,470 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328075642456520
2019-03-28 07:56:42,488 [salt.minion      :1432][INFO    ][14360] Starting a new job with PID 14360
2019-03-28 07:56:42,511 [salt.minion      :1711][INFO    ][14360] Returning information for job: 20190328075642456520
2019-03-28 07:56:57,791 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (119.061151028s left)
2019-03-28 07:57:12,558 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328075712543592
2019-03-28 07:57:12,576 [salt.minion      :1432][INFO    ][14433] Starting a new job with PID 14433
2019-03-28 07:57:12,603 [salt.minion      :1711][INFO    ][14433] Returning information for job: 20190328075712543592
2019-03-28 07:57:30,402 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (86.450097084s left)
2019-03-28 07:57:42,606 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328075742591987
2019-03-28 07:57:42,623 [salt.minion      :1432][INFO    ][14462] Starting a new job with PID 14462
2019-03-28 07:57:42,646 [salt.minion      :1711][INFO    ][14462] Returning information for job: 20190328075742591987
2019-03-28 07:58:03,234 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (53.6181750298s left)
2019-03-28 07:58:12,745 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328075812730048
2019-03-28 07:58:12,766 [salt.minion      :1432][INFO    ][14539] Starting a new job with PID 14539
2019-03-28 07:58:12,790 [salt.minion      :1711][INFO    ][14539] Returning information for job: 20190328075812730048
2019-03-28 07:58:35,973 [salt.loaded.ext.module.maas:1023][INFO    ][7984] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (20.8790559769s left)
2019-03-28 07:58:42,825 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328075842814586
2019-03-28 07:58:42,847 [salt.minion      :1432][INFO    ][14570] Starting a new job with PID 14570
2019-03-28 07:58:42,870 [salt.minion      :1711][INFO    ][14570] Returning information for job: 20190328075842814586
2019-03-28 07:59:08,729 [salt.state       :302 ][ERROR   ][7984] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-03-28 07:59:08,730 [salt.state       :1951][INFO    ][7984] Completed state [maas.wait_for_machine_status] at time 07:59:08.729659 duration_in_ms=2261886.948
2019-03-28 07:59:08,736 [salt.minion      :1711][INFO    ][7984] Returning information for job: 20190328072118965169
2019-03-28 07:59:19,641 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command pillar.get with jid 20190328075919629749
2019-03-28 07:59:19,663 [salt.minion      :1432][INFO    ][14644] Starting a new job with PID 14644
2019-03-28 07:59:19,669 [salt.minion      :1711][INFO    ][14644] Returning information for job: 20190328075919629749
2019-03-28 07:59:20,209 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command service.status with jid 20190328075920201512
2019-03-28 07:59:20,228 [salt.minion      :1432][INFO    ][14649] Starting a new job with PID 14649
2019-03-28 07:59:20,821 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14649] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-03-28 07:59:20,857 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14649] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-03-28 07:59:20,875 [salt.minion      :1711][INFO    ][14649] Returning information for job: 20190328075920201512
2019-03-28 07:59:21,414 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command state.apply with jid 20190328075921398776
2019-03-28 07:59:21,431 [salt.minion      :1432][INFO    ][14660] Starting a new job with PID 14660
2019-03-28 07:59:27,317 [salt.state       :915 ][INFO    ][14660] Loading fresh modules for state activity
2019-03-28 07:59:27,900 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14660] Executing command 'salt-minion --version' in directory '/root'
2019-03-28 07:59:28,208 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14660] Executing command 'salt-minion --version' in directory '/root'
2019-03-28 07:59:29,197 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14660] Executing command 'salt-minion --version' in directory '/root'
2019-03-28 07:59:29,502 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14660] Executing command 'salt-minion --version' in directory '/root'
2019-03-28 07:59:31,319 [salt.state       :1780][INFO    ][14660] Running state [salt-minion] at time 07:59:31.319345
2019-03-28 07:59:31,319 [salt.state       :1813][INFO    ][14660] Executing state pkg.installed for [salt-minion]
2019-03-28 07:59:31,320 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14660] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-28 07:59:31,433 [salt.state       :300 ][INFO    ][14660] All specified packages are already installed
2019-03-28 07:59:31,434 [salt.state       :1951][INFO    ][14660] Completed state [salt-minion] at time 07:59:31.434174 duration_in_ms=114.828
2019-03-28 07:59:31,434 [salt.state       :1780][INFO    ][14660] Running state [salt_minion_dependency_packages] at time 07:59:31.434494
2019-03-28 07:59:31,434 [salt.state       :1813][INFO    ][14660] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-03-28 07:59:31,445 [salt.state       :300 ][INFO    ][14660] All specified packages are already installed
2019-03-28 07:59:31,445 [salt.state       :1951][INFO    ][14660] Completed state [salt_minion_dependency_packages] at time 07:59:31.445238 duration_in_ms=10.744
2019-03-28 07:59:31,451 [salt.state       :1780][INFO    ][14660] Running state [/etc/salt/minion.d/minion.conf] at time 07:59:31.451753
2019-03-28 07:59:31,452 [salt.state       :1813][INFO    ][14660] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-03-28 07:59:31,736 [salt.state       :300 ][INFO    ][14660] File /etc/salt/minion.d/minion.conf is in the correct state
2019-03-28 07:59:31,736 [salt.state       :1951][INFO    ][14660] Completed state [/etc/salt/minion.d/minion.conf] at time 07:59:31.736327 duration_in_ms=284.576
2019-03-28 07:59:31,736 [salt.state       :1780][INFO    ][14660] Running state [python-netaddr] at time 07:59:31.736622
2019-03-28 07:59:31,736 [salt.state       :1813][INFO    ][14660] Executing state pkg.installed for [python-netaddr]
2019-03-28 07:59:31,747 [salt.state       :300 ][INFO    ][14660] All specified packages are already installed
2019-03-28 07:59:31,747 [salt.state       :1951][INFO    ][14660] Completed state [python-netaddr] at time 07:59:31.747641 duration_in_ms=11.019
2019-03-28 07:59:31,750 [salt.state       :1780][INFO    ][14660] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 07:59:31.750725
2019-03-28 07:59:31,751 [salt.state       :1813][INFO    ][14660] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-03-28 07:59:31,763 [salt.state       :300 ][INFO    ][14660] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-03-28 07:59:31,763 [salt.state       :1951][INFO    ][14660] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 07:59:31.763424 duration_in_ms=12.698
2019-03-28 07:59:31,764 [salt.state       :1780][INFO    ][14660] Running state [salt-minion] at time 07:59:31.764701
2019-03-28 07:59:31,765 [salt.state       :1813][INFO    ][14660] Executing state service.running for [salt-minion]
2019-03-28 07:59:31,765 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14660] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-28 07:59:31,804 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14660] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-03-28 07:59:31,821 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14660] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-03-28 07:59:31,842 [salt.state       :300 ][INFO    ][14660] The service salt-minion is already running
2019-03-28 07:59:31,843 [salt.state       :1951][INFO    ][14660] Completed state [salt-minion] at time 07:59:31.843371 duration_in_ms=78.669
2019-03-28 07:59:31,846 [salt.state       :1780][INFO    ][14660] Running state [/etc/salt/grains.d] at time 07:59:31.846262
2019-03-28 07:59:31,846 [salt.state       :1813][INFO    ][14660] Executing state file.directory for [/etc/salt/grains.d]
2019-03-28 07:59:31,848 [salt.state       :300 ][INFO    ][14660] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-03-28 07:59:31,848 [salt.state       :1951][INFO    ][14660] Completed state [/etc/salt/grains.d] at time 07:59:31.848874 duration_in_ms=2.612
2019-03-28 07:59:31,850 [salt.state       :1780][INFO    ][14660] Running state [/etc/salt/grains] at time 07:59:31.850265
2019-03-28 07:59:31,852 [salt.state       :1813][INFO    ][14660] Executing state file.managed for [/etc/salt/grains]
2019-03-28 07:59:31,853 [salt.state       :300 ][INFO    ][14660] File /etc/salt/grains exists with proper permissions. No changes made.
2019-03-28 07:59:31,853 [salt.state       :1951][INFO    ][14660] Completed state [/etc/salt/grains] at time 07:59:31.853320 duration_in_ms=3.056
2019-03-28 07:59:31,853 [salt.state       :1780][INFO    ][14660] Running state [/etc/salt/grains.d/placeholder] at time 07:59:31.853839
2019-03-28 07:59:31,854 [salt.state       :1813][INFO    ][14660] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-03-28 07:59:31,854 [salt.state       :300 ][INFO    ][14660] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-03-28 07:59:31,854 [salt.state       :1951][INFO    ][14660] Completed state [/etc/salt/grains.d/placeholder] at time 07:59:31.854907 duration_in_ms=1.068
2019-03-28 07:59:31,855 [salt.state       :1780][INFO    ][14660] Running state [/etc/salt/grains.d/sphinx] at time 07:59:31.855433
2019-03-28 07:59:31,855 [salt.state       :1813][INFO    ][14660] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-03-28 07:59:31,856 [salt.state       :300 ][INFO    ][14660] File /etc/salt/grains.d/sphinx is in the correct state
2019-03-28 07:59:31,857 [salt.state       :1951][INFO    ][14660] Completed state [/etc/salt/grains.d/sphinx] at time 07:59:31.857130 duration_in_ms=1.697
2019-03-28 07:59:31,859 [salt.state       :1780][INFO    ][14660] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 07:59:31.859314
2019-03-28 07:59:31,859 [salt.state       :1813][INFO    ][14660] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-03-28 07:59:31,859 [salt.state       :300 ][INFO    ][14660] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-03-28 07:59:31,860 [salt.state       :1951][INFO    ][14660] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 07:59:31.860181 duration_in_ms=0.867
2019-03-28 07:59:31,860 [salt.state       :1780][INFO    ][14660] Running state [/etc/salt/grains.d/dns_records] at time 07:59:31.860713
2019-03-28 07:59:31,861 [salt.state       :1813][INFO    ][14660] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-03-28 07:59:31,861 [salt.state       :300 ][INFO    ][14660] File /etc/salt/grains.d/dns_records is in the correct state
2019-03-28 07:59:31,862 [salt.state       :1951][INFO    ][14660] Completed state [/etc/salt/grains.d/dns_records] at time 07:59:31.862189 duration_in_ms=1.476
2019-03-28 07:59:31,864 [salt.state       :1780][INFO    ][14660] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 07:59:31.864343
2019-03-28 07:59:31,864 [salt.state       :1813][INFO    ][14660] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-03-28 07:59:31,864 [salt.state       :300 ][INFO    ][14660] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-03-28 07:59:31,865 [salt.state       :1951][INFO    ][14660] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 07:59:31.865147 duration_in_ms=0.803
2019-03-28 07:59:31,865 [salt.state       :1780][INFO    ][14660] Running state [/etc/salt/grains.d/salt] at time 07:59:31.865641
2019-03-28 07:59:31,865 [salt.state       :1813][INFO    ][14660] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-03-28 07:59:31,866 [salt.state       :300 ][INFO    ][14660] File /etc/salt/grains.d/salt is in the correct state
2019-03-28 07:59:31,867 [salt.state       :1951][INFO    ][14660] Completed state [/etc/salt/grains.d/salt] at time 07:59:31.867026 duration_in_ms=1.385
2019-03-28 07:59:31,867 [salt.state       :1780][INFO    ][14660] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 07:59:31.867922
2019-03-28 07:59:31,868 [salt.state       :1813][INFO    ][14660] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-03-28 07:59:31,868 [salt.state       :300 ][INFO    ][14660] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-03-28 07:59:31,868 [salt.state       :1951][INFO    ][14660] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 07:59:31.868716 duration_in_ms=0.795
2019-03-28 07:59:31,870 [salt.state       :1780][INFO    ][14660] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 07:59:31.870633
2019-03-28 07:59:31,870 [salt.state       :1813][INFO    ][14660] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-03-28 07:59:31,871 [salt.state       :300 ][INFO    ][14660] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-03-28 07:59:31,871 [salt.state       :1951][INFO    ][14660] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 07:59:31.871455 duration_in_ms=0.822
2019-03-28 07:59:31,872 [salt.state       :1780][INFO    ][14660] Running state [mine.update] at time 07:59:31.872132
2019-03-28 07:59:31,872 [salt.state       :1813][INFO    ][14660] Executing state module.wait for [mine.update]
2019-03-28 07:59:31,872 [salt.state       :300 ][INFO    ][14660] No changes made for mine.update
2019-03-28 07:59:31,872 [salt.state       :1951][INFO    ][14660] Completed state [mine.update] at time 07:59:31.872882 duration_in_ms=0.75
2019-03-28 07:59:31,873 [salt.state       :1780][INFO    ][14660] Running state [ca-certificates] at time 07:59:31.873135
2019-03-28 07:59:31,873 [salt.state       :1813][INFO    ][14660] Executing state pkg.installed for [ca-certificates]
2019-03-28 07:59:31,883 [salt.state       :300 ][INFO    ][14660] All specified packages are already installed
2019-03-28 07:59:31,883 [salt.state       :1951][INFO    ][14660] Completed state [ca-certificates] at time 07:59:31.883543 duration_in_ms=10.408
2019-03-28 07:59:31,884 [salt.state       :1780][INFO    ][14660] Running state [update-ca-certificates] at time 07:59:31.884252
2019-03-28 07:59:31,884 [salt.state       :1813][INFO    ][14660] Executing state cmd.wait for [update-ca-certificates]
2019-03-28 07:59:31,884 [salt.state       :300 ][INFO    ][14660] No changes made for update-ca-certificates
2019-03-28 07:59:31,885 [salt.state       :1951][INFO    ][14660] Completed state [update-ca-certificates] at time 07:59:31.885032 duration_in_ms=0.78
2019-03-28 07:59:31,885 [salt.state       :1780][INFO    ][14660] Running state [iptables] at time 07:59:31.885282
2019-03-28 07:59:31,885 [salt.state       :1813][INFO    ][14660] Executing state pkg.installed for [iptables]
2019-03-28 07:59:31,895 [salt.state       :300 ][INFO    ][14660] All specified packages are already installed
2019-03-28 07:59:31,895 [salt.state       :1951][INFO    ][14660] Completed state [iptables] at time 07:59:31.895330 duration_in_ms=10.048
2019-03-28 07:59:31,895 [salt.state       :1780][INFO    ][14660] Running state [iptables-persistent] at time 07:59:31.895590
2019-03-28 07:59:31,895 [salt.state       :1813][INFO    ][14660] Executing state pkg.installed for [iptables-persistent]
2019-03-28 07:59:31,905 [salt.state       :300 ][INFO    ][14660] All specified packages are already installed
2019-03-28 07:59:31,905 [salt.state       :1951][INFO    ][14660] Completed state [iptables-persistent] at time 07:59:31.905716 duration_in_ms=10.125
2019-03-28 07:59:31,907 [salt.state       :1780][INFO    ][14660] Running state [iptables_modules_v4_load] at time 07:59:31.906964
2019-03-28 07:59:31,907 [salt.state       :1813][INFO    ][14660] Executing state kmod.present for [iptables_modules_v4_load]
2019-03-28 07:59:31,907 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14660] Executing command 'lsmod' in directory '/root'
2019-03-28 07:59:31,928 [salt.state       :300 ][INFO    ][14660] Kernel modules iptable_filter, ip_tables are already present
2019-03-28 07:59:31,929 [salt.state       :1951][INFO    ][14660] Completed state [iptables_modules_v4_load] at time 07:59:31.929117 duration_in_ms=22.152
2019-03-28 07:59:31,930 [salt.state       :1780][INFO    ][14660] Running state [/etc/iptables/rules.v4] at time 07:59:31.930411
2019-03-28 07:59:31,931 [salt.state       :1813][INFO    ][14660] Executing state file.managed for [/etc/iptables/rules.v4]
2019-03-28 07:59:32,034 [salt.state       :300 ][INFO    ][14660] File /etc/iptables/rules.v4 is in the correct state
2019-03-28 07:59:32,034 [salt.state       :1951][INFO    ][14660] Completed state [/etc/iptables/rules.v4] at time 07:59:32.034600 duration_in_ms=104.189
2019-03-28 07:59:32,035 [salt.state       :1780][INFO    ][14660] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 07:59:32.035739
2019-03-28 07:59:32,036 [salt.state       :1813][INFO    ][14660] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-03-28 07:59:32,036 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14660] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-03-28 07:59:32,058 [salt.state       :300 ][INFO    ][14660] onlyif execution failed
2019-03-28 07:59:32,059 [salt.state       :1951][INFO    ][14660] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 07:59:32.059645 duration_in_ms=23.905
2019-03-28 07:59:32,061 [salt.state       :1780][INFO    ][14660] Running state [netfilter-persistent] at time 07:59:32.061608
2019-03-28 07:59:32,062 [salt.state       :1813][INFO    ][14660] Executing state service.running for [netfilter-persistent]
2019-03-28 07:59:32,063 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14660] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-03-28 07:59:32,085 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14660] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-03-28 07:59:32,102 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14660] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-03-28 07:59:32,121 [salt.state       :300 ][INFO    ][14660] The service netfilter-persistent is already running
2019-03-28 07:59:32,122 [salt.state       :1951][INFO    ][14660] Completed state [netfilter-persistent] at time 07:59:32.122384 duration_in_ms=60.776
2019-03-28 07:59:32,123 [salt.state       :1780][INFO    ][14660] Running state [iptables_extra.remove_stale_tables] at time 07:59:32.123713
2019-03-28 07:59:32,124 [salt.state       :1813][INFO    ][14660] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-03-28 07:59:32,124 [salt.state       :300 ][INFO    ][14660] No changes made for iptables_extra.remove_stale_tables
2019-03-28 07:59:32,125 [salt.state       :1951][INFO    ][14660] Completed state [iptables_extra.remove_stale_tables] at time 07:59:32.125041 duration_in_ms=1.327
2019-03-28 07:59:32,125 [salt.state       :1780][INFO    ][14660] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 07:59:32.125445
2019-03-28 07:59:32,125 [salt.state       :1813][INFO    ][14660] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-03-28 07:59:32,126 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14660] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-03-28 07:59:32,143 [salt.state       :300 ][INFO    ][14660] onlyif execution failed
2019-03-28 07:59:32,144 [salt.state       :1951][INFO    ][14660] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 07:59:32.144076 duration_in_ms=18.632
2019-03-28 07:59:32,145 [salt.state       :1780][INFO    ][14660] Running state [/etc/iptables/rules.v6] at time 07:59:32.145482
2019-03-28 07:59:32,145 [salt.state       :1813][INFO    ][14660] Executing state file.absent for [/etc/iptables/rules.v6]
2019-03-28 07:59:32,146 [salt.state       :300 ][INFO    ][14660] File /etc/iptables/rules.v6 is not present
2019-03-28 07:59:32,147 [salt.state       :1951][INFO    ][14660] Completed state [/etc/iptables/rules.v6] at time 07:59:32.146965 duration_in_ms=1.483
2019-03-28 07:59:32,148 [salt.state       :1780][INFO    ][14660] Running state [iptables_extra.flush_all] at time 07:59:32.148058
2019-03-28 07:59:32,148 [salt.state       :1813][INFO    ][14660] Executing state module.wait for [iptables_extra.flush_all]
2019-03-28 07:59:32,148 [salt.state       :300 ][INFO    ][14660] No changes made for iptables_extra.flush_all
2019-03-28 07:59:32,149 [salt.state       :1951][INFO    ][14660] Completed state [iptables_extra.flush_all] at time 07:59:32.149234 duration_in_ms=1.177
2019-03-28 07:59:32,154 [salt.minion      :1711][INFO    ][14660] Returning information for job: 20190328075921398776
2019-03-28 07:59:32,697 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command state.apply with jid 20190328075932684917
2019-03-28 07:59:32,718 [salt.minion      :1432][INFO    ][14743] Starting a new job with PID 14743
2019-03-28 07:59:33,636 [salt.state       :915 ][INFO    ][14743] Loading fresh modules for state activity
2019-03-28 07:59:34,503 [salt.state       :1780][INFO    ][14743] Running state [maas-rack-controller] at time 07:59:34.503390
2019-03-28 07:59:34,503 [salt.state       :1813][INFO    ][14743] Executing state pkg.installed for [maas-rack-controller]
2019-03-28 07:59:34,504 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14743] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-28 07:59:34,610 [salt.state       :300 ][INFO    ][14743] All specified packages are already installed
2019-03-28 07:59:34,611 [salt.state       :1951][INFO    ][14743] Completed state [maas-rack-controller] at time 07:59:34.611908 duration_in_ms=108.518
2019-03-28 07:59:34,612 [salt.state       :1780][INFO    ][14743] Running state [ipmitool] at time 07:59:34.612288
2019-03-28 07:59:34,612 [salt.state       :1813][INFO    ][14743] Executing state pkg.installed for [ipmitool]
2019-03-28 07:59:34,621 [salt.state       :300 ][INFO    ][14743] All specified packages are already installed
2019-03-28 07:59:34,621 [salt.state       :1951][INFO    ][14743] Completed state [ipmitool] at time 07:59:34.621912 duration_in_ms=9.623
2019-03-28 07:59:34,626 [salt.state       :1780][INFO    ][14743] Running state [/etc/maas/rackd.conf] at time 07:59:34.626856
2019-03-28 07:59:34,627 [salt.state       :1813][INFO    ][14743] Executing state file.line for [/etc/maas/rackd.conf]
2019-03-28 07:59:34,628 [salt.state       :300 ][INFO    ][14743] No changes needed to be made
2019-03-28 07:59:34,628 [salt.state       :1951][INFO    ][14743] Completed state [/etc/maas/rackd.conf] at time 07:59:34.628295 duration_in_ms=1.439
2019-03-28 07:59:34,628 [salt.state       :1780][INFO    ][14743] Running state [/etc/maas/rackd.conf] at time 07:59:34.628530
2019-03-28 07:59:34,628 [salt.state       :1813][INFO    ][14743] Executing state file.managed for [/etc/maas/rackd.conf]
2019-03-28 07:59:34,629 [salt.loaded.int.states.file:2298][WARNING ][14743] 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-03-28 07:59:34,629 [salt.state       :300 ][INFO    ][14743] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-03-28 07:59:34,630 [salt.state       :1951][INFO    ][14743] Completed state [/etc/maas/rackd.conf] at time 07:59:34.630024 duration_in_ms=1.494
2019-03-28 07:59:34,631 [salt.state       :1780][INFO    ][14743] Running state [maas-rackd] at time 07:59:34.631036
2019-03-28 07:59:34,631 [salt.state       :1813][INFO    ][14743] Executing state service.running for [maas-rackd]
2019-03-28 07:59:34,632 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14743] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-03-28 07:59:34,665 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14743] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-03-28 07:59:34,684 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14743] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-03-28 07:59:34,701 [salt.state       :300 ][INFO    ][14743] The service maas-rackd is already running
2019-03-28 07:59:34,702 [salt.state       :1951][INFO    ][14743] Completed state [maas-rackd] at time 07:59:34.702286 duration_in_ms=71.249
2019-03-28 07:59:34,704 [salt.minion      :1711][INFO    ][14743] Returning information for job: 20190328075932684917
2019-03-28 07:59:35,250 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command state.apply with jid 20190328075935233288
2019-03-28 07:59:35,267 [salt.minion      :1432][INFO    ][14766] Starting a new job with PID 14766
2019-03-28 07:59:36,174 [salt.state       :915 ][INFO    ][14766] Loading fresh modules for state activity
2019-03-28 07:59:37,185 [salt.state       :1780][INFO    ][14766] Running state [maas-region-controller] at time 07:59:37.185005
2019-03-28 07:59:37,185 [salt.state       :1813][INFO    ][14766] Executing state pkg.installed for [maas-region-controller]
2019-03-28 07:59:37,186 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14766] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-28 07:59:37,288 [salt.state       :300 ][INFO    ][14766] All specified packages are already installed
2019-03-28 07:59:37,289 [salt.state       :1951][INFO    ][14766] Completed state [maas-region-controller] at time 07:59:37.288988 duration_in_ms=103.983
2019-03-28 07:59:37,289 [salt.state       :1780][INFO    ][14766] Running state [python-oauth] at time 07:59:37.289298
2019-03-28 07:59:37,289 [salt.state       :1813][INFO    ][14766] Executing state pkg.installed for [python-oauth]
2019-03-28 07:59:37,298 [salt.state       :300 ][INFO    ][14766] All specified packages are already installed
2019-03-28 07:59:37,298 [salt.state       :1951][INFO    ][14766] Completed state [python-oauth] at time 07:59:37.298548 duration_in_ms=9.249
2019-03-28 07:59:37,302 [salt.state       :1780][INFO    ][14766] Running state [/etc/maas/regiond.conf] at time 07:59:37.302764
2019-03-28 07:59:37,303 [salt.state       :1813][INFO    ][14766] Executing state file.replace for [/etc/maas/regiond.conf]
2019-03-28 07:59:37,307 [salt.state       :300 ][INFO    ][14766] No changes needed to be made
2019-03-28 07:59:37,307 [salt.state       :1951][INFO    ][14766] Completed state [/etc/maas/regiond.conf] at time 07:59:37.307462 duration_in_ms=4.698
2019-03-28 07:59:37,307 [salt.state       :1780][INFO    ][14766] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 07:59:37.307934
2019-03-28 07:59:37,308 [salt.state       :1813][INFO    ][14766] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-03-28 07:59:37,366 [salt.state       :300 ][INFO    ][14766] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-03-28 07:59:37,366 [salt.state       :1951][INFO    ][14766] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 07:59:37.366661 duration_in_ms=58.726
2019-03-28 07:59:37,367 [salt.state       :1780][INFO    ][14766] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:59:37.367837
2019-03-28 07:59:37,368 [salt.state       :1813][INFO    ][14766] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-28 07:59:37,377 [salt.state       :300 ][INFO    ][14766] No changes needed to be made
2019-03-28 07:59:37,377 [salt.state       :1951][INFO    ][14766] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:59:37.377371 duration_in_ms=9.535
2019-03-28 07:59:37,378 [salt.state       :1780][INFO    ][14766] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:59:37.378090
2019-03-28 07:59:37,378 [salt.state       :1813][INFO    ][14766] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-28 07:59:37,385 [salt.state       :300 ][INFO    ][14766] No changes needed to be made
2019-03-28 07:59:37,385 [salt.state       :1951][INFO    ][14766] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:59:37.385878 duration_in_ms=7.789
2019-03-28 07:59:37,386 [salt.state       :1780][INFO    ][14766] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 07:59:37.386351
2019-03-28 07:59:37,386 [salt.state       :1813][INFO    ][14766] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-03-28 07:59:37,413 [salt.state       :300 ][INFO    ][14766] No changes needed to be made
2019-03-28 07:59:37,413 [salt.state       :1951][INFO    ][14766] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 07:59:37.413928 duration_in_ms=27.578
2019-03-28 07:59:37,414 [salt.state       :1780][INFO    ][14766] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 07:59:37.414397
2019-03-28 07:59:37,414 [salt.state       :1813][INFO    ][14766] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-03-28 07:59:37,429 [salt.state       :300 ][INFO    ][14766] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-03-28 07:59:37,430 [salt.state       :1951][INFO    ][14766] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 07:59:37.430126 duration_in_ms=15.727
2019-03-28 07:59:37,434 [salt.state       :1780][INFO    ][14766] Running state [a2enmod headers] at time 07:59:37.434565
2019-03-28 07:59:37,435 [salt.state       :1813][INFO    ][14766] Executing state cmd.run for [a2enmod headers]
2019-03-28 07:59:37,435 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14766] Executing command 'a2enmod headers' in directory '/root'
2019-03-28 07:59:37,507 [salt.state       :300 ][INFO    ][14766] {'pid': 14785, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-03-28 07:59:37,508 [salt.state       :1951][INFO    ][14766] Completed state [a2enmod headers] at time 07:59:37.508443 duration_in_ms=73.878
2019-03-28 07:59:37,509 [salt.state       :1780][INFO    ][14766] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 07:59:37.509041
2019-03-28 07:59:37,509 [salt.state       :1813][INFO    ][14766] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-03-28 07:59:37,534 [salt.state       :300 ][INFO    ][14766] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-03-28 07:59:37,535 [salt.state       :1951][INFO    ][14766] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 07:59:37.534794 duration_in_ms=25.753
2019-03-28 07:59:37,536 [salt.state       :1780][INFO    ][14766] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 07:59:37.536309
2019-03-28 07:59:37,536 [salt.state       :1813][INFO    ][14766] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-03-28 07:59:37,591 [salt.state       :300 ][INFO    ][14766] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-03-28 07:59:37,591 [salt.state       :1951][INFO    ][14766] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 07:59:37.591443 duration_in_ms=55.135
2019-03-28 07:59:37,591 [salt.state       :1780][INFO    ][14766] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 07:59:37.591931
2019-03-28 07:59:37,592 [salt.state       :1813][INFO    ][14766] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-03-28 07:59:37,649 [salt.state       :300 ][INFO    ][14766] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-03-28 07:59:37,649 [salt.state       :1951][INFO    ][14766] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 07:59:37.649268 duration_in_ms=57.337
2019-03-28 07:59:37,649 [salt.state       :1780][INFO    ][14766] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 07:59:37.649759
2019-03-28 07:59:37,650 [salt.state       :1813][INFO    ][14766] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-03-28 07:59:37,720 [salt.state       :300 ][INFO    ][14766] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-03-28 07:59:37,720 [salt.state       :1951][INFO    ][14766] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 07:59:37.720941 duration_in_ms=71.181
2019-03-28 07:59:37,721 [salt.state       :1780][INFO    ][14766] Running state [/root/.pgpass] at time 07:59:37.721188
2019-03-28 07:59:37,721 [salt.state       :1813][INFO    ][14766] Executing state file.managed for [/root/.pgpass]
2019-03-28 07:59:37,766 [salt.state       :300 ][INFO    ][14766] File /root/.pgpass is in the correct state
2019-03-28 07:59:37,766 [salt.state       :1951][INFO    ][14766] Completed state [/root/.pgpass] at time 07:59:37.766227 duration_in_ms=45.039
2019-03-28 07:59:37,772 [salt.state       :1780][INFO    ][14766] Running state [maas-region syncdb --noinput] at time 07:59:37.772656
2019-03-28 07:59:37,772 [salt.state       :1813][INFO    ][14766] Executing state cmd.run for [maas-region syncdb --noinput]
2019-03-28 07:59:37,773 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14766] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-03-28 07:59:40,351 [salt.state       :300 ][INFO    ][14766] {'pid': 14798, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: piston3, metadataserver, sessions, maasserver, contenttypes, auth, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-03-28 07:59:40,352 [salt.state       :1951][INFO    ][14766] Completed state [maas-region syncdb --noinput] at time 07:59:40.352506 duration_in_ms=2579.848
2019-03-28 07:59:40,353 [salt.state       :2022][WARNING ][14766] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-28 07:59:40,356 [salt.state       :1780][INFO    ][14766] Running state [maas-regiond] at time 07:59:40.356411
2019-03-28 07:59:40,357 [salt.state       :1813][INFO    ][14766] Executing state service.running for [maas-regiond]
2019-03-28 07:59:40,358 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14766] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-03-28 07:59:40,400 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14766] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-03-28 07:59:40,417 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14766] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-03-28 07:59:40,439 [salt.state       :300 ][INFO    ][14766] The service maas-regiond is already running
2019-03-28 07:59:40,440 [salt.state       :1951][INFO    ][14766] Completed state [maas-regiond] at time 07:59:40.440209 duration_in_ms=83.799
2019-03-28 07:59:40,445 [salt.state       :1780][INFO    ][14766] Running state [bind9] at time 07:59:40.445033
2019-03-28 07:59:40,445 [salt.state       :1813][INFO    ][14766] Executing state service.running for [bind9]
2019-03-28 07:59:40,446 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14766] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-03-28 07:59:40,464 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14766] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-03-28 07:59:40,481 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14766] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-03-28 07:59:40,497 [salt.state       :300 ][INFO    ][14766] The service bind9 is already running
2019-03-28 07:59:40,498 [salt.state       :1951][INFO    ][14766] Completed state [bind9] at time 07:59:40.498050 duration_in_ms=53.016
2019-03-28 07:59:40,500 [salt.state       :1780][INFO    ][14766] Running state [apache2] at time 07:59:40.500483
2019-03-28 07:59:40,500 [salt.state       :1813][INFO    ][14766] Executing state service.running for [apache2]
2019-03-28 07:59:40,501 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14766] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-03-28 07:59:40,522 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14766] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-03-28 07:59:40,538 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14766] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-03-28 07:59:40,566 [salt.state       :300 ][INFO    ][14766] The service apache2 is already running
2019-03-28 07:59:40,566 [salt.state       :1951][INFO    ][14766] Completed state [apache2] at time 07:59:40.566658 duration_in_ms=66.175
2019-03-28 07:59:40,568 [salt.state       :1780][INFO    ][14766] Running state [maasng.wait_for_http_code] at time 07:59:40.568592
2019-03-28 07:59:40,569 [salt.state       :1813][INFO    ][14766] Executing state module.run for [maasng.wait_for_http_code]
2019-03-28 07:59:40,569 [salt.utils.decorators:613 ][WARNING ][14766] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-28 07:59:40,578 [salt.state       :300 ][INFO    ][14766] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-03-28 07:59:40,578 [salt.state       :1951][INFO    ][14766] Completed state [maasng.wait_for_http_code] at time 07:59:40.578694 duration_in_ms=10.103
2019-03-28 07:59:40,579 [salt.state       :1780][INFO    ][14766] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 07:59:40.579522
2019-03-28 07:59:40,579 [salt.state       :1813][INFO    ][14766] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-03-28 07:59:40,580 [salt.state       :300 ][INFO    ][14766] /var/lib/maas/.setup_admin exists
2019-03-28 07:59:40,580 [salt.state       :1951][INFO    ][14766] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 07:59:40.580440 duration_in_ms=0.918
2019-03-28 07:59:40,581 [salt.state       :1780][INFO    ][14766] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:59:40.581183
2019-03-28 07:59:40,581 [salt.state       :1813][INFO    ][14766] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-28 07:59:40,582 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14766] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-28 07:59:42,437 [salt.state       :300 ][INFO    ][14766] {'pid': 14817, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-28 07:59:42,437 [salt.state       :1951][INFO    ][14766] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:59:42.437693 duration_in_ms=1856.51
2019-03-28 07:59:42,443 [salt.state       :1780][INFO    ][14766] Running state [maas_region_boot_source_resources_mirror] at time 07:59:42.443249
2019-03-28 07:59:42,443 [salt.state       :1813][INFO    ][14766] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-03-28 07:59:42,512 [salt.state       :300 ][INFO    ][14766] {'changes': {}}
2019-03-28 07:59:42,512 [salt.state       :1951][INFO    ][14766] Completed state [maas_region_boot_source_resources_mirror] at time 07:59:42.512617 duration_in_ms=69.367
2019-03-28 07:59:42,513 [salt.state       :1780][INFO    ][14766] Running state [maasng.boot_resources_import] at time 07:59:42.513291
2019-03-28 07:59:42,513 [salt.state       :1813][INFO    ][14766] Executing state module.run for [maasng.boot_resources_import]
2019-03-28 07:59:42,513 [salt.utils.decorators:613 ][WARNING ][14766] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-28 07:59:42,574 [salt.loaded.ext.module.maasng:1600][INFO    ][14766] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-03-28 07:59:47,626 [salt.loaded.ext.module.maasng:1600][INFO    ][14766] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-03-28 07:59:50,330 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328075950317358
2019-03-28 07:59:50,355 [salt.minion      :1432][INFO    ][14986] Starting a new job with PID 14986
2019-03-28 07:59:50,380 [salt.minion      :1711][INFO    ][14986] Returning information for job: 20190328075950317358
2019-03-28 07:59:52,711 [salt.state       :300 ][INFO    ][14766] {'ret': True}
2019-03-28 07:59:52,712 [salt.state       :1951][INFO    ][14766] Completed state [maasng.boot_resources_import] at time 07:59:52.712383 duration_in_ms=10199.091
2019-03-28 07:59:52,713 [salt.state       :1780][INFO    ][14766] Running state [maas_region_boot_sources_selection_xenial] at time 07:59:52.713757
2019-03-28 07:59:52,714 [salt.state       :1813][INFO    ][14766] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-03-28 07:59:52,886 [salt.state       :300 ][INFO    ][14766] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-03-28 07:59:52,887 [salt.state       :1951][INFO    ][14766] Completed state [maas_region_boot_sources_selection_xenial] at time 07:59:52.887631 duration_in_ms=173.874
2019-03-28 07:59:52,889 [salt.state       :1780][INFO    ][14766] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 07:59:52.889165
2019-03-28 07:59:52,889 [salt.state       :1813][INFO    ][14766] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-03-28 07:59:52,890 [salt.utils.decorators:613 ][WARNING ][14766] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-28 07:59:52,890 [salt.loaded.ext.module.maasng:1771][INFO    ][14766] boot-sources sync initiated for ALL Rack's
2019-03-28 07:59:53,845 [salt.state       :300 ][INFO    ][14766] {'ret': True}
2019-03-28 07:59:53,846 [salt.state       :1951][INFO    ][14766] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 07:59:53.845990 duration_in_ms=956.824
2019-03-28 07:59:53,848 [salt.state       :1780][INFO    ][14766] Running state [maas.process_maas_config] at time 07:59:53.848595
2019-03-28 07:59:53,849 [salt.state       :1813][INFO    ][14766] Executing state module.run for [maas.process_maas_config]
2019-03-28 07:59:53,849 [salt.utils.decorators:613 ][WARNING ][14766] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-28 07:59:53,850 [salt.loaded.ext.module.maas:92  ][INFO    ][14766] maasconfig name=enable_http_proxy value=True
2019-03-28 07:59:53,897 [salt.loaded.ext.module.maas:92  ][INFO    ][14766] maasconfig name=upstream_dns value=8.8.8.8
2019-03-28 07:59:53,951 [salt.loaded.ext.module.maas:92  ][INFO    ][14766] maasconfig name=commissioning_distro_series value=xenial
2019-03-28 07:59:54,005 [salt.loaded.ext.module.maas:92  ][INFO    ][14766] maasconfig name=default_osystem value=ubuntu
2019-03-28 07:59:55,266 [salt.loaded.ext.module.maas:92  ][INFO    ][14766] maasconfig name=active_discovery_interval value=600
2019-03-28 07:59:55,310 [salt.loaded.ext.module.maas:92  ][INFO    ][14766] maasconfig name=dnssec_validation value=no
2019-03-28 07:59:55,365 [salt.loaded.ext.module.maas:92  ][INFO    ][14766] maasconfig name=maas_name value=mas01
2019-03-28 07:59:55,404 [salt.loaded.ext.module.maas:92  ][INFO    ][14766] maasconfig name=network_discovery value=enabled
2019-03-28 07:59:55,533 [salt.loaded.ext.module.maas:92  ][INFO    ][14766] maasconfig name=enable_third_party_drivers value=True
2019-03-28 07:59:55,613 [salt.loaded.ext.module.maas:92  ][INFO    ][14766] maasconfig name=default_storage_layout value=lvm
2019-03-28 07:59:55,681 [salt.loaded.ext.module.maas:92  ][INFO    ][14766] maasconfig name=ntp_external_only value=True
2019-03-28 07:59:55,738 [salt.loaded.ext.module.maas:92  ][INFO    ][14766] maasconfig name=disk_erase_with_secure_erase value=False
2019-03-28 07:59:55,785 [salt.loaded.ext.module.maas:92  ][INFO    ][14766] maasconfig name=default_distro_series value=xenial
2019-03-28 07:59:55,845 [salt.loaded.ext.module.maas:92  ][INFO    ][14766] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-03-28 07:59:55,943 [salt.state       :300 ][INFO    ][14766] {'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-03-28 07:59:55,943 [salt.state       :1951][INFO    ][14766] Completed state [maas.process_maas_config] at time 07:59:55.943678 duration_in_ms=2095.082
2019-03-28 07:59:55,944 [salt.state       :1780][INFO    ][14766] Running state [pxe_admin] at time 07:59:55.944544
2019-03-28 07:59:55,944 [salt.state       :1813][INFO    ][14766] Executing state maasng.fabric_present for [pxe_admin]
2019-03-28 07:59:56,011 [salt.loaded.ext.module.maasng:945 ][INFO    ][14766] [{u'id': 0, 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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', 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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fq6qgd', u'fabric': u'pxe_admin', 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'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-28 07:59:56,075 [salt.loaded.ext.module.maasng:1008][WARNING ][14766] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-03-28 07:59:56,076 [salt.loaded.ext.module.maasng:1011][WARNING ][14766] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-03-28 07:59:56,140 [salt.state       :300 ][INFO    ][14766] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-03-28 07:59:56,140 [salt.state       :1951][INFO    ][14766] Completed state [pxe_admin] at time 07:59:56.140770 duration_in_ms=196.225
2019-03-28 07:59:56,141 [salt.state       :1780][INFO    ][14766] Running state [vlan 0] at time 07:59:56.141069
2019-03-28 07:59:56,141 [salt.state       :1813][INFO    ][14766] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-28 07:59:56,189 [salt.loaded.ext.module.maasng:945 ][INFO    ][14766] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fq6qgd', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-28 07:59:56,291 [salt.loaded.ext.module.maasng:945 ][INFO    ][14766] [{u'id': 0, 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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', 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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fq6qgd', u'fabric': u'pxe_admin', 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'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-28 07:59:56,555 [salt.loaded.ext.module.maasng:945 ][INFO    ][14766] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fq6qgd', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-28 07:59:56,685 [salt.state       :300 ][INFO    ][14766] {'new': 'Vlan untagged was updated'}
2019-03-28 07:59:56,685 [salt.state       :1951][INFO    ][14766] Completed state [vlan 0] at time 07:59:56.685883 duration_in_ms=544.812
2019-03-28 07:59:56,687 [salt.state       :1780][INFO    ][14766] Running state [192.168.11.0/24] at time 07:59:56.687506
2019-03-28 07:59:56,687 [salt.state       :1813][INFO    ][14766] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-03-28 07:59:56,857 [salt.loaded.ext.module.maasng:945 ][INFO    ][14766] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', 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'name': u'fabric-2', u'id': 2}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'fq6qgd', u'fabric': u'pxe_admin', 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'name': u'pxe_admin', u'id': 1}]
2019-03-28 07:59:56,857 [salt.loaded.ext.module.maasng:1235][WARNING ][14766] Ignoring parameter vlan:0
2019-03-28 07:59:56,914 [salt.state       :300 ][INFO    ][14766] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-03-28 07:59:56,915 [salt.state       :1951][INFO    ][14766] Completed state [192.168.11.0/24] at time 07:59:56.915229 duration_in_ms=227.723
2019-03-28 07:59:56,916 [salt.state       :1780][INFO    ][14766] Running state [maas_create_iprange_1] at time 07:59:56.916027
2019-03-28 07:59:56,916 [salt.state       :1813][INFO    ][14766] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-03-28 07:59:56,959 [salt.state       :300 ][INFO    ][14766] Iprange maas_create_iprange_1 already exist.
2019-03-28 07:59:56,959 [salt.state       :1951][INFO    ][14766] Completed state [maas_create_iprange_1] at time 07:59:56.959831 duration_in_ms=43.803
2019-03-28 07:59:56,960 [salt.state       :1780][INFO    ][14766] Running state [vlan 0] at time 07:59:56.960109
2019-03-28 07:59:56,960 [salt.state       :1813][INFO    ][14766] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-28 07:59:57,002 [salt.loaded.ext.module.maasng:945 ][INFO    ][14766] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fq6qgd', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-03-28 07:59:57,079 [salt.loaded.ext.module.maasng:945 ][INFO    ][14766] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', 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'name': u'fabric-2', u'id': 2}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'fq6qgd', u'fabric': u'pxe_admin', 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'name': u'pxe_admin', u'id': 1}]
2019-03-28 07:59:57,306 [salt.loaded.ext.module.maasng:945 ][INFO    ][14766] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'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'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', 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'name': u'fabric-2', u'id': 2}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'fq6qgd', u'fabric': u'pxe_admin', 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'name': u'pxe_admin', u'id': 1}]
2019-03-28 07:59:57,419 [salt.state       :300 ][INFO    ][14766] {'new': 'Vlan untagged was updated'}
2019-03-28 07:59:57,419 [salt.state       :1951][INFO    ][14766] Completed state [vlan 0] at time 07:59:57.419449 duration_in_ms=459.339
2019-03-28 07:59:57,420 [salt.state       :1780][INFO    ][14766] Running state [opnfv] at time 07:59:57.420192
2019-03-28 07:59:57,420 [salt.state       :1813][INFO    ][14766] Executing state maasng.sshkey_present for [opnfv]
2019-03-28 07:59:57,464 [salt.loaded.ext.module.maasng:1903][INFO    ][14766] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H', u'keysource': u''}]
2019-03-28 07:59:57,464 [salt.state       :300 ][INFO    ][14766] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H already exist for user opnfv.
2019-03-28 07:59:57,464 [salt.state       :1951][INFO    ][14766] Completed state [opnfv] at time 07:59:57.464707 duration_in_ms=44.514
2019-03-28 07:59:57,467 [salt.minion      :1711][INFO    ][14766] Returning information for job: 20190328075935233288
2019-03-28 07:59:58,049 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command state.apply with jid 20190328075958039599
2019-03-28 07:59:58,064 [salt.minion      :1432][INFO    ][15255] Starting a new job with PID 15255
2019-03-28 08:00:04,205 [salt.state       :915 ][INFO    ][15255] Loading fresh modules for state activity
2019-03-28 08:00:04,312 [salt.state       :1780][INFO    ][15255] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:00:04.311930
2019-03-28 08:00:04,312 [salt.state       :1813][INFO    ][15255] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-28 08:00:04,313 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15255] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-28 08:00:06,334 [salt.state       :300 ][INFO    ][15255] {'pid': 15292, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-28 08:00:06,335 [salt.state       :1951][INFO    ][15255] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:00:06.335587 duration_in_ms=2023.657
2019-03-28 08:00:06,337 [salt.state       :1780][INFO    ][15255] Running state [maas.process_machines] at time 08:00:06.337861
2019-03-28 08:00:06,338 [salt.state       :1813][INFO    ][15255] Executing state module.run for [maas.process_machines]
2019-03-28 08:00:06,341 [salt.utils.decorators:613 ][WARNING ][15255] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-28 08:00:06,905 [salt.loaded.ext.module.maas:412 ][WARNING ][15255] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-28 08:00:06,906 [salt.loaded.ext.module.maas:92  ][INFO    ][15255] 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=sg4ya8 architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-28 08:00:08,021 [salt.loaded.ext.module.maas:412 ][WARNING ][15255] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-28 08:00:08,021 [salt.loaded.ext.module.maas:92  ][INFO    ][15255] 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=et7nb4 architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-28 08:00:09,118 [salt.loaded.ext.module.maas:412 ][WARNING ][15255] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-28 08:00:09,119 [salt.loaded.ext.module.maas:92  ][INFO    ][15255] 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=wy8byb architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-28 08:00:10,248 [salt.loaded.ext.module.maas:412 ][WARNING ][15255] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-28 08:00:10,249 [salt.loaded.ext.module.maas:92  ][INFO    ][15255] 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=p3b88p architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-28 08:00:11,358 [salt.loaded.ext.module.maas:412 ][WARNING ][15255] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-28 08:00:11,359 [salt.loaded.ext.module.maas:92  ][INFO    ][15255] 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=yy7cx8 architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-28 08:00:12,315 [salt.state       :300 ][INFO    ][15255] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-28 08:00:12,316 [salt.state       :1951][INFO    ][15255] Completed state [maas.process_machines] at time 08:00:12.316370 duration_in_ms=5978.508
2019-03-28 08:00:12,319 [salt.minion      :1711][INFO    ][15255] Returning information for job: 20190328075958039599
2019-03-28 08:00:45,321 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command state.apply with jid 20190328080045310336
2019-03-28 08:00:45,344 [salt.minion      :1432][INFO    ][15532] Starting a new job with PID 15532
2019-03-28 08:00:51,228 [salt.state       :915 ][INFO    ][15532] Loading fresh modules for state activity
2019-03-28 08:00:51,312 [salt.state       :1780][INFO    ][15532] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:00:51.312869
2019-03-28 08:00:51,313 [salt.state       :1813][INFO    ][15532] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-28 08:00:51,315 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15532] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-28 08:00:53,135 [salt.state       :300 ][INFO    ][15532] {'pid': 15539, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-28 08:00:53,136 [salt.state       :1951][INFO    ][15532] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:00:53.136821 duration_in_ms=1823.952
2019-03-28 08:00:53,141 [salt.state       :1780][INFO    ][15532] Running state [maas.wait_for_machine_status] at time 08:00:53.141090
2019-03-28 08:00:53,141 [salt.state       :1813][INFO    ][15532] Executing state module.run for [maas.wait_for_machine_status]
2019-03-28 08:00:53,141 [salt.utils.decorators:613 ][WARNING ][15532] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-28 08:00:55,918 [salt.state       :300 ][INFO    ][15532] {'ret': True}
2019-03-28 08:00:55,919 [salt.state       :1951][INFO    ][15532] Completed state [maas.wait_for_machine_status] at time 08:00:55.919358 duration_in_ms=2778.267
2019-03-28 08:00:55,921 [salt.minion      :1711][INFO    ][15532] Returning information for job: 20190328080045310336
2019-03-28 08:00:56,519 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command state.apply with jid 20190328080056459512
2019-03-28 08:00:56,540 [salt.minion      :1432][INFO    ][15596] Starting a new job with PID 15596
2019-03-28 08:00:57,485 [salt.state       :915 ][INFO    ][15596] Loading fresh modules for state activity
2019-03-28 08:00:57,631 [salt.state       :1780][INFO    ][15596] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:00:57.630872
2019-03-28 08:00:57,631 [salt.state       :1813][INFO    ][15596] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-28 08:00:57,634 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15596] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-28 08:00:59,420 [salt.state       :300 ][INFO    ][15596] {'pid': 15608, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-28 08:00:59,421 [salt.state       :1951][INFO    ][15596] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:00:59.421289 duration_in_ms=1790.418
2019-03-28 08:00:59,425 [salt.state       :1780][INFO    ][15596] Running state [maas_machines_storage_cmp002_lvm] at time 08:00:59.424956
2019-03-28 08:00:59,425 [salt.state       :1813][INFO    ][15596] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-28 08:00:59,933 [salt.state       :300 ][INFO    ][15596] Machine cmp002 is not in Ready state.
2019-03-28 08:00:59,933 [salt.state       :1951][INFO    ][15596] Completed state [maas_machines_storage_cmp002_lvm] at time 08:00:59.933697 duration_in_ms=508.74
2019-03-28 08:00:59,934 [salt.state       :1780][INFO    ][15596] Running state [maas_machines_storage_cmp001_lvm] at time 08:00:59.934065
2019-03-28 08:00:59,934 [salt.state       :1813][INFO    ][15596] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-28 08:01:00,469 [salt.state       :300 ][INFO    ][15596] Machine cmp001 is not in Ready state.
2019-03-28 08:01:00,469 [salt.state       :1951][INFO    ][15596] Completed state [maas_machines_storage_cmp001_lvm] at time 08:01:00.469777 duration_in_ms=535.711
2019-03-28 08:01:00,474 [salt.minion      :1711][INFO    ][15596] Returning information for job: 20190328080056459512
2019-03-28 08:01:01,018 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command state.apply with jid 20190328080101008374
2019-03-28 08:01:01,038 [salt.minion      :1432][INFO    ][15618] Starting a new job with PID 15618
2019-03-28 08:01:02,015 [salt.state       :915 ][INFO    ][15618] Loading fresh modules for state activity
2019-03-28 08:01:02,105 [salt.state       :1780][INFO    ][15618] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:01:02.105092
2019-03-28 08:01:02,105 [salt.state       :1813][INFO    ][15618] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-28 08:01:02,107 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15618] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-28 08:01:03,949 [salt.state       :300 ][INFO    ][15618] {'pid': 15631, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-28 08:01:03,950 [salt.state       :1951][INFO    ][15618] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:01:03.950566 duration_in_ms=1845.474
2019-03-28 08:01:03,954 [salt.state       :1780][INFO    ][15618] Running state [maas.deploy_machines] at time 08:01:03.954384
2019-03-28 08:01:03,955 [salt.state       :1813][INFO    ][15618] Executing state module.run for [maas.deploy_machines]
2019-03-28 08:01:03,956 [salt.utils.decorators:613 ][WARNING ][15618] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-28 08:01:04,525 [salt.loaded.ext.module.maas:684 ][INFO    ][15618] deploymachines hwe_kernel=hwe-16.04 system_id=yy7cx8 distro_series=xenial
2019-03-28 08:01:06,861 [salt.state       :300 ][INFO    ][15618] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-03-28 08:01:06,861 [salt.state       :1951][INFO    ][15618] Completed state [maas.deploy_machines] at time 08:01:06.861526 duration_in_ms=2907.142
2019-03-28 08:01:06,864 [salt.minion      :1711][INFO    ][15618] Returning information for job: 20190328080101008374
2019-03-28 08:01:07,437 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command state.apply with jid 20190328080107423701
2019-03-28 08:01:07,459 [salt.minion      :1432][INFO    ][15697] Starting a new job with PID 15697
2019-03-28 08:01:13,393 [salt.state       :915 ][INFO    ][15697] Loading fresh modules for state activity
2019-03-28 08:01:13,486 [salt.state       :1780][INFO    ][15697] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:01:13.485967
2019-03-28 08:01:13,486 [salt.state       :1813][INFO    ][15697] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-28 08:01:13,488 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15697] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-28 08:01:15,261 [salt.state       :300 ][INFO    ][15697] {'pid': 15713, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-28 08:01:15,262 [salt.state       :1951][INFO    ][15697] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:01:15.262285 duration_in_ms=1776.317
2019-03-28 08:01:15,266 [salt.state       :1780][INFO    ][15697] Running state [maas.wait_for_machine_status] at time 08:01:15.266303
2019-03-28 08:01:15,268 [salt.state       :1813][INFO    ][15697] Executing state module.run for [maas.wait_for_machine_status]
2019-03-28 08:01:15,268 [salt.utils.decorators:613 ][WARNING ][15697] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-28 08:01:18,033 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.24735594s left)
2019-03-28 08:01:22,554 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328080122544844
2019-03-28 08:01:22,569 [salt.minion      :1432][INFO    ][15724] Starting a new job with PID 15724
2019-03-28 08:01:22,592 [salt.minion      :1711][INFO    ][15724] Returning information for job: 20190328080122544844
2019-03-28 08:01:50,793 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.48815513s left)
2019-03-28 08:01:52,737 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328080152729894
2019-03-28 08:01:52,751 [salt.minion      :1432][INFO    ][15754] Starting a new job with PID 15754
2019-03-28 08:01:52,775 [salt.minion      :1711][INFO    ][15754] Returning information for job: 20190328080152729894
2019-03-28 08:02:22,931 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328080222921795
2019-03-28 08:02:22,951 [salt.minion      :1432][INFO    ][15826] Starting a new job with PID 15826
2019-03-28 08:02:22,973 [salt.minion      :1711][INFO    ][15826] Returning information for job: 20190328080222921795
2019-03-28 08:02:23,556 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.72484303s left)
2019-03-28 08:02:53,117 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328080253100667
2019-03-28 08:02:53,138 [salt.minion      :1432][INFO    ][15864] Starting a new job with PID 15864
2019-03-28 08:02:53,165 [salt.minion      :1711][INFO    ][15864] Returning information for job: 20190328080253100667
2019-03-28 08:02:56,323 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.95781112s left)
2019-03-28 08:03:23,310 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328080323301946
2019-03-28 08:03:23,333 [salt.minion      :1432][INFO    ][15946] Starting a new job with PID 15946
2019-03-28 08:03:23,358 [salt.minion      :1711][INFO    ][15946] Returning information for job: 20190328080323301946
2019-03-28 08:03:29,150 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.13044s left)
2019-03-28 08:03:53,381 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328080353371563
2019-03-28 08:03:53,394 [salt.minion      :1432][INFO    ][15976] Starting a new job with PID 15976
2019-03-28 08:03:53,414 [salt.minion      :1711][INFO    ][15976] Returning information for job: 20190328080353371563
2019-03-28 08:04:01,921 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.36009312s left)
2019-03-28 08:04:23,587 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328080423579135
2019-03-28 08:04:23,610 [salt.minion      :1432][INFO    ][16051] Starting a new job with PID 16051
2019-03-28 08:04:23,635 [salt.minion      :1711][INFO    ][16051] Returning information for job: 20190328080423579135
2019-03-28 08:04:34,573 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.70758295s left)
2019-03-28 08:04:53,670 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328080453655336
2019-03-28 08:04:53,690 [salt.minion      :1432][INFO    ][16081] Starting a new job with PID 16081
2019-03-28 08:04:53,714 [salt.minion      :1711][INFO    ][16081] Returning information for job: 20190328080453655336
2019-03-28 08:05:07,365 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.91586494s left)
2019-03-28 08:05:23,866 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328080523859733
2019-03-28 08:05:23,882 [salt.minion      :1432][INFO    ][16177] Starting a new job with PID 16177
2019-03-28 08:05:23,905 [salt.minion      :1711][INFO    ][16177] Returning information for job: 20190328080523859733
2019-03-28 08:05:40,208 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1985.07283902s left)
2019-03-28 08:05:54,054 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328080554046927
2019-03-28 08:05:54,069 [salt.minion      :1432][INFO    ][16215] Starting a new job with PID 16215
2019-03-28 08:05:54,092 [salt.minion      :1711][INFO    ][16215] Returning information for job: 20190328080554046927
2019-03-28 08:06:13,025 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1952.25557804s left)
2019-03-28 08:06:24,132 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328080624124519
2019-03-28 08:06:24,152 [salt.minion      :1432][INFO    ][16322] Starting a new job with PID 16322
2019-03-28 08:06:24,175 [salt.minion      :1711][INFO    ][16322] Returning information for job: 20190328080624124519
2019-03-28 08:06:45,900 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1919.38033104s left)
2019-03-28 08:06:54,213 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328080654204743
2019-03-28 08:06:54,234 [salt.minion      :1432][INFO    ][16359] Starting a new job with PID 16359
2019-03-28 08:06:54,260 [salt.minion      :1711][INFO    ][16359] Returning information for job: 20190328080654204743
2019-03-28 08:07:18,688 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1886.59225392s left)
2019-03-28 08:07:24,325 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328080724319021
2019-03-28 08:07:24,338 [salt.minion      :1432][INFO    ][16569] Starting a new job with PID 16569
2019-03-28 08:07:24,361 [salt.minion      :1711][INFO    ][16569] Returning information for job: 20190328080724319021
2019-03-28 08:07:51,568 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1853.71239805s left)
2019-03-28 08:07:54,437 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328080754423948
2019-03-28 08:07:54,461 [salt.minion      :1432][INFO    ][16599] Starting a new job with PID 16599
2019-03-28 08:07:54,487 [salt.minion      :1711][INFO    ][16599] Returning information for job: 20190328080754423948
2019-03-28 08:08:24,551 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328080824543522
2019-03-28 08:08:24,564 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1820.71700811s left)
2019-03-28 08:08:24,572 [salt.minion      :1432][INFO    ][16691] Starting a new job with PID 16691
2019-03-28 08:08:24,595 [salt.minion      :1711][INFO    ][16691] Returning information for job: 20190328080824543522
2019-03-28 08:08:54,650 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328080854641932
2019-03-28 08:08:54,667 [salt.minion      :1432][INFO    ][16718] Starting a new job with PID 16718
2019-03-28 08:08:54,690 [salt.minion      :1711][INFO    ][16718] Returning information for job: 20190328080854641932
2019-03-28 08:08:58,234 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1787.04695415s left)
2019-03-28 08:09:24,813 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328080924798323
2019-03-28 08:09:24,834 [salt.minion      :1432][INFO    ][16955] Starting a new job with PID 16955
2019-03-28 08:09:24,858 [salt.minion      :1711][INFO    ][16955] Returning information for job: 20190328080924798323
2019-03-28 08:09:30,845 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1754.435431s left)
2019-03-28 08:09:54,932 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328080954918015
2019-03-28 08:09:54,956 [salt.minion      :1432][INFO    ][16987] Starting a new job with PID 16987
2019-03-28 08:09:54,984 [salt.minion      :1711][INFO    ][16987] Returning information for job: 20190328080954918015
2019-03-28 08:10:03,284 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1721.99641609s left)
2019-03-28 08:10:24,978 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328081024959710
2019-03-28 08:10:25,005 [salt.minion      :1432][INFO    ][17087] Starting a new job with PID 17087
2019-03-28 08:10:25,039 [salt.minion      :1711][INFO    ][17087] Returning information for job: 20190328081024959710
2019-03-28 08:10:35,858 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1689.42242002s left)
2019-03-28 08:10:55,016 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328081055004276
2019-03-28 08:10:55,040 [salt.minion      :1432][INFO    ][17120] Starting a new job with PID 17120
2019-03-28 08:10:55,081 [salt.minion      :1711][INFO    ][17120] Returning information for job: 20190328081055004276
2019-03-28 08:11:08,632 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1656.64877605s left)
2019-03-28 08:11:25,184 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328081125175196
2019-03-28 08:11:25,199 [salt.minion      :1432][INFO    ][17191] Starting a new job with PID 17191
2019-03-28 08:11:25,220 [salt.minion      :1711][INFO    ][17191] Returning information for job: 20190328081125175196
2019-03-28 08:11:41,390 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1623.89112496s left)
2019-03-28 08:11:55,309 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328081155301799
2019-03-28 08:11:55,331 [salt.minion      :1432][INFO    ][17240] Starting a new job with PID 17240
2019-03-28 08:11:55,370 [salt.minion      :1711][INFO    ][17240] Returning information for job: 20190328081155301799
2019-03-28 08:12:14,270 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1591.01093507s left)
2019-03-28 08:12:25,497 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328081225483629
2019-03-28 08:12:25,519 [salt.minion      :1432][INFO    ][17294] Starting a new job with PID 17294
2019-03-28 08:12:25,547 [salt.minion      :1711][INFO    ][17294] Returning information for job: 20190328081225483629
2019-03-28 08:12:47,076 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1558.20508814s left)
2019-03-28 08:12:55,653 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328081255643795
2019-03-28 08:12:55,675 [salt.minion      :1432][INFO    ][17375] Starting a new job with PID 17375
2019-03-28 08:12:55,702 [salt.minion      :1711][INFO    ][17375] Returning information for job: 20190328081255643795
2019-03-28 08:13:19,825 [salt.loaded.ext.module.maas:1023][INFO    ][15697] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1525.45527196s left)
2019-03-28 08:13:25,822 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command saltutil.find_job with jid 20190328081325806261
2019-03-28 08:13:25,843 [salt.minion      :1432][INFO    ][17585] Starting a new job with PID 17585
2019-03-28 08:13:25,867 [salt.minion      :1711][INFO    ][17585] Returning information for job: 20190328081325806261
2019-03-28 08:13:44,796 [salt.utils.schedule:1377][INFO    ][5242] Running scheduled job: __mine_interval
2019-03-28 08:13:52,545 [salt.state       :300 ][INFO    ][15697] {'ret': True}
2019-03-28 08:13:52,546 [salt.state       :1951][INFO    ][15697] Completed state [maas.wait_for_machine_status] at time 08:13:52.546003 duration_in_ms=757279.697
2019-03-28 08:13:52,553 [salt.minion      :1711][INFO    ][15697] Returning information for job: 20190328080107423701
2019-03-28 09:13:44,796 [salt.utils.schedule:1377][INFO    ][5242] Running scheduled job: __mine_interval
2019-03-28 09:47:40,493 [salt.minion      :1308][INFO    ][5242] User sudo_ubuntu Executing command cp.push_dir with jid 20190328094740479490
2019-03-28 09:47:40,534 [salt.minion      :1432][INFO    ][27075] Starting a new job with PID 27075
