2019-04-26 02:06:36,542 [salt.utils.decorators:613 ][WARNING ][1978] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 02:06:37,561 [salt.utils.decorators:613 ][WARNING ][1978] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 02:06:41,181 [salt.loaded.int.states.file:2298][WARNING ][2586] 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-04-26 02:07:12,935 [salt.state       :2022][WARNING ][3045] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-26 02:07:16,029 [salt.utils.decorators:613 ][WARNING ][3045] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 02:07:29,212 [salt.utils.decorators:613 ][WARNING ][3045] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 02:07:52,223 [salt.utils.decorators:613 ][WARNING ][3045] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 02:07:53,131 [salt.utils.decorators:613 ][WARNING ][3045] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 02:07:55,213 [salt.loaded.ext.module.maasng:1008][WARNING ][3045] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-04-26 02:07:55,214 [salt.loaded.ext.module.maasng:1011][WARNING ][3045] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-04-26 02:07:55,870 [salt.loaded.ext.module.maasng:1235][WARNING ][3045] Ignoring parameter vlan:0
2019-04-26 02:08:08,303 [salt.utils.decorators:613 ][WARNING ][5462] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 02:08:08,357 [salt.loaded.ext.module.maas:412 ][WARNING ][5462] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-26 02:08:09,681 [salt.loaded.ext.module.maas:412 ][WARNING ][5462] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-26 02:08:10,874 [salt.loaded.ext.module.maas:412 ][WARNING ][5462] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-26 02:08:12,019 [salt.loaded.ext.module.maas:412 ][WARNING ][5462] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-26 02:08:13,129 [salt.loaded.ext.module.maas:412 ][WARNING ][5462] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-26 02:08:19,191 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6065] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-26 02:08:19,222 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6065] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-26 02:08:19,262 [salt.utils.parsers:1051][WARNING ][383] Minion received a SIGTERM. Exiting.
2019-04-26 02:08:20,377 [salt.cli.daemons :293 ][INFO    ][6154] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-04-26 02:08:20,549 [salt.cli.daemons :82  ][INFO    ][6154] Starting up the Salt Minion
2019-04-26 02:08:20,549 [salt.utils.event :1017][INFO    ][6154] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-04-26 02:08:21,804 [salt.minion      :976 ][INFO    ][6154] Creating minion process manager
2019-04-26 02:08:23,895 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6154] Executing command ['date', '+%z'] in directory '/root'
2019-04-26 02:08:23,924 [salt.utils.schedule:568 ][INFO    ][6154] Updating job settings for scheduled job: __mine_interval
2019-04-26 02:08:23,925 [salt.minion      :1108][INFO    ][6154] Added mine.update to scheduler
2019-04-26 02:08:23,930 [salt.minion      :1975][INFO    ][6154] Minion is starting as user 'root'
2019-04-26 02:08:23,950 [salt.minion      :2336][INFO    ][6154] Minion is ready to receive requests!
2019-04-26 02:08:45,644 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command state.apply with jid 20190426020845631336
2019-04-26 02:08:45,667 [salt.minion      :1432][INFO    ][6252] Starting a new job with PID 6252
2019-04-26 02:08:53,548 [salt.state       :915 ][INFO    ][6252] Loading fresh modules for state activity
2019-04-26 02:08:53,597 [salt.fileclient  :1219][INFO    ][6252] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-26 02:08:53,644 [salt.state       :1780][INFO    ][6252] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:08:53.644140
2019-04-26 02:08:53,644 [salt.state       :1813][INFO    ][6252] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-26 02:08:53,646 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6252] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-26 02:08:55,489 [salt.state       :300 ][INFO    ][6252] {'pid': 6263, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-26 02:08:55,490 [salt.state       :1951][INFO    ][6252] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:08:55.490383 duration_in_ms=1846.242
2019-04-26 02:08:55,495 [salt.state       :1780][INFO    ][6252] Running state [maas.wait_for_machine_status] at time 02:08:55.494864
2019-04-26 02:08:55,495 [salt.state       :1813][INFO    ][6252] Executing state module.run for [maas.wait_for_machine_status]
2019-04-26 02:08:55,496 [salt.utils.decorators:613 ][WARNING ][6252] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 02:08:56,285 [salt.loaded.ext.module.maas:1023][INFO    ][6252] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.22585487s left)
2019-04-26 02:09:00,743 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426020900733538
2019-04-26 02:09:00,764 [salt.minion      :1432][INFO    ][6287] Starting a new job with PID 6287
2019-04-26 02:09:00,792 [salt.minion      :1711][INFO    ][6287] Returning information for job: 20190426020900733538
2019-04-26 02:09:27,093 [salt.loaded.ext.module.maas:1023][INFO    ][6252] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.41840601s left)
2019-04-26 02:09:30,813 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426020930799824
2019-04-26 02:09:30,835 [salt.minion      :1432][INFO    ][6357] Starting a new job with PID 6357
2019-04-26 02:09:30,861 [salt.minion      :1711][INFO    ][6357] Returning information for job: 20190426020930799824
2019-04-26 02:09:57,881 [salt.loaded.ext.module.maas:1023][INFO    ][6252] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.62983584s left)
2019-04-26 02:10:00,905 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426021000887246
2019-04-26 02:10:00,920 [salt.minion      :1432][INFO    ][6386] Starting a new job with PID 6386
2019-04-26 02:10:00,944 [salt.minion      :1711][INFO    ][6386] Returning information for job: 20190426021000887246
2019-04-26 02:10:28,744 [salt.loaded.ext.module.maas:1023][INFO    ][6252] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.76727891s left)
2019-04-26 02:10:30,961 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426021030950827
2019-04-26 02:10:30,984 [salt.minion      :1432][INFO    ][6457] Starting a new job with PID 6457
2019-04-26 02:10:31,010 [salt.minion      :1711][INFO    ][6457] Returning information for job: 20190426021030950827
2019-04-26 02:10:59,805 [salt.loaded.ext.module.maas:1023][INFO    ][6252] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.70592093s left)
2019-04-26 02:11:01,020 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426021101009681
2019-04-26 02:11:01,041 [salt.minion      :1432][INFO    ][6510] Starting a new job with PID 6510
2019-04-26 02:11:01,069 [salt.minion      :1711][INFO    ][6510] Returning information for job: 20190426021101009681
2019-04-26 02:11:30,934 [salt.loaded.ext.module.maas:1023][INFO    ][6252] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.57722998s left)
2019-04-26 02:11:31,094 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426021131086366
2019-04-26 02:11:31,106 [salt.minion      :1432][INFO    ][6732] Starting a new job with PID 6732
2019-04-26 02:11:31,137 [salt.minion      :1711][INFO    ][6732] Returning information for job: 20190426021131086366
2019-04-26 02:12:01,182 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426021201168905
2019-04-26 02:12:01,201 [salt.minion      :1432][INFO    ][6821] Starting a new job with PID 6821
2019-04-26 02:12:01,230 [salt.minion      :1711][INFO    ][6821] Returning information for job: 20190426021201168905
2019-04-26 02:12:02,226 [salt.loaded.ext.module.maas:1023][INFO    ][6252] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.28558087s left)
2019-04-26 02:12:31,296 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426021231286696
2019-04-26 02:12:31,308 [salt.minion      :1432][INFO    ][7129] Starting a new job with PID 7129
2019-04-26 02:12:31,337 [salt.minion      :1711][INFO    ][7129] Returning information for job: 20190426021231286696
2019-04-26 02:12:33,406 [salt.loaded.ext.module.maas:1023][INFO    ][6252] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.10573101s left)
2019-04-26 02:13:01,391 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426021301378439
2019-04-26 02:13:01,412 [salt.minion      :1432][INFO    ][7259] Starting a new job with PID 7259
2019-04-26 02:13:01,446 [salt.minion      :1711][INFO    ][7259] Returning information for job: 20190426021301378439
2019-04-26 02:13:05,553 [salt.loaded.ext.module.maas:1023][INFO    ][6252] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.95837283s left)
2019-04-26 02:13:31,577 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426021331563346
2019-04-26 02:13:31,595 [salt.minion      :1432][INFO    ][7833] Starting a new job with PID 7833
2019-04-26 02:13:31,621 [salt.minion      :1711][INFO    ][7833] Returning information for job: 20190426021331563346
2019-04-26 02:13:37,381 [salt.loaded.ext.module.maas:1023][INFO    ][6252] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.13070893s left)
2019-04-26 02:14:01,665 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426021401651015
2019-04-26 02:14:01,686 [salt.minion      :1432][INFO    ][7878] Starting a new job with PID 7878
2019-04-26 02:14:01,711 [salt.minion      :1711][INFO    ][7878] Returning information for job: 20190426021401651015
2019-04-26 02:14:09,541 [salt.loaded.ext.module.maas:1023][INFO    ][6252] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.97049093s left)
2019-04-26 02:14:31,794 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426021431783466
2019-04-26 02:14:31,815 [salt.minion      :1432][INFO    ][8094] Starting a new job with PID 8094
2019-04-26 02:14:31,845 [salt.minion      :1711][INFO    ][8094] Returning information for job: 20190426021431783466
2019-04-26 02:14:42,182 [salt.state       :300 ][INFO    ][6252] {'ret': True}
2019-04-26 02:14:42,182 [salt.state       :1951][INFO    ][6252] Completed state [maas.wait_for_machine_status] at time 02:14:42.182530 duration_in_ms=346687.666
2019-04-26 02:14:42,186 [salt.minion      :1711][INFO    ][6252] Returning information for job: 20190426020845631336
2019-04-26 02:14:42,844 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command state.apply with jid 20190426021442836843
2019-04-26 02:14:42,857 [salt.minion      :1432][INFO    ][8133] Starting a new job with PID 8133
2019-04-26 02:14:50,802 [salt.state       :915 ][INFO    ][8133] Loading fresh modules for state activity
2019-04-26 02:14:50,854 [salt.fileclient  :1219][INFO    ][8133] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-26 02:14:50,948 [salt.state       :1780][INFO    ][8133] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:50.948382
2019-04-26 02:14:50,948 [salt.state       :1813][INFO    ][8133] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-26 02:14:50,950 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8133] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-26 02:14:52,801 [salt.state       :300 ][INFO    ][8133] {'pid': 8145, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-26 02:14:52,802 [salt.state       :1951][INFO    ][8133] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:52.802393 duration_in_ms=1854.01
2019-04-26 02:14:52,806 [salt.state       :1780][INFO    ][8133] Running state [maas_machines_storage_cmp002_lvm] at time 02:14:52.806707
2019-04-26 02:14:52,807 [salt.state       :1813][INFO    ][8133] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-26 02:14:53,861 [salt.loaded.ext.module.maasng:610 ][INFO    ][8133] 7xpnbr
2019-04-26 02:14:53,861 [salt.loaded.ext.module.maasng:626 ][INFO    ][8133] sda
2019-04-26 02:14:54,409 [salt.loaded.ext.module.maasng:361 ][INFO    ][8133] 7xpnbr
2019-04-26 02:14:54,503 [salt.loaded.ext.module.maasng:367 ][INFO    ][8133] [{u'block_size': 4096, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/7xpnbr/blockdevices/1/', u'type': u'physical', u'id': 1, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'7xpnbr', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'0a386c0f-3263-4cca-83aa-964317de77c4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7xpnbr', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'ac6e28ca-712f-4826-b806-4dcb4b3a251e', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/7xpnbr/blockdevices/1/partition/1'}]}, {u'block_size': 4096, u'uuid': u'1a8d5ef3-abf6-4c54-9cd4-106ff012aaa3', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/7xpnbr/blockdevices/3/', u'type': u'virtual', u'id': 3, u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'7xpnbr', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'ee27c03d-3a93-4a56-a027-579fe82e29fe', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'size': 800097042432, u'used_size': 800097042432, u'model': None, u'partitions': []}]
2019-04-26 02:14:54,504 [salt.loaded.ext.module.maasng:632 ][INFO    ][8133] vgroot
2019-04-26 02:14:54,504 [salt.loaded.ext.module.maasng:635 ][INFO    ][8133] lvroot
2019-04-26 02:14:54,504 [salt.loaded.ext.module.maasng:639 ][INFO    ][8133] 107374182400
2019-04-26 02:14:55,101 [salt.loaded.ext.module.maasng:645 ][INFO    ][8133] {u'hwe_kernel': u'', u'swap_size': None, 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', 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'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', 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'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', 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'parents': [], u'system_id': u'7xpnbr', u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/7xpnbr/interfaces/4/'}, u'disable_ipv4': False, u'cpu_count': 40, u'owner_data': {}, 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'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/7xpnbr/blockdevices/1/', u'system_id': u'7xpnbr', u'type': u'physical', u'partitions': [{u'uuid': u'b87bdc6d-b383-41af-a328-bc7eb924fdaf', u'resource_uri': u'/MAAS/api/2.0/nodes/7xpnbr/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7xpnbr', u'filesystem': {u'uuid': u'20a82d4a-e256-4e82-93cd-3251245241ed', 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'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id': 1, 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'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'7xpnbr', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'blockdevice_set': [{u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/7xpnbr/blockdevices/1/', u'system_id': u'7xpnbr', u'type': u'physical', u'partitions': [{u'uuid': u'b87bdc6d-b383-41af-a328-bc7eb924fdaf', u'resource_uri': u'/MAAS/api/2.0/nodes/7xpnbr/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7xpnbr', u'filesystem': {u'uuid': u'20a82d4a-e256-4e82-93cd-3251245241ed', 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'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'id': 1, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/7xpnbr/blockdevices/11/', u'system_id': u'7xpnbr', u'type': u'virtual', u'partitions': [], u'tags': [], u'uuid': u'73e33b53-4fe6-4168-8a9a-e8695fa08a18', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'id': 11, u'partition_table_type': None, u'filesystem': {u'uuid': u'6e2a8e82-63cd-4287-8e82-0e1f963c8be6', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'model': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/7xpnbr/blockdevices/1/', u'system_id': u'7xpnbr', u'type': u'physical', u'partitions': [{u'uuid': u'b87bdc6d-b383-41af-a328-bc7eb924fdaf', u'resource_uri': u'/MAAS/api/2.0/nodes/7xpnbr/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7xpnbr', u'filesystem': {u'uuid': u'20a82d4a-e256-4e82-93cd-3251245241ed', 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'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id': 1, 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'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'7xpnbr', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', 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'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', 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'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', 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'parents': [], u'system_id': u'7xpnbr', u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/7xpnbr/interfaces/4/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'7xpnbr', u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/7xpnbr/interfaces/14/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'7xpnbr', u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/7xpnbr/interfaces/15/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': 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'children': [], u'discovered': None, u'parents': [], u'system_id': u'7xpnbr', u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/7xpnbr/interfaces/11/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', 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'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', 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'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', 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'parents': [], u'system_id': u'7xpnbr', u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/7xpnbr/interfaces/12/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': 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'children': [], u'discovered': None, u'parents': [], u'system_id': u'7xpnbr', u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/7xpnbr/interfaces/13/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/7xpnbr/blockdevices/11/', u'system_id': u'7xpnbr', u'type': u'virtual', u'partitions': [], u'tags': [], u'uuid': u'73e33b53-4fe6-4168-8a9a-e8695fa08a18', u'used_for': u'ext4 formatted filesystem mounted at /', u'id': 11, u'partition_table_type': None, u'filesystem': {u'uuid': u'6e2a8e82-63cd-4287-8e82-0e1f963c8be6', 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/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'model': None, u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/7xpnbr/'}
2019-04-26 02:14:55,103 [salt.state       :300 ][INFO    ][8133] {'new': {'storage_layout': 'lvm'}}
2019-04-26 02:14:55,103 [salt.state       :1951][INFO    ][8133] Completed state [maas_machines_storage_cmp002_lvm] at time 02:14:55.103792 duration_in_ms=2297.085
2019-04-26 02:14:55,104 [salt.state       :1780][INFO    ][8133] Running state [maas_machines_storage_cmp001_lvm] at time 02:14:55.104177
2019-04-26 02:14:55,104 [salt.state       :1813][INFO    ][8133] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-26 02:14:56,288 [salt.loaded.ext.module.maasng:610 ][INFO    ][8133] d3waqd
2019-04-26 02:14:56,288 [salt.loaded.ext.module.maasng:626 ][INFO    ][8133] sda
2019-04-26 02:14:56,843 [salt.loaded.ext.module.maasng:361 ][INFO    ][8133] d3waqd
2019-04-26 02:14:56,932 [salt.loaded.ext.module.maasng:367 ][INFO    ][8133] [{u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/d3waqd/blockdevices/2/', u'system_id': u'd3waqd', u'type': u'physical', u'partitions': [{u'uuid': u'40b9a4ce-6d76-4915-b021-2f523e660218', u'resource_uri': u'/MAAS/api/2.0/nodes/d3waqd/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'd3waqd', u'filesystem': {u'uuid': u'3d285513-d6d0-4592-bf64-00bee6a0bdad', 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'size': 800101236736, u'type': u'partition', u'id': 2, u'device_id': 2}], u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'id': 2, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/d3waqd/blockdevices/4/', u'system_id': u'd3waqd', u'type': u'virtual', u'partitions': [], u'tags': [], u'uuid': u'b4a3cfb3-cf17-4fb4-9b18-214ca7865670', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'id': 4, u'partition_table_type': None, u'filesystem': {u'uuid': u'90a1d451-ff39-461f-b9b3-7e969289a166', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'model': None, u'size': 800097042432}]
2019-04-26 02:14:56,933 [salt.loaded.ext.module.maasng:632 ][INFO    ][8133] vgroot
2019-04-26 02:14:56,933 [salt.loaded.ext.module.maasng:635 ][INFO    ][8133] lvroot
2019-04-26 02:14:56,933 [salt.loaded.ext.module.maasng:639 ][INFO    ][8133] 107374182400
2019-04-26 02:14:57,524 [salt.loaded.ext.module.maasng:645 ][INFO    ][8133] {u'hwe_kernel': u'', u'swap_size': None, 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', 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'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', 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'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', 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'system_id': u'd3waqd', u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/d3waqd/interfaces/5/'}, u'disable_ipv4': False, u'cpu_count': 40, u'owner_data': {}, 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'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/d3waqd/blockdevices/2/', u'system_id': u'd3waqd', u'type': u'physical', u'partitions': [{u'uuid': u'e9864932-7635-42c5-afa7-8fc15d1cb3af', u'resource_uri': u'/MAAS/api/2.0/nodes/d3waqd/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'd3waqd', u'filesystem': {u'uuid': u'1c7408b0-853f-47cf-a7ca-7eb11a4ce051', 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'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}], u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id': 2, 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'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'd3waqd', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'blockdevice_set': [{u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/d3waqd/blockdevices/2/', u'system_id': u'd3waqd', u'type': u'physical', u'partitions': [{u'uuid': u'e9864932-7635-42c5-afa7-8fc15d1cb3af', u'resource_uri': u'/MAAS/api/2.0/nodes/d3waqd/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'd3waqd', u'filesystem': {u'uuid': u'1c7408b0-853f-47cf-a7ca-7eb11a4ce051', 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'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}], u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'id': 2, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/d3waqd/blockdevices/12/', u'system_id': u'd3waqd', u'type': u'virtual', u'partitions': [], u'tags': [], u'uuid': u'0102d47b-610f-40cd-baff-d37875140fe0', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'id': 12, u'partition_table_type': None, u'filesystem': {u'uuid': u'5645405c-b890-40d6-acc5-7b1bbd89e516', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'model': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/d3waqd/blockdevices/2/', u'system_id': u'd3waqd', u'type': u'physical', u'partitions': [{u'uuid': u'e9864932-7635-42c5-afa7-8fc15d1cb3af', u'resource_uri': u'/MAAS/api/2.0/nodes/d3waqd/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'd3waqd', u'filesystem': {u'uuid': u'1c7408b0-853f-47cf-a7ca-7eb11a4ce051', 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'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}], u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id': 2, 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'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'd3waqd', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', 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'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', 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'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', 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'system_id': u'd3waqd', u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/d3waqd/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'd3waqd', u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/d3waqd/interfaces/17/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'd3waqd', u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/d3waqd/interfaces/19/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': 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'children': [], u'discovered': None, u'parents': [], u'system_id': u'd3waqd', u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/d3waqd/interfaces/16/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': 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'children': [], u'discovered': None, u'parents': [], u'system_id': u'd3waqd', u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/d3waqd/interfaces/18/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', 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'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', 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'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', 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.41'}], u'parents': [], u'system_id': u'd3waqd', u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/d3waqd/interfaces/20/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/d3waqd/blockdevices/12/', u'system_id': u'd3waqd', u'type': u'virtual', u'partitions': [], u'tags': [], u'uuid': u'0102d47b-610f-40cd-baff-d37875140fe0', u'used_for': u'ext4 formatted filesystem mounted at /', u'id': 12, u'partition_table_type': None, u'filesystem': {u'uuid': u'5645405c-b890-40d6-acc5-7b1bbd89e516', 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/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'model': None, u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/d3waqd/'}
2019-04-26 02:14:57,527 [salt.state       :300 ][INFO    ][8133] {'new': {'storage_layout': 'lvm'}}
2019-04-26 02:14:57,528 [salt.state       :1951][INFO    ][8133] Completed state [maas_machines_storage_cmp001_lvm] at time 02:14:57.528127 duration_in_ms=2423.949
2019-04-26 02:14:57,533 [salt.minion      :1711][INFO    ][8133] Returning information for job: 20190426021442836843
2019-04-26 02:14:58,218 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command state.apply with jid 20190426021458207331
2019-04-26 02:14:58,233 [salt.minion      :1432][INFO    ][8179] Starting a new job with PID 8179
2019-04-26 02:14:59,389 [salt.state       :915 ][INFO    ][8179] Loading fresh modules for state activity
2019-04-26 02:14:59,449 [salt.fileclient  :1219][INFO    ][8179] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-26 02:14:59,501 [salt.state       :1780][INFO    ][8179] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:59.501181
2019-04-26 02:14:59,501 [salt.state       :1813][INFO    ][8179] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-26 02:14:59,503 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8179] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-26 02:15:01,360 [salt.state       :300 ][INFO    ][8179] {'pid': 8198, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-26 02:15:01,361 [salt.state       :1951][INFO    ][8179] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:01.361181 duration_in_ms=1859.999
2019-04-26 02:15:01,364 [salt.state       :1780][INFO    ][8179] Running state [maas.deploy_machines] at time 02:15:01.364244
2019-04-26 02:15:01,364 [salt.state       :1813][INFO    ][8179] Executing state module.run for [maas.deploy_machines]
2019-04-26 02:15:01,365 [salt.utils.decorators:613 ][WARNING ][8179] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 02:15:01,920 [salt.loaded.ext.module.maas:684 ][INFO    ][8179] deploymachines hwe_kernel=ga-16.04 system_id=7xpnbr distro_series=xenial
2019-04-26 02:15:04,244 [salt.loaded.ext.module.maas:684 ][INFO    ][8179] deploymachines hwe_kernel=ga-16.04 system_id=d3waqd distro_series=xenial
2019-04-26 02:15:06,594 [salt.loaded.ext.module.maas:684 ][INFO    ][8179] deploymachines hwe_kernel=ga-16.04 system_id=pwqhw3 distro_series=xenial
2019-04-26 02:15:08,923 [salt.loaded.ext.module.maas:684 ][INFO    ][8179] deploymachines hwe_kernel=ga-16.04 system_id=cnkwfh distro_series=xenial
2019-04-26 02:15:11,312 [salt.loaded.ext.module.maas:684 ][INFO    ][8179] deploymachines hwe_kernel=ga-16.04 system_id=apwgpg distro_series=xenial
2019-04-26 02:15:13,342 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426021513332733
2019-04-26 02:15:13,363 [salt.minion      :1432][INFO    ][8457] Starting a new job with PID 8457
2019-04-26 02:15:13,394 [salt.minion      :1711][INFO    ][8457] Returning information for job: 20190426021513332733
2019-04-26 02:15:13,665 [salt.state       :300 ][INFO    ][8179] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-26 02:15:13,665 [salt.state       :1951][INFO    ][8179] Completed state [maas.deploy_machines] at time 02:15:13.665504 duration_in_ms=12301.259
2019-04-26 02:15:13,669 [salt.minion      :1711][INFO    ][8179] Returning information for job: 20190426021458207331
2019-04-26 02:15:14,361 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command state.apply with jid 20190426021514347773
2019-04-26 02:15:14,381 [salt.minion      :1432][INFO    ][8472] Starting a new job with PID 8472
2019-04-26 02:15:22,632 [salt.state       :915 ][INFO    ][8472] Loading fresh modules for state activity
2019-04-26 02:15:22,687 [salt.fileclient  :1219][INFO    ][8472] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-26 02:15:22,730 [salt.state       :1780][INFO    ][8472] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:22.730408
2019-04-26 02:15:22,730 [salt.state       :1813][INFO    ][8472] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-26 02:15:22,735 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8472] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-26 02:15:24,668 [salt.state       :300 ][INFO    ][8472] {'pid': 8532, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-26 02:15:24,669 [salt.state       :1951][INFO    ][8472] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:24.669260 duration_in_ms=1938.85
2019-04-26 02:15:24,673 [salt.state       :1780][INFO    ][8472] Running state [maas.wait_for_machine_status] at time 02:15:24.673069
2019-04-26 02:15:24,673 [salt.state       :1813][INFO    ][8472] Executing state module.run for [maas.wait_for_machine_status]
2019-04-26 02:15:24,674 [salt.utils.decorators:613 ][WARNING ][8472] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 02:15:27,630 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.0576458s left)
2019-04-26 02:15:29,392 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426021529377543
2019-04-26 02:15:29,411 [salt.minion      :1432][INFO    ][8551] Starting a new job with PID 8551
2019-04-26 02:15:29,441 [salt.minion      :1711][INFO    ][8551] Returning information for job: 20190426021529377543
2019-04-26 02:15:59,443 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426021559432915
2019-04-26 02:15:59,458 [salt.minion      :1432][INFO    ][8579] Starting a new job with PID 8579
2019-04-26 02:15:59,486 [salt.minion      :1711][INFO    ][8579] Returning information for job: 20190426021559432915
2019-04-26 02:16:00,550 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.13810682s left)
2019-04-26 02:16:29,529 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426021629518992
2019-04-26 02:16:29,552 [salt.minion      :1432][INFO    ][8648] Starting a new job with PID 8648
2019-04-26 02:16:29,580 [salt.minion      :1711][INFO    ][8648] Returning information for job: 20190426021629518992
2019-04-26 02:16:33,305 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.38307381s left)
2019-04-26 02:16:59,585 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426021659570759
2019-04-26 02:16:59,602 [salt.minion      :1432][INFO    ][8682] Starting a new job with PID 8682
2019-04-26 02:16:59,632 [salt.minion      :1711][INFO    ][8682] Returning information for job: 20190426021659570759
2019-04-26 02:17:06,069 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.61894083s left)
2019-04-26 02:17:29,657 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426021729641380
2019-04-26 02:17:29,676 [salt.minion      :1432][INFO    ][8773] Starting a new job with PID 8773
2019-04-26 02:17:29,705 [salt.minion      :1711][INFO    ][8773] Returning information for job: 20190426021729641380
2019-04-26 02:17:38,533 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2116.15457487s left)
2019-04-26 02:17:59,710 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426021759703622
2019-04-26 02:17:59,724 [salt.minion      :1432][INFO    ][8826] Starting a new job with PID 8826
2019-04-26 02:17:59,749 [salt.minion      :1711][INFO    ][8826] Returning information for job: 20190426021759703622
2019-04-26 02:18:11,489 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.19847894s left)
2019-04-26 02:18:29,789 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426021829774378
2019-04-26 02:18:29,807 [salt.minion      :1432][INFO    ][9040] Starting a new job with PID 9040
2019-04-26 02:18:29,830 [salt.minion      :1711][INFO    ][9040] Returning information for job: 20190426021829774378
2019-04-26 02:18:44,507 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2050.1806519s left)
2019-04-26 02:18:59,875 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426021859863651
2019-04-26 02:18:59,894 [salt.minion      :1432][INFO    ][9081] Starting a new job with PID 9081
2019-04-26 02:18:59,922 [salt.minion      :1711][INFO    ][9081] Returning information for job: 20190426021859863651
2019-04-26 02:19:18,345 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.34336686s left)
2019-04-26 02:19:29,961 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426021929953497
2019-04-26 02:19:29,975 [salt.minion      :1432][INFO    ][9430] Starting a new job with PID 9430
2019-04-26 02:19:29,994 [salt.minion      :1711][INFO    ][9430] Returning information for job: 20190426021929953497
2019-04-26 02:19:51,257 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.43042898s left)
2019-04-26 02:20:00,029 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426022000017938
2019-04-26 02:20:00,050 [salt.minion      :1432][INFO    ][9516] Starting a new job with PID 9516
2019-04-26 02:20:00,071 [salt.minion      :1711][INFO    ][9516] Returning information for job: 20190426022000017938
2019-04-26 02:20:24,156 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.53198981s left)
2019-04-26 02:20:30,152 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426022030140237
2019-04-26 02:20:30,170 [salt.minion      :1432][INFO    ][9896] Starting a new job with PID 9896
2019-04-26 02:20:30,193 [salt.minion      :1711][INFO    ][9896] Returning information for job: 20190426022030140237
2019-04-26 02:20:57,229 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1917.45859098s left)
2019-04-26 02:21:00,252 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426022100241795
2019-04-26 02:21:00,274 [salt.minion      :1432][INFO    ][9927] Starting a new job with PID 9927
2019-04-26 02:21:00,295 [salt.minion      :1711][INFO    ][9927] Returning information for job: 20190426022100241795
2019-04-26 02:21:29,948 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1884.739645s left)
2019-04-26 02:21:30,368 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426022130354750
2019-04-26 02:21:30,382 [salt.minion      :1432][INFO    ][10190] Starting a new job with PID 10190
2019-04-26 02:21:30,401 [salt.minion      :1711][INFO    ][10190] Returning information for job: 20190426022130354750
2019-04-26 02:22:00,461 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426022200451785
2019-04-26 02:22:00,478 [salt.minion      :1432][INFO    ][10228] Starting a new job with PID 10228
2019-04-26 02:22:00,501 [salt.minion      :1711][INFO    ][10228] Returning information for job: 20190426022200451785
2019-04-26 02:22:02,997 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1851.69088578s left)
2019-04-26 02:22:30,612 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426022230591917
2019-04-26 02:22:30,633 [salt.minion      :1432][INFO    ][10543] Starting a new job with PID 10543
2019-04-26 02:22:30,657 [salt.minion      :1711][INFO    ][10543] Returning information for job: 20190426022230591917
2019-04-26 02:22:35,869 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1818.81875491s left)
2019-04-26 02:23:00,744 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426022300730302
2019-04-26 02:23:00,765 [salt.minion      :1432][INFO    ][10593] Starting a new job with PID 10593
2019-04-26 02:23:00,792 [salt.minion      :1711][INFO    ][10593] Returning information for job: 20190426022300730302
2019-04-26 02:23:08,628 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1786.06002498s left)
2019-04-26 02:23:30,906 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426022330896133
2019-04-26 02:23:30,920 [salt.minion      :1432][INFO    ][10974] Starting a new job with PID 10974
2019-04-26 02:23:30,941 [salt.minion      :1711][INFO    ][10974] Returning information for job: 20190426022330896133
2019-04-26 02:23:41,580 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1753.10808897s left)
2019-04-26 02:24:01,030 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426022401017337
2019-04-26 02:24:01,050 [salt.minion      :1432][INFO    ][11017] Starting a new job with PID 11017
2019-04-26 02:24:01,070 [salt.minion      :1711][INFO    ][11017] Returning information for job: 20190426022401017337
2019-04-26 02:24:14,387 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1720.30125499s left)
2019-04-26 02:24:31,170 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426022431162317
2019-04-26 02:24:31,190 [salt.minion      :1432][INFO    ][11127] Starting a new job with PID 11127
2019-04-26 02:24:31,218 [salt.minion      :1711][INFO    ][11127] Returning information for job: 20190426022431162317
2019-04-26 02:24:47,178 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1687.51021481s left)
2019-04-26 02:25:01,292 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426022501283617
2019-04-26 02:25:01,314 [salt.minion      :1432][INFO    ][11156] Starting a new job with PID 11156
2019-04-26 02:25:01,336 [salt.minion      :1711][INFO    ][11156] Returning information for job: 20190426022501283617
2019-04-26 02:25:20,013 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1654.67509079s left)
2019-04-26 02:25:31,373 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426022531359605
2019-04-26 02:25:31,391 [salt.minion      :1432][INFO    ][11267] Starting a new job with PID 11267
2019-04-26 02:25:31,412 [salt.minion      :1711][INFO    ][11267] Returning information for job: 20190426022531359605
2019-04-26 02:25:52,809 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1621.87908888s left)
2019-04-26 02:26:01,450 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426022601439699
2019-04-26 02:26:01,473 [salt.minion      :1432][INFO    ][11340] Starting a new job with PID 11340
2019-04-26 02:26:01,499 [salt.minion      :1711][INFO    ][11340] Returning information for job: 20190426022601439699
2019-04-26 02:26:25,645 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1589.04252791s left)
2019-04-26 02:26:31,657 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426022631650513
2019-04-26 02:26:31,676 [salt.minion      :1432][INFO    ][11618] Starting a new job with PID 11618
2019-04-26 02:26:31,694 [salt.minion      :1711][INFO    ][11618] Returning information for job: 20190426022631650513
2019-04-26 02:26:58,277 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1556.41079187s left)
2019-04-26 02:27:01,793 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426022701779689
2019-04-26 02:27:01,814 [salt.minion      :1432][INFO    ][11778] Starting a new job with PID 11778
2019-04-26 02:27:01,836 [salt.minion      :1711][INFO    ][11778] Returning information for job: 20190426022701779689
2019-04-26 02:27:31,105 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1523.58276391s left)
2019-04-26 02:27:31,969 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426022731955512
2019-04-26 02:27:31,989 [salt.minion      :1432][INFO    ][11884] Starting a new job with PID 11884
2019-04-26 02:27:32,016 [salt.minion      :1711][INFO    ][11884] Returning information for job: 20190426022731955512
2019-04-26 02:28:02,169 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426022802160261
2019-04-26 02:28:02,184 [salt.minion      :1432][INFO    ][11977] Starting a new job with PID 11977
2019-04-26 02:28:02,208 [salt.minion      :1711][INFO    ][11977] Returning information for job: 20190426022802160261
2019-04-26 02:28:03,802 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1490.88596988s left)
2019-04-26 02:28:32,197 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426022832184709
2019-04-26 02:28:32,217 [salt.minion      :1432][INFO    ][12269] Starting a new job with PID 12269
2019-04-26 02:28:32,241 [salt.minion      :1711][INFO    ][12269] Returning information for job: 20190426022832184709
2019-04-26 02:28:36,622 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1458.06591082s left)
2019-04-26 02:29:02,381 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426022902367628
2019-04-26 02:29:02,400 [salt.minion      :1432][INFO    ][12298] Starting a new job with PID 12298
2019-04-26 02:29:02,429 [salt.minion      :1711][INFO    ][12298] Returning information for job: 20190426022902367628
2019-04-26 02:29:09,429 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1425.25848699s left)
2019-04-26 02:29:32,610 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426022932595753
2019-04-26 02:29:32,633 [salt.minion      :1432][INFO    ][12370] Starting a new job with PID 12370
2019-04-26 02:29:32,653 [salt.minion      :1711][INFO    ][12370] Returning information for job: 20190426022932595753
2019-04-26 02:29:42,132 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1392.55616593s left)
2019-04-26 02:30:02,829 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426023002819335
2019-04-26 02:30:02,842 [salt.minion      :1432][INFO    ][12402] Starting a new job with PID 12402
2019-04-26 02:30:02,864 [salt.minion      :1711][INFO    ][12402] Returning information for job: 20190426023002819335
2019-04-26 02:30:14,977 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1359.71092892s left)
2019-04-26 02:30:33,035 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426023033023980
2019-04-26 02:30:33,054 [salt.minion      :1432][INFO    ][12475] Starting a new job with PID 12475
2019-04-26 02:30:33,076 [salt.minion      :1711][INFO    ][12475] Returning information for job: 20190426023033023980
2019-04-26 02:30:47,784 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1326.90370893s left)
2019-04-26 02:31:03,078 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426023103064560
2019-04-26 02:31:03,101 [salt.minion      :1432][INFO    ][12505] Starting a new job with PID 12505
2019-04-26 02:31:03,121 [salt.minion      :1711][INFO    ][12505] Returning information for job: 20190426023103064560
2019-04-26 02:31:20,527 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1294.16081595s left)
2019-04-26 02:31:33,117 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426023133103437
2019-04-26 02:31:33,139 [salt.minion      :1432][INFO    ][12578] Starting a new job with PID 12578
2019-04-26 02:31:33,167 [salt.minion      :1711][INFO    ][12578] Returning information for job: 20190426023133103437
2019-04-26 02:31:53,261 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1261.42677188s left)
2019-04-26 02:32:03,186 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426023203170348
2019-04-26 02:32:03,204 [salt.minion      :1432][INFO    ][12614] Starting a new job with PID 12614
2019-04-26 02:32:03,225 [salt.minion      :1711][INFO    ][12614] Returning information for job: 20190426023203170348
2019-04-26 02:32:25,953 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1228.73451281s left)
2019-04-26 02:32:33,234 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426023233220326
2019-04-26 02:32:33,254 [salt.minion      :1432][INFO    ][12687] Starting a new job with PID 12687
2019-04-26 02:32:33,275 [salt.minion      :1711][INFO    ][12687] Returning information for job: 20190426023233220326
2019-04-26 02:32:58,829 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1195.85904098s left)
2019-04-26 02:33:03,321 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426023303306915
2019-04-26 02:33:03,344 [salt.minion      :1432][INFO    ][12719] Starting a new job with PID 12719
2019-04-26 02:33:03,366 [salt.minion      :1711][INFO    ][12719] Returning information for job: 20190426023303306915
2019-04-26 02:33:31,519 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1163.16866589s left)
2019-04-26 02:33:33,396 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426023333385817
2019-04-26 02:33:33,417 [salt.minion      :1432][INFO    ][12790] Starting a new job with PID 12790
2019-04-26 02:33:33,440 [salt.minion      :1711][INFO    ][12790] Returning information for job: 20190426023333385817
2019-04-26 02:34:03,508 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426023403499686
2019-04-26 02:34:03,524 [salt.minion      :1432][INFO    ][12821] Starting a new job with PID 12821
2019-04-26 02:34:03,548 [salt.minion      :1711][INFO    ][12821] Returning information for job: 20190426023403499686
2019-04-26 02:34:04,189 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1130.4992919s left)
2019-04-26 02:34:33,608 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426023433595178
2019-04-26 02:34:33,622 [salt.minion      :1432][INFO    ][12890] Starting a new job with PID 12890
2019-04-26 02:34:33,647 [salt.minion      :1711][INFO    ][12890] Returning information for job: 20190426023433595178
2019-04-26 02:34:36,967 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1097.72100282s left)
2019-04-26 02:35:03,740 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426023503731932
2019-04-26 02:35:03,761 [salt.minion      :1432][INFO    ][12922] Starting a new job with PID 12922
2019-04-26 02:35:03,781 [salt.minion      :1711][INFO    ][12922] Returning information for job: 20190426023503731932
2019-04-26 02:35:09,669 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1065.01857901s left)
2019-04-26 02:35:33,841 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426023533827828
2019-04-26 02:35:33,861 [salt.minion      :1432][INFO    ][12993] Starting a new job with PID 12993
2019-04-26 02:35:33,886 [salt.minion      :1711][INFO    ][12993] Returning information for job: 20190426023533827828
2019-04-26 02:35:42,426 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1032.26169801s left)
2019-04-26 02:36:04,013 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426023603998585
2019-04-26 02:36:04,031 [salt.minion      :1432][INFO    ][13025] Starting a new job with PID 13025
2019-04-26 02:36:04,056 [salt.minion      :1711][INFO    ][13025] Returning information for job: 20190426023603998585
2019-04-26 02:36:15,215 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (999.472665787s left)
2019-04-26 02:36:34,180 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426023634171611
2019-04-26 02:36:34,196 [salt.minion      :1432][INFO    ][13096] Starting a new job with PID 13096
2019-04-26 02:36:34,219 [salt.minion      :1711][INFO    ][13096] Returning information for job: 20190426023634171611
2019-04-26 02:36:48,045 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (966.642743826s left)
2019-04-26 02:37:04,341 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426023704328264
2019-04-26 02:37:04,358 [salt.minion      :1432][INFO    ][13132] Starting a new job with PID 13132
2019-04-26 02:37:04,384 [salt.minion      :1711][INFO    ][13132] Returning information for job: 20190426023704328264
2019-04-26 02:37:20,736 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (933.951723814s left)
2019-04-26 02:37:34,555 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426023734540394
2019-04-26 02:37:34,576 [salt.minion      :1432][INFO    ][13222] Starting a new job with PID 13222
2019-04-26 02:37:34,600 [salt.minion      :1711][INFO    ][13222] Returning information for job: 20190426023734540394
2019-04-26 02:37:53,413 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (901.274745941s left)
2019-04-26 02:38:04,737 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426023804724410
2019-04-26 02:38:04,758 [salt.minion      :1432][INFO    ][13252] Starting a new job with PID 13252
2019-04-26 02:38:04,782 [salt.minion      :1711][INFO    ][13252] Returning information for job: 20190426023804724410
2019-04-26 02:38:26,292 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (868.39594388s left)
2019-04-26 02:38:34,785 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426023834770459
2019-04-26 02:38:34,808 [salt.minion      :1432][INFO    ][13326] Starting a new job with PID 13326
2019-04-26 02:38:34,832 [salt.minion      :1711][INFO    ][13326] Returning information for job: 20190426023834770459
2019-04-26 02:38:59,049 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (835.639093876s left)
2019-04-26 02:39:04,806 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426023904793788
2019-04-26 02:39:04,823 [salt.minion      :1432][INFO    ][13358] Starting a new job with PID 13358
2019-04-26 02:39:04,848 [salt.minion      :1711][INFO    ][13358] Returning information for job: 20190426023904793788
2019-04-26 02:39:31,694 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (802.993642807s left)
2019-04-26 02:39:34,853 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426023934836925
2019-04-26 02:39:34,874 [salt.minion      :1432][INFO    ][13429] Starting a new job with PID 13429
2019-04-26 02:39:34,897 [salt.minion      :1711][INFO    ][13429] Returning information for job: 20190426023934836925
2019-04-26 02:40:04,378 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (770.310068846s left)
2019-04-26 02:40:04,882 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426024004873913
2019-04-26 02:40:04,902 [salt.minion      :1432][INFO    ][13459] Starting a new job with PID 13459
2019-04-26 02:40:04,926 [salt.minion      :1711][INFO    ][13459] Returning information for job: 20190426024004873913
2019-04-26 02:40:34,988 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426024034978519
2019-04-26 02:40:35,006 [salt.minion      :1432][INFO    ][13528] Starting a new job with PID 13528
2019-04-26 02:40:35,030 [salt.minion      :1711][INFO    ][13528] Returning information for job: 20190426024034978519
2019-04-26 02:40:37,133 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (737.554613829s left)
2019-04-26 02:41:05,048 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426024105032938
2019-04-26 02:41:05,070 [salt.minion      :1432][INFO    ][13559] Starting a new job with PID 13559
2019-04-26 02:41:05,092 [salt.minion      :1711][INFO    ][13559] Returning information for job: 20190426024105032938
2019-04-26 02:41:09,805 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (704.882606983s left)
2019-04-26 02:41:35,162 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426024135148632
2019-04-26 02:41:35,183 [salt.minion      :1432][INFO    ][13631] Starting a new job with PID 13631
2019-04-26 02:41:35,208 [salt.minion      :1711][INFO    ][13631] Returning information for job: 20190426024135148632
2019-04-26 02:41:42,490 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (672.19764185s left)
2019-04-26 02:42:05,256 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426024205249536
2019-04-26 02:42:05,269 [salt.minion      :1432][INFO    ][13663] Starting a new job with PID 13663
2019-04-26 02:42:05,291 [salt.minion      :1711][INFO    ][13663] Returning information for job: 20190426024205249536
2019-04-26 02:42:15,296 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (639.392399788s left)
2019-04-26 02:42:35,409 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426024235401157
2019-04-26 02:42:35,425 [salt.minion      :1432][INFO    ][13738] Starting a new job with PID 13738
2019-04-26 02:42:35,451 [salt.minion      :1711][INFO    ][13738] Returning information for job: 20190426024235401157
2019-04-26 02:42:48,102 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (606.58540988s left)
2019-04-26 02:43:05,608 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426024305595328
2019-04-26 02:43:05,630 [salt.minion      :1432][INFO    ][13770] Starting a new job with PID 13770
2019-04-26 02:43:05,654 [salt.minion      :1711][INFO    ][13770] Returning information for job: 20190426024305595328
2019-04-26 02:43:20,875 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (573.813030958s left)
2019-04-26 02:43:35,742 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426024335729096
2019-04-26 02:43:35,760 [salt.minion      :1432][INFO    ][13843] Starting a new job with PID 13843
2019-04-26 02:43:35,785 [salt.minion      :1711][INFO    ][13843] Returning information for job: 20190426024335729096
2019-04-26 02:43:53,561 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (541.127337933s left)
2019-04-26 02:44:05,765 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426024405756736
2019-04-26 02:44:05,783 [salt.minion      :1432][INFO    ][13873] Starting a new job with PID 13873
2019-04-26 02:44:05,805 [salt.minion      :1711][INFO    ][13873] Returning information for job: 20190426024405756736
2019-04-26 02:44:26,261 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (508.427055836s left)
2019-04-26 02:44:35,942 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426024435929872
2019-04-26 02:44:35,963 [salt.minion      :1432][INFO    ][13944] Starting a new job with PID 13944
2019-04-26 02:44:35,987 [salt.minion      :1711][INFO    ][13944] Returning information for job: 20190426024435929872
2019-04-26 02:44:59,208 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (475.479823828s left)
2019-04-26 02:45:06,009 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426024505994597
2019-04-26 02:45:06,030 [salt.minion      :1432][INFO    ][13974] Starting a new job with PID 13974
2019-04-26 02:45:06,053 [salt.minion      :1711][INFO    ][13974] Returning information for job: 20190426024505994597
2019-04-26 02:45:31,857 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (442.830737829s left)
2019-04-26 02:45:36,220 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426024536204302
2019-04-26 02:45:36,240 [salt.minion      :1432][INFO    ][14046] Starting a new job with PID 14046
2019-04-26 02:45:36,264 [salt.minion      :1711][INFO    ][14046] Returning information for job: 20190426024536204302
2019-04-26 02:46:04,636 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (410.05347085s left)
2019-04-26 02:46:06,318 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426024606304509
2019-04-26 02:46:06,341 [salt.minion      :1432][INFO    ][14078] Starting a new job with PID 14078
2019-04-26 02:46:06,365 [salt.minion      :1711][INFO    ][14078] Returning information for job: 20190426024606304509
2019-04-26 02:46:36,371 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426024636361199
2019-04-26 02:46:36,391 [salt.minion      :1432][INFO    ][14152] Starting a new job with PID 14152
2019-04-26 02:46:36,415 [salt.minion      :1711][INFO    ][14152] Returning information for job: 20190426024636361199
2019-04-26 02:46:37,415 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (377.273427963s left)
2019-04-26 02:47:06,540 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426024706525974
2019-04-26 02:47:06,562 [salt.minion      :1432][INFO    ][14312] Starting a new job with PID 14312
2019-04-26 02:47:06,590 [salt.minion      :1711][INFO    ][14312] Returning information for job: 20190426024706525974
2019-04-26 02:47:10,181 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (344.507332802s left)
2019-04-26 02:47:36,620 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426024736607541
2019-04-26 02:47:36,642 [salt.minion      :1432][INFO    ][14402] Starting a new job with PID 14402
2019-04-26 02:47:36,668 [salt.minion      :1711][INFO    ][14402] Returning information for job: 20190426024736607541
2019-04-26 02:47:42,863 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (311.824635983s left)
2019-04-26 02:48:06,813 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426024806799612
2019-04-26 02:48:06,836 [salt.minion      :1432][INFO    ][14432] Starting a new job with PID 14432
2019-04-26 02:48:06,859 [salt.minion      :1711][INFO    ][14432] Returning information for job: 20190426024806799612
2019-04-26 02:48:15,550 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (279.137567997s left)
2019-04-26 02:48:36,931 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426024836923163
2019-04-26 02:48:36,944 [salt.minion      :1432][INFO    ][14503] Starting a new job with PID 14503
2019-04-26 02:48:36,966 [salt.minion      :1711][INFO    ][14503] Returning information for job: 20190426024836923163
2019-04-26 02:48:48,422 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (246.265927792s left)
2019-04-26 02:49:07,103 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426024907091484
2019-04-26 02:49:07,124 [salt.minion      :1432][INFO    ][14533] Starting a new job with PID 14533
2019-04-26 02:49:07,152 [salt.minion      :1711][INFO    ][14533] Returning information for job: 20190426024907091484
2019-04-26 02:49:21,071 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (213.616858006s left)
2019-04-26 02:49:37,124 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426024937113047
2019-04-26 02:49:37,147 [salt.minion      :1432][INFO    ][14607] Starting a new job with PID 14607
2019-04-26 02:49:37,171 [salt.minion      :1711][INFO    ][14607] Returning information for job: 20190426024937113047
2019-04-26 02:49:53,832 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (180.856369972s left)
2019-04-26 02:50:07,338 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426025007322391
2019-04-26 02:50:07,361 [salt.minion      :1432][INFO    ][14639] Starting a new job with PID 14639
2019-04-26 02:50:07,382 [salt.minion      :1711][INFO    ][14639] Returning information for job: 20190426025007322391
2019-04-26 02:50:26,624 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (148.064322948s left)
2019-04-26 02:50:37,389 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426025037374994
2019-04-26 02:50:37,409 [salt.minion      :1432][INFO    ][14713] Starting a new job with PID 14713
2019-04-26 02:50:37,432 [salt.minion      :1711][INFO    ][14713] Returning information for job: 20190426025037374994
2019-04-26 02:50:59,323 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (115.364699841s left)
2019-04-26 02:51:07,604 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426025107592015
2019-04-26 02:51:07,628 [salt.minion      :1432][INFO    ][14745] Starting a new job with PID 14745
2019-04-26 02:51:07,652 [salt.minion      :1711][INFO    ][14745] Returning information for job: 20190426025107592015
2019-04-26 02:51:32,094 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (82.5938129425s left)
2019-04-26 02:51:37,687 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426025137675945
2019-04-26 02:51:37,709 [salt.minion      :1432][INFO    ][14820] Starting a new job with PID 14820
2019-04-26 02:51:37,732 [salt.minion      :1711][INFO    ][14820] Returning information for job: 20190426025137675945
2019-04-26 02:52:04,865 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (49.822685957s left)
2019-04-26 02:52:07,744 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426025207727762
2019-04-26 02:52:07,766 [salt.minion      :1432][INFO    ][14850] Starting a new job with PID 14850
2019-04-26 02:52:07,794 [salt.minion      :1711][INFO    ][14850] Returning information for job: 20190426025207727762
2019-04-26 02:52:37,556 [salt.loaded.ext.module.maas:1023][INFO    ][8472] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (17.1317520142s left)
2019-04-26 02:52:37,841 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426025237825553
2019-04-26 02:52:37,860 [salt.minion      :1432][INFO    ][14924] Starting a new job with PID 14924
2019-04-26 02:52:37,888 [salt.minion      :1711][INFO    ][14924] Returning information for job: 20190426025237825553
2019-04-26 02:53:07,944 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426025307936353
2019-04-26 02:53:07,961 [salt.minion      :1432][INFO    ][14950] Starting a new job with PID 14950
2019-04-26 02:53:07,985 [salt.minion      :1711][INFO    ][14950] Returning information for job: 20190426025307936353
2019-04-26 02:53:10,289 [salt.state       :302 ][ERROR   ][8472] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-04-26 02:53:10,290 [salt.state       :1951][INFO    ][8472] Completed state [maas.wait_for_machine_status] at time 02:53:10.289955 duration_in_ms=2265616.883
2019-04-26 02:53:10,293 [salt.minion      :1711][INFO    ][8472] Returning information for job: 20190426021514347773
2019-04-26 02:53:21,134 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command pillar.get with jid 20190426025321121435
2019-04-26 02:53:21,156 [salt.minion      :1432][INFO    ][15006] Starting a new job with PID 15006
2019-04-26 02:53:21,165 [salt.minion      :1711][INFO    ][15006] Returning information for job: 20190426025321121435
2019-04-26 02:53:21,701 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command service.status with jid 20190426025321689911
2019-04-26 02:53:21,718 [salt.minion      :1432][INFO    ][15011] Starting a new job with PID 15011
2019-04-26 02:53:22,210 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][15011] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-26 02:53:22,248 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][15011] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-26 02:53:22,265 [salt.minion      :1711][INFO    ][15011] Returning information for job: 20190426025321689911
2019-04-26 02:53:22,819 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command state.apply with jid 20190426025322810357
2019-04-26 02:53:22,833 [salt.minion      :1432][INFO    ][15028] Starting a new job with PID 15028
2019-04-26 02:53:30,984 [salt.state       :915 ][INFO    ][15028] Loading fresh modules for state activity
2019-04-26 02:53:31,570 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15028] Executing command 'salt-minion --version' in directory '/root'
2019-04-26 02:53:31,880 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15028] Executing command 'salt-minion --version' in directory '/root'
2019-04-26 02:53:32,863 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15028] Executing command 'salt-minion --version' in directory '/root'
2019-04-26 02:53:33,146 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15028] Executing command 'salt-minion --version' in directory '/root'
2019-04-26 02:53:34,876 [salt.state       :1780][INFO    ][15028] Running state [salt-minion] at time 02:53:34.876618
2019-04-26 02:53:34,877 [salt.state       :1813][INFO    ][15028] Executing state pkg.installed for [salt-minion]
2019-04-26 02:53:34,878 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15028] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-26 02:53:34,985 [salt.state       :300 ][INFO    ][15028] All specified packages are already installed
2019-04-26 02:53:34,986 [salt.state       :1951][INFO    ][15028] Completed state [salt-minion] at time 02:53:34.985934 duration_in_ms=109.317
2019-04-26 02:53:34,986 [salt.state       :1780][INFO    ][15028] Running state [salt_minion_dependency_packages] at time 02:53:34.986264
2019-04-26 02:53:34,986 [salt.state       :1813][INFO    ][15028] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-26 02:53:34,996 [salt.state       :300 ][INFO    ][15028] All specified packages are already installed
2019-04-26 02:53:34,996 [salt.state       :1951][INFO    ][15028] Completed state [salt_minion_dependency_packages] at time 02:53:34.996300 duration_in_ms=10.036
2019-04-26 02:53:34,999 [salt.state       :1780][INFO    ][15028] Running state [/etc/salt/minion.d/minion.conf] at time 02:53:34.999212
2019-04-26 02:53:34,999 [salt.state       :1813][INFO    ][15028] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-26 02:53:35,254 [salt.state       :300 ][INFO    ][15028] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-26 02:53:35,254 [salt.state       :1951][INFO    ][15028] Completed state [/etc/salt/minion.d/minion.conf] at time 02:53:35.254453 duration_in_ms=255.241
2019-04-26 02:53:35,254 [salt.state       :1780][INFO    ][15028] Running state [python-netaddr] at time 02:53:35.254723
2019-04-26 02:53:35,255 [salt.state       :1813][INFO    ][15028] Executing state pkg.installed for [python-netaddr]
2019-04-26 02:53:35,264 [salt.state       :300 ][INFO    ][15028] All specified packages are already installed
2019-04-26 02:53:35,264 [salt.state       :1951][INFO    ][15028] Completed state [python-netaddr] at time 02:53:35.264456 duration_in_ms=9.733
2019-04-26 02:53:35,268 [salt.state       :1780][INFO    ][15028] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:53:35.267972
2019-04-26 02:53:35,268 [salt.state       :1813][INFO    ][15028] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-26 02:53:35,279 [salt.state       :300 ][INFO    ][15028] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-26 02:53:35,279 [salt.state       :1951][INFO    ][15028] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:53:35.279844 duration_in_ms=11.872
2019-04-26 02:53:35,281 [salt.state       :1780][INFO    ][15028] Running state [salt-minion] at time 02:53:35.280978
2019-04-26 02:53:35,281 [salt.state       :1813][INFO    ][15028] Executing state service.running for [salt-minion]
2019-04-26 02:53:35,281 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15028] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-26 02:53:35,326 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15028] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-26 02:53:35,345 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15028] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-26 02:53:35,364 [salt.state       :300 ][INFO    ][15028] The service salt-minion is already running
2019-04-26 02:53:35,364 [salt.state       :1951][INFO    ][15028] Completed state [salt-minion] at time 02:53:35.364583 duration_in_ms=83.605
2019-04-26 02:53:35,366 [salt.state       :1780][INFO    ][15028] Running state [/etc/salt/grains.d] at time 02:53:35.366613
2019-04-26 02:53:35,369 [salt.state       :1813][INFO    ][15028] Executing state file.directory for [/etc/salt/grains.d]
2019-04-26 02:53:35,370 [salt.state       :300 ][INFO    ][15028] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-26 02:53:35,370 [salt.state       :1951][INFO    ][15028] Completed state [/etc/salt/grains.d] at time 02:53:35.370574 duration_in_ms=3.961
2019-04-26 02:53:35,371 [salt.state       :1780][INFO    ][15028] Running state [/etc/salt/grains] at time 02:53:35.371792
2019-04-26 02:53:35,372 [salt.state       :1813][INFO    ][15028] Executing state file.managed for [/etc/salt/grains]
2019-04-26 02:53:35,372 [salt.state       :300 ][INFO    ][15028] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-26 02:53:35,373 [salt.state       :1951][INFO    ][15028] Completed state [/etc/salt/grains] at time 02:53:35.373125 duration_in_ms=1.333
2019-04-26 02:53:35,373 [salt.state       :1780][INFO    ][15028] Running state [/etc/salt/grains.d/placeholder] at time 02:53:35.373772
2019-04-26 02:53:35,374 [salt.state       :1813][INFO    ][15028] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-26 02:53:35,374 [salt.state       :300 ][INFO    ][15028] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-26 02:53:35,375 [salt.state       :1951][INFO    ][15028] Completed state [/etc/salt/grains.d/placeholder] at time 02:53:35.375087 duration_in_ms=1.314
2019-04-26 02:53:35,375 [salt.state       :1780][INFO    ][15028] Running state [/etc/salt/grains.d/sphinx] at time 02:53:35.375735
2019-04-26 02:53:35,376 [salt.state       :1813][INFO    ][15028] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-26 02:53:35,377 [salt.state       :300 ][INFO    ][15028] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-26 02:53:35,377 [salt.state       :1951][INFO    ][15028] Completed state [/etc/salt/grains.d/sphinx] at time 02:53:35.377832 duration_in_ms=2.096
2019-04-26 02:53:35,382 [salt.state       :1780][INFO    ][15028] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:53:35.382380
2019-04-26 02:53:35,382 [salt.state       :1813][INFO    ][15028] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-26 02:53:35,383 [salt.state       :300 ][INFO    ][15028] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-26 02:53:35,383 [salt.state       :1951][INFO    ][15028] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:53:35.383464 duration_in_ms=1.083
2019-04-26 02:53:35,383 [salt.state       :1780][INFO    ][15028] Running state [/etc/salt/grains.d/dns_records] at time 02:53:35.383943
2019-04-26 02:53:35,384 [salt.state       :1813][INFO    ][15028] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-26 02:53:35,385 [salt.state       :300 ][INFO    ][15028] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-26 02:53:35,385 [salt.state       :1951][INFO    ][15028] Completed state [/etc/salt/grains.d/dns_records] at time 02:53:35.385255 duration_in_ms=1.312
2019-04-26 02:53:35,386 [salt.state       :1780][INFO    ][15028] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:53:35.386177
2019-04-26 02:53:35,386 [salt.state       :1813][INFO    ][15028] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-04-26 02:53:35,386 [salt.state       :300 ][INFO    ][15028] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-26 02:53:35,386 [salt.state       :1951][INFO    ][15028] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:53:35.386930 duration_in_ms=0.753
2019-04-26 02:53:35,387 [salt.state       :1780][INFO    ][15028] Running state [/etc/salt/grains.d/salt] at time 02:53:35.387394
2019-04-26 02:53:35,387 [salt.state       :1813][INFO    ][15028] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-26 02:53:35,388 [salt.state       :300 ][INFO    ][15028] File /etc/salt/grains.d/salt is in the correct state
2019-04-26 02:53:35,388 [salt.state       :1951][INFO    ][15028] Completed state [/etc/salt/grains.d/salt] at time 02:53:35.388701 duration_in_ms=1.307
2019-04-26 02:53:35,389 [salt.state       :1780][INFO    ][15028] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:53:35.389558
2019-04-26 02:53:35,389 [salt.state       :1813][INFO    ][15028] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-26 02:53:35,390 [salt.state       :300 ][INFO    ][15028] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-26 02:53:35,390 [salt.state       :1951][INFO    ][15028] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:53:35.390271 duration_in_ms=0.713
2019-04-26 02:53:35,394 [salt.state       :1780][INFO    ][15028] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:53:35.394042
2019-04-26 02:53:35,394 [salt.state       :1813][INFO    ][15028] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-26 02:53:35,394 [salt.state       :300 ][INFO    ][15028] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-26 02:53:35,394 [salt.state       :1951][INFO    ][15028] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:53:35.394807 duration_in_ms=0.766
2019-04-26 02:53:35,395 [salt.state       :1780][INFO    ][15028] Running state [mine.update] at time 02:53:35.395475
2019-04-26 02:53:35,395 [salt.state       :1813][INFO    ][15028] Executing state module.wait for [mine.update]
2019-04-26 02:53:35,396 [salt.state       :300 ][INFO    ][15028] No changes made for mine.update
2019-04-26 02:53:35,396 [salt.state       :1951][INFO    ][15028] Completed state [mine.update] at time 02:53:35.396165 duration_in_ms=0.69
2019-04-26 02:53:35,396 [salt.state       :1780][INFO    ][15028] Running state [ca-certificates] at time 02:53:35.396398
2019-04-26 02:53:35,396 [salt.state       :1813][INFO    ][15028] Executing state pkg.installed for [ca-certificates]
2019-04-26 02:53:35,406 [salt.state       :300 ][INFO    ][15028] All specified packages are already installed
2019-04-26 02:53:35,406 [salt.state       :1951][INFO    ][15028] Completed state [ca-certificates] at time 02:53:35.406741 duration_in_ms=10.343
2019-04-26 02:53:35,407 [salt.state       :1780][INFO    ][15028] Running state [update-ca-certificates] at time 02:53:35.407438
2019-04-26 02:53:35,407 [salt.state       :1813][INFO    ][15028] Executing state cmd.wait for [update-ca-certificates]
2019-04-26 02:53:35,408 [salt.state       :300 ][INFO    ][15028] No changes made for update-ca-certificates
2019-04-26 02:53:35,408 [salt.state       :1951][INFO    ][15028] Completed state [update-ca-certificates] at time 02:53:35.408167 duration_in_ms=0.729
2019-04-26 02:53:35,408 [salt.state       :1780][INFO    ][15028] Running state [iptables] at time 02:53:35.408402
2019-04-26 02:53:35,408 [salt.state       :1813][INFO    ][15028] Executing state pkg.installed for [iptables]
2019-04-26 02:53:35,418 [salt.state       :300 ][INFO    ][15028] All specified packages are already installed
2019-04-26 02:53:35,418 [salt.state       :1951][INFO    ][15028] Completed state [iptables] at time 02:53:35.418424 duration_in_ms=10.022
2019-04-26 02:53:35,418 [salt.state       :1780][INFO    ][15028] Running state [iptables-persistent] at time 02:53:35.418655
2019-04-26 02:53:35,418 [salt.state       :1813][INFO    ][15028] Executing state pkg.installed for [iptables-persistent]
2019-04-26 02:53:35,428 [salt.state       :300 ][INFO    ][15028] All specified packages are already installed
2019-04-26 02:53:35,428 [salt.state       :1951][INFO    ][15028] Completed state [iptables-persistent] at time 02:53:35.428746 duration_in_ms=10.09
2019-04-26 02:53:35,430 [salt.state       :1780][INFO    ][15028] Running state [iptables_modules_v4_load] at time 02:53:35.430660
2019-04-26 02:53:35,431 [salt.state       :1813][INFO    ][15028] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-26 02:53:35,432 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15028] Executing command 'lsmod' in directory '/root'
2019-04-26 02:53:35,455 [salt.state       :300 ][INFO    ][15028] Kernel modules iptable_filter, ip_tables are already present
2019-04-26 02:53:35,455 [salt.state       :1951][INFO    ][15028] Completed state [iptables_modules_v4_load] at time 02:53:35.455660 duration_in_ms=25.0
2019-04-26 02:53:35,456 [salt.state       :1780][INFO    ][15028] Running state [/etc/iptables/rules.v4] at time 02:53:35.456871
2019-04-26 02:53:35,457 [salt.state       :1813][INFO    ][15028] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-26 02:53:35,556 [salt.state       :300 ][INFO    ][15028] File /etc/iptables/rules.v4 is in the correct state
2019-04-26 02:53:35,556 [salt.state       :1951][INFO    ][15028] Completed state [/etc/iptables/rules.v4] at time 02:53:35.556638 duration_in_ms=99.768
2019-04-26 02:53:35,557 [salt.state       :1780][INFO    ][15028] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:53:35.557801
2019-04-26 02:53:35,558 [salt.state       :1813][INFO    ][15028] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-26 02:53:35,558 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15028] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-26 02:53:35,581 [salt.state       :300 ][INFO    ][15028] onlyif execution failed
2019-04-26 02:53:35,581 [salt.state       :1951][INFO    ][15028] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:53:35.581644 duration_in_ms=23.842
2019-04-26 02:53:35,586 [salt.state       :1780][INFO    ][15028] Running state [netfilter-persistent] at time 02:53:35.586489
2019-04-26 02:53:35,587 [salt.state       :1813][INFO    ][15028] Executing state service.running for [netfilter-persistent]
2019-04-26 02:53:35,588 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15028] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-26 02:53:35,604 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15028] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-26 02:53:35,617 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15028] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-26 02:53:35,637 [salt.state       :300 ][INFO    ][15028] The service netfilter-persistent is already running
2019-04-26 02:53:35,638 [salt.state       :1951][INFO    ][15028] Completed state [netfilter-persistent] at time 02:53:35.638291 duration_in_ms=51.801
2019-04-26 02:53:35,640 [salt.state       :1780][INFO    ][15028] Running state [iptables_extra.remove_stale_tables] at time 02:53:35.639930
2019-04-26 02:53:35,640 [salt.state       :1813][INFO    ][15028] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-26 02:53:35,640 [salt.state       :300 ][INFO    ][15028] No changes made for iptables_extra.remove_stale_tables
2019-04-26 02:53:35,641 [salt.state       :1951][INFO    ][15028] Completed state [iptables_extra.remove_stale_tables] at time 02:53:35.641271 duration_in_ms=1.34
2019-04-26 02:53:35,641 [salt.state       :1780][INFO    ][15028] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:53:35.641671
2019-04-26 02:53:35,642 [salt.state       :1813][INFO    ][15028] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-26 02:53:35,645 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15028] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-26 02:53:35,660 [salt.state       :300 ][INFO    ][15028] onlyif execution failed
2019-04-26 02:53:35,660 [salt.state       :1951][INFO    ][15028] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:53:35.660580 duration_in_ms=18.909
2019-04-26 02:53:35,662 [salt.state       :1780][INFO    ][15028] Running state [/etc/iptables/rules.v6] at time 02:53:35.661953
2019-04-26 02:53:35,662 [salt.state       :1813][INFO    ][15028] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-26 02:53:35,663 [salt.state       :300 ][INFO    ][15028] File /etc/iptables/rules.v6 is not present
2019-04-26 02:53:35,663 [salt.state       :1951][INFO    ][15028] Completed state [/etc/iptables/rules.v6] at time 02:53:35.663304 duration_in_ms=1.351
2019-04-26 02:53:35,664 [salt.state       :1780][INFO    ][15028] Running state [iptables_extra.flush_all] at time 02:53:35.664345
2019-04-26 02:53:35,664 [salt.state       :1813][INFO    ][15028] Executing state module.wait for [iptables_extra.flush_all]
2019-04-26 02:53:35,665 [salt.state       :300 ][INFO    ][15028] No changes made for iptables_extra.flush_all
2019-04-26 02:53:35,665 [salt.state       :1951][INFO    ][15028] Completed state [iptables_extra.flush_all] at time 02:53:35.665510 duration_in_ms=1.166
2019-04-26 02:53:35,671 [salt.minion      :1711][INFO    ][15028] Returning information for job: 20190426025322810357
2019-04-26 02:53:36,223 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command state.apply with jid 20190426025336206408
2019-04-26 02:53:36,247 [salt.minion      :1432][INFO    ][15114] Starting a new job with PID 15114
2019-04-26 02:53:37,350 [salt.state       :915 ][INFO    ][15114] Loading fresh modules for state activity
2019-04-26 02:53:38,209 [salt.state       :1780][INFO    ][15114] Running state [maas-rack-controller] at time 02:53:38.209852
2019-04-26 02:53:38,210 [salt.state       :1813][INFO    ][15114] Executing state pkg.installed for [maas-rack-controller]
2019-04-26 02:53:38,211 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15114] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-26 02:53:38,301 [salt.state       :300 ][INFO    ][15114] All specified packages are already installed
2019-04-26 02:53:38,301 [salt.state       :1951][INFO    ][15114] Completed state [maas-rack-controller] at time 02:53:38.301622 duration_in_ms=91.769
2019-04-26 02:53:38,302 [salt.state       :1780][INFO    ][15114] Running state [ipmitool] at time 02:53:38.302058
2019-04-26 02:53:38,302 [salt.state       :1813][INFO    ][15114] Executing state pkg.installed for [ipmitool]
2019-04-26 02:53:38,312 [salt.state       :300 ][INFO    ][15114] All specified packages are already installed
2019-04-26 02:53:38,312 [salt.state       :1951][INFO    ][15114] Completed state [ipmitool] at time 02:53:38.312350 duration_in_ms=10.292
2019-04-26 02:53:38,316 [salt.state       :1780][INFO    ][15114] Running state [/etc/maas/rackd.conf] at time 02:53:38.316635
2019-04-26 02:53:38,316 [salt.state       :1813][INFO    ][15114] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-26 02:53:38,317 [salt.state       :300 ][INFO    ][15114] No changes needed to be made
2019-04-26 02:53:38,318 [salt.state       :1951][INFO    ][15114] Completed state [/etc/maas/rackd.conf] at time 02:53:38.318091 duration_in_ms=1.457
2019-04-26 02:53:38,318 [salt.state       :1780][INFO    ][15114] Running state [/etc/maas/rackd.conf] at time 02:53:38.318335
2019-04-26 02:53:38,318 [salt.state       :1813][INFO    ][15114] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-26 02:53:38,320 [salt.loaded.int.states.file:2298][WARNING ][15114] 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-04-26 02:53:38,321 [salt.state       :300 ][INFO    ][15114] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-26 02:53:38,321 [salt.state       :1951][INFO    ][15114] Completed state [/etc/maas/rackd.conf] at time 02:53:38.321267 duration_in_ms=2.933
2019-04-26 02:53:38,322 [salt.state       :1780][INFO    ][15114] Running state [maas-rackd] at time 02:53:38.322305
2019-04-26 02:53:38,322 [salt.state       :1813][INFO    ][15114] Executing state service.running for [maas-rackd]
2019-04-26 02:53:38,323 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15114] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-26 02:53:38,360 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15114] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-26 02:53:38,376 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15114] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-26 02:53:38,399 [salt.state       :300 ][INFO    ][15114] The service maas-rackd is already running
2019-04-26 02:53:38,399 [salt.state       :1951][INFO    ][15114] Completed state [maas-rackd] at time 02:53:38.399550 duration_in_ms=77.243
2019-04-26 02:53:38,402 [salt.minion      :1711][INFO    ][15114] Returning information for job: 20190426025336206408
2019-04-26 02:53:38,941 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command state.apply with jid 20190426025338929349
2019-04-26 02:53:38,963 [salt.minion      :1432][INFO    ][15137] Starting a new job with PID 15137
2019-04-26 02:53:40,032 [salt.state       :915 ][INFO    ][15137] Loading fresh modules for state activity
2019-04-26 02:53:41,034 [salt.state       :1780][INFO    ][15137] Running state [maas-region-controller] at time 02:53:41.034317
2019-04-26 02:53:41,034 [salt.state       :1813][INFO    ][15137] Executing state pkg.installed for [maas-region-controller]
2019-04-26 02:53:41,035 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15137] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-26 02:53:41,138 [salt.state       :300 ][INFO    ][15137] All specified packages are already installed
2019-04-26 02:53:41,139 [salt.state       :1951][INFO    ][15137] Completed state [maas-region-controller] at time 02:53:41.139407 duration_in_ms=105.091
2019-04-26 02:53:41,139 [salt.state       :1780][INFO    ][15137] Running state [python-oauth] at time 02:53:41.139723
2019-04-26 02:53:41,140 [salt.state       :1813][INFO    ][15137] Executing state pkg.installed for [python-oauth]
2019-04-26 02:53:41,148 [salt.state       :300 ][INFO    ][15137] All specified packages are already installed
2019-04-26 02:53:41,149 [salt.state       :1951][INFO    ][15137] Completed state [python-oauth] at time 02:53:41.149008 duration_in_ms=9.286
2019-04-26 02:53:41,152 [salt.state       :1780][INFO    ][15137] Running state [/etc/maas/regiond.conf] at time 02:53:41.152852
2019-04-26 02:53:41,153 [salt.state       :1813][INFO    ][15137] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-26 02:53:41,157 [salt.state       :300 ][INFO    ][15137] No changes needed to be made
2019-04-26 02:53:41,157 [salt.state       :1951][INFO    ][15137] Completed state [/etc/maas/regiond.conf] at time 02:53:41.157490 duration_in_ms=4.639
2019-04-26 02:53:41,158 [salt.state       :1780][INFO    ][15137] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:53:41.157960
2019-04-26 02:53:41,158 [salt.state       :1813][INFO    ][15137] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-26 02:53:41,213 [salt.state       :300 ][INFO    ][15137] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-26 02:53:41,214 [salt.state       :1951][INFO    ][15137] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:53:41.214289 duration_in_ms=56.328
2019-04-26 02:53:41,215 [salt.state       :1780][INFO    ][15137] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:53:41.215847
2019-04-26 02:53:41,216 [salt.state       :1813][INFO    ][15137] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-26 02:53:41,224 [salt.state       :300 ][INFO    ][15137] No changes needed to be made
2019-04-26 02:53:41,224 [salt.state       :1951][INFO    ][15137] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:53:41.224827 duration_in_ms=8.981
2019-04-26 02:53:41,225 [salt.state       :1780][INFO    ][15137] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:53:41.225304
2019-04-26 02:53:41,225 [salt.state       :1813][INFO    ][15137] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-26 02:53:41,228 [salt.state       :300 ][INFO    ][15137] No changes needed to be made
2019-04-26 02:53:41,229 [salt.state       :1951][INFO    ][15137] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:53:41.229123 duration_in_ms=3.819
2019-04-26 02:53:41,229 [salt.state       :1780][INFO    ][15137] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:53:41.229601
2019-04-26 02:53:41,229 [salt.state       :1813][INFO    ][15137] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-26 02:53:41,252 [salt.state       :300 ][INFO    ][15137] No changes needed to be made
2019-04-26 02:53:41,252 [salt.state       :1951][INFO    ][15137] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:53:41.252806 duration_in_ms=23.205
2019-04-26 02:53:41,253 [salt.state       :1780][INFO    ][15137] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:53:41.253281
2019-04-26 02:53:41,253 [salt.state       :1813][INFO    ][15137] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-26 02:53:41,267 [salt.state       :300 ][INFO    ][15137] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-26 02:53:41,267 [salt.state       :1951][INFO    ][15137] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:53:41.267675 duration_in_ms=14.393
2019-04-26 02:53:41,268 [salt.state       :1780][INFO    ][15137] Running state [a2enmod headers] at time 02:53:41.268923
2019-04-26 02:53:41,269 [salt.state       :1813][INFO    ][15137] Executing state cmd.run for [a2enmod headers]
2019-04-26 02:53:41,269 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15137] Executing command 'a2enmod headers' in directory '/root'
2019-04-26 02:53:41,338 [salt.state       :300 ][INFO    ][15137] {'pid': 15156, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-26 02:53:41,338 [salt.state       :1951][INFO    ][15137] Completed state [a2enmod headers] at time 02:53:41.338673 duration_in_ms=69.748
2019-04-26 02:53:41,339 [salt.state       :1780][INFO    ][15137] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:53:41.339237
2019-04-26 02:53:41,339 [salt.state       :1813][INFO    ][15137] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-26 02:53:41,359 [salt.state       :300 ][INFO    ][15137] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-26 02:53:41,360 [salt.state       :1951][INFO    ][15137] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:53:41.360190 duration_in_ms=20.953
2019-04-26 02:53:41,361 [salt.state       :1780][INFO    ][15137] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:53:41.361304
2019-04-26 02:53:41,361 [salt.state       :1813][INFO    ][15137] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-26 02:53:41,422 [salt.state       :300 ][INFO    ][15137] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-26 02:53:41,422 [salt.state       :1951][INFO    ][15137] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:53:41.422667 duration_in_ms=61.363
2019-04-26 02:53:41,423 [salt.state       :1780][INFO    ][15137] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:53:41.423651
2019-04-26 02:53:41,424 [salt.state       :1813][INFO    ][15137] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-26 02:53:41,476 [salt.state       :300 ][INFO    ][15137] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-26 02:53:41,476 [salt.state       :1951][INFO    ][15137] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:53:41.476833 duration_in_ms=53.182
2019-04-26 02:53:41,477 [salt.state       :1780][INFO    ][15137] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:53:41.477343
2019-04-26 02:53:41,477 [salt.state       :1813][INFO    ][15137] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-26 02:53:41,537 [salt.state       :300 ][INFO    ][15137] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-26 02:53:41,537 [salt.state       :1951][INFO    ][15137] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:53:41.537677 duration_in_ms=60.334
2019-04-26 02:53:41,537 [salt.state       :1780][INFO    ][15137] Running state [/root/.pgpass] at time 02:53:41.537943
2019-04-26 02:53:41,538 [salt.state       :1813][INFO    ][15137] Executing state file.managed for [/root/.pgpass]
2019-04-26 02:53:41,584 [salt.state       :300 ][INFO    ][15137] File /root/.pgpass is in the correct state
2019-04-26 02:53:41,585 [salt.state       :1951][INFO    ][15137] Completed state [/root/.pgpass] at time 02:53:41.585081 duration_in_ms=47.138
2019-04-26 02:53:41,590 [salt.state       :1780][INFO    ][15137] Running state [maas-region syncdb --noinput] at time 02:53:41.590077
2019-04-26 02:53:41,590 [salt.state       :1813][INFO    ][15137] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-26 02:53:41,590 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15137] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-26 02:53:44,044 [salt.state       :300 ][INFO    ][15137] {'pid': 15169, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: contenttypes, metadataserver, maasserver, auth, sessions, piston3, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-26 02:53:44,045 [salt.state       :1951][INFO    ][15137] Completed state [maas-region syncdb --noinput] at time 02:53:44.044872 duration_in_ms=2454.793
2019-04-26 02:53:44,045 [salt.state       :2022][WARNING ][15137] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-26 02:53:44,049 [salt.state       :1780][INFO    ][15137] Running state [maas-regiond] at time 02:53:44.049100
2019-04-26 02:53:44,049 [salt.state       :1813][INFO    ][15137] Executing state service.running for [maas-regiond]
2019-04-26 02:53:44,051 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15137] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-26 02:53:44,093 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15137] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-26 02:53:44,110 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15137] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-26 02:53:44,129 [salt.state       :300 ][INFO    ][15137] The service maas-regiond is already running
2019-04-26 02:53:44,130 [salt.state       :1951][INFO    ][15137] Completed state [maas-regiond] at time 02:53:44.130055 duration_in_ms=80.956
2019-04-26 02:53:44,132 [salt.state       :1780][INFO    ][15137] Running state [bind9] at time 02:53:44.132158
2019-04-26 02:53:44,132 [salt.state       :1813][INFO    ][15137] Executing state service.running for [bind9]
2019-04-26 02:53:44,133 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15137] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-26 02:53:44,150 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15137] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-26 02:53:44,169 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15137] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-26 02:53:44,186 [salt.state       :300 ][INFO    ][15137] The service bind9 is already running
2019-04-26 02:53:44,189 [salt.state       :1951][INFO    ][15137] Completed state [bind9] at time 02:53:44.189788 duration_in_ms=57.63
2019-04-26 02:53:44,193 [salt.state       :1780][INFO    ][15137] Running state [apache2] at time 02:53:44.193072
2019-04-26 02:53:44,193 [salt.state       :1813][INFO    ][15137] Executing state service.running for [apache2]
2019-04-26 02:53:44,194 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15137] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-26 02:53:44,215 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15137] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-26 02:53:44,232 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15137] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-26 02:53:44,254 [salt.state       :300 ][INFO    ][15137] The service apache2 is already running
2019-04-26 02:53:44,254 [salt.state       :1951][INFO    ][15137] Completed state [apache2] at time 02:53:44.254790 duration_in_ms=61.718
2019-04-26 02:53:44,256 [salt.state       :1780][INFO    ][15137] Running state [maasng.wait_for_http_code] at time 02:53:44.256270
2019-04-26 02:53:44,256 [salt.state       :1813][INFO    ][15137] Executing state module.run for [maasng.wait_for_http_code]
2019-04-26 02:53:44,257 [salt.utils.decorators:613 ][WARNING ][15137] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 02:53:44,371 [salt.state       :300 ][INFO    ][15137] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-26 02:53:44,371 [salt.state       :1951][INFO    ][15137] Completed state [maasng.wait_for_http_code] at time 02:53:44.371511 duration_in_ms=115.241
2019-04-26 02:53:44,372 [salt.state       :1780][INFO    ][15137] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:53:44.372488
2019-04-26 02:53:44,372 [salt.state       :1813][INFO    ][15137] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-26 02:53:44,373 [salt.state       :300 ][INFO    ][15137] /var/lib/maas/.setup_admin exists
2019-04-26 02:53:44,374 [salt.state       :1951][INFO    ][15137] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:53:44.373998 duration_in_ms=1.509
2019-04-26 02:53:44,375 [salt.state       :1780][INFO    ][15137] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:44.375370
2019-04-26 02:53:44,375 [salt.state       :1813][INFO    ][15137] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-26 02:53:44,376 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15137] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-26 02:53:46,057 [salt.state       :300 ][INFO    ][15137] {'pid': 15194, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-26 02:53:46,059 [salt.state       :1951][INFO    ][15137] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:46.058704 duration_in_ms=1683.332
2019-04-26 02:53:46,065 [salt.state       :1780][INFO    ][15137] Running state [maas_region_boot_source_resources_mirror] at time 02:53:46.065268
2019-04-26 02:53:46,065 [salt.state       :1813][INFO    ][15137] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-26 02:53:46,157 [salt.state       :300 ][INFO    ][15137] {'changes': {}}
2019-04-26 02:53:46,157 [salt.state       :1951][INFO    ][15137] Completed state [maas_region_boot_source_resources_mirror] at time 02:53:46.157441 duration_in_ms=92.172
2019-04-26 02:53:46,158 [salt.state       :1780][INFO    ][15137] Running state [maasng.boot_resources_import] at time 02:53:46.158261
2019-04-26 02:53:46,158 [salt.state       :1813][INFO    ][15137] Executing state module.run for [maasng.boot_resources_import]
2019-04-26 02:53:46,159 [salt.utils.decorators:613 ][WARNING ][15137] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 02:53:46,249 [salt.loaded.ext.module.maasng:1600][INFO    ][15137] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-26 02:53:51,285 [salt.loaded.ext.module.maasng:1600][INFO    ][15137] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-26 02:53:53,971 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426025353957887
2019-04-26 02:53:53,988 [salt.minion      :1432][INFO    ][15227] Starting a new job with PID 15227
2019-04-26 02:53:54,012 [salt.minion      :1711][INFO    ][15227] Returning information for job: 20190426025353957887
2019-04-26 02:53:56,372 [salt.state       :300 ][INFO    ][15137] {'ret': True}
2019-04-26 02:53:56,372 [salt.state       :1951][INFO    ][15137] Completed state [maasng.boot_resources_import] at time 02:53:56.372549 duration_in_ms=10214.288
2019-04-26 02:53:56,373 [salt.state       :1780][INFO    ][15137] Running state [maas_region_boot_sources_selection_xenial] at time 02:53:56.373554
2019-04-26 02:53:56,373 [salt.state       :1813][INFO    ][15137] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-26 02:53:56,534 [salt.state       :300 ][INFO    ][15137] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-26 02:53:56,534 [salt.state       :1951][INFO    ][15137] Completed state [maas_region_boot_sources_selection_xenial] at time 02:53:56.534423 duration_in_ms=160.868
2019-04-26 02:53:56,537 [salt.state       :1780][INFO    ][15137] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:53:56.536920
2019-04-26 02:53:56,537 [salt.state       :1813][INFO    ][15137] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-26 02:53:56,538 [salt.utils.decorators:613 ][WARNING ][15137] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 02:53:56,538 [salt.loaded.ext.module.maasng:1771][INFO    ][15137] boot-sources sync initiated for ALL Rack's
2019-04-26 02:53:57,528 [salt.state       :300 ][INFO    ][15137] {'ret': True}
2019-04-26 02:53:57,528 [salt.state       :1951][INFO    ][15137] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:53:57.528621 duration_in_ms=991.701
2019-04-26 02:53:57,530 [salt.state       :1780][INFO    ][15137] Running state [maas.process_maas_config] at time 02:53:57.530091
2019-04-26 02:53:57,530 [salt.state       :1813][INFO    ][15137] Executing state module.run for [maas.process_maas_config]
2019-04-26 02:53:57,532 [salt.utils.decorators:613 ][WARNING ][15137] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 02:53:57,533 [salt.loaded.ext.module.maas:92  ][INFO    ][15137] maasconfig name=enable_http_proxy value=True
2019-04-26 02:53:57,578 [salt.loaded.ext.module.maas:92  ][INFO    ][15137] maasconfig name=upstream_dns value=8.8.8.8
2019-04-26 02:53:57,631 [salt.loaded.ext.module.maas:92  ][INFO    ][15137] maasconfig name=commissioning_distro_series value=xenial
2019-04-26 02:53:57,683 [salt.loaded.ext.module.maas:92  ][INFO    ][15137] maasconfig name=default_osystem value=ubuntu
2019-04-26 02:53:57,744 [salt.loaded.ext.module.maas:92  ][INFO    ][15137] maasconfig name=active_discovery_interval value=600
2019-04-26 02:53:57,789 [salt.loaded.ext.module.maas:92  ][INFO    ][15137] maasconfig name=dnssec_validation value=no
2019-04-26 02:53:57,841 [salt.loaded.ext.module.maas:92  ][INFO    ][15137] maasconfig name=maas_name value=mas01
2019-04-26 02:53:57,889 [salt.loaded.ext.module.maas:92  ][INFO    ][15137] maasconfig name=network_discovery value=enabled
2019-04-26 02:53:57,998 [salt.loaded.ext.module.maas:92  ][INFO    ][15137] maasconfig name=enable_third_party_drivers value=True
2019-04-26 02:53:59,096 [salt.loaded.ext.module.maas:92  ][INFO    ][15137] maasconfig name=default_storage_layout value=lvm
2019-04-26 02:53:59,150 [salt.loaded.ext.module.maas:92  ][INFO    ][15137] maasconfig name=ntp_external_only value=True
2019-04-26 02:53:59,215 [salt.loaded.ext.module.maas:92  ][INFO    ][15137] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-26 02:53:59,261 [salt.loaded.ext.module.maas:92  ][INFO    ][15137] maasconfig name=default_distro_series value=xenial
2019-04-26 02:53:59,311 [salt.loaded.ext.module.maas:92  ][INFO    ][15137] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-04-26 02:53:59,448 [salt.state       :300 ][INFO    ][15137] {'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-04-26 02:53:59,448 [salt.state       :1951][INFO    ][15137] Completed state [maas.process_maas_config] at time 02:53:59.448543 duration_in_ms=1918.451
2019-04-26 02:53:59,449 [salt.state       :1780][INFO    ][15137] Running state [pxe_admin] at time 02:53:59.449319
2019-04-26 02:53:59,449 [salt.state       :1813][INFO    ][15137] Executing state maasng.fabric_present for [pxe_admin]
2019-04-26 02:53:59,507 [salt.loaded.ext.module.maasng:945 ][INFO    ][15137] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-26 02:53:59,562 [salt.loaded.ext.module.maasng:1008][WARNING ][15137] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-26 02:53:59,562 [salt.loaded.ext.module.maasng:1011][WARNING ][15137] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-26 02:53:59,630 [salt.state       :300 ][INFO    ][15137] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-26 02:53:59,632 [salt.state       :1951][INFO    ][15137] Completed state [pxe_admin] at time 02:53:59.630791 duration_in_ms=181.473
2019-04-26 02:53:59,632 [salt.state       :1780][INFO    ][15137] Running state [vlan 0] at time 02:53:59.632514
2019-04-26 02:53:59,632 [salt.state       :1813][INFO    ][15137] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-26 02:53:59,681 [salt.loaded.ext.module.maasng:945 ][INFO    ][15137] [{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'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {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'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-26 02:53:59,782 [salt.loaded.ext.module.maasng:945 ][INFO    ][15137] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'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'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7x76qx', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-26 02:54:00,012 [salt.loaded.ext.module.maasng:945 ][INFO    ][15137] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7x76qx', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-04-26 02:54:00,088 [salt.state       :300 ][INFO    ][15137] {'new': 'Vlan untagged was updated'}
2019-04-26 02:54:00,088 [salt.state       :1951][INFO    ][15137] Completed state [vlan 0] at time 02:54:00.088397 duration_in_ms=455.882
2019-04-26 02:54:00,089 [salt.state       :1780][INFO    ][15137] Running state [192.168.11.0/24] at time 02:54:00.089516
2019-04-26 02:54:00,089 [salt.state       :1813][INFO    ][15137] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-26 02:54:00,277 [salt.loaded.ext.module.maasng:945 ][INFO    ][15137] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7x76qx', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-04-26 02:54:00,278 [salt.loaded.ext.module.maasng:1235][WARNING ][15137] Ignoring parameter vlan:0
2019-04-26 02:54:00,346 [salt.state       :300 ][INFO    ][15137] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-26 02:54:00,347 [salt.state       :1951][INFO    ][15137] Completed state [192.168.11.0/24] at time 02:54:00.347633 duration_in_ms=258.118
2019-04-26 02:54:00,348 [salt.state       :1780][INFO    ][15137] Running state [maas_create_iprange_1] at time 02:54:00.348406
2019-04-26 02:54:00,348 [salt.state       :1813][INFO    ][15137] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-26 02:54:00,392 [salt.state       :300 ][INFO    ][15137] Iprange maas_create_iprange_1 already exist.
2019-04-26 02:54:00,392 [salt.state       :1951][INFO    ][15137] Completed state [maas_create_iprange_1] at time 02:54:00.392750 duration_in_ms=44.344
2019-04-26 02:54:00,393 [salt.state       :1780][INFO    ][15137] Running state [vlan 0] at time 02:54:00.393129
2019-04-26 02:54:00,393 [salt.state       :1813][INFO    ][15137] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-26 02:54:00,438 [salt.loaded.ext.module.maasng:945 ][INFO    ][15137] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-26 02:54:00,513 [salt.loaded.ext.module.maasng:945 ][INFO    ][15137] [{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'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', 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'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7x76qx', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-04-26 02:54:00,733 [salt.loaded.ext.module.maasng:945 ][INFO    ][15137] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'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'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7x76qx', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-04-26 02:54:00,811 [salt.state       :300 ][INFO    ][15137] {'new': 'Vlan untagged was updated'}
2019-04-26 02:54:00,811 [salt.state       :1951][INFO    ][15137] Completed state [vlan 0] at time 02:54:00.811463 duration_in_ms=418.333
2019-04-26 02:54:00,812 [salt.state       :1780][INFO    ][15137] Running state [opnfv] at time 02:54:00.812036
2019-04-26 02:54:00,812 [salt.state       :1813][INFO    ][15137] Executing state maasng.sshkey_present for [opnfv]
2019-04-26 02:54:00,853 [salt.loaded.ext.module.maasng:1903][INFO    ][15137] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-04-26 02:54:00,853 [salt.state       :300 ][INFO    ][15137] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-04-26 02:54:00,853 [salt.state       :1951][INFO    ][15137] Completed state [opnfv] at time 02:54:00.853663 duration_in_ms=41.627
2019-04-26 02:54:00,860 [salt.minion      :1711][INFO    ][15137] Returning information for job: 20190426025338929349
2019-04-26 02:54:01,473 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command state.apply with jid 20190426025401463499
2019-04-26 02:54:01,489 [salt.minion      :1432][INFO    ][15591] Starting a new job with PID 15591
2019-04-26 02:54:09,424 [salt.state       :915 ][INFO    ][15591] Loading fresh modules for state activity
2019-04-26 02:54:09,522 [salt.state       :1780][INFO    ][15591] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:09.522277
2019-04-26 02:54:09,522 [salt.state       :1813][INFO    ][15591] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-26 02:54:09,524 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15591] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-26 02:54:11,245 [salt.state       :300 ][INFO    ][15591] {'pid': 15617, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-26 02:54:11,246 [salt.state       :1951][INFO    ][15591] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:11.246017 duration_in_ms=1723.739
2019-04-26 02:54:11,250 [salt.state       :1780][INFO    ][15591] Running state [maas.process_machines] at time 02:54:11.250080
2019-04-26 02:54:11,250 [salt.state       :1813][INFO    ][15591] Executing state module.run for [maas.process_machines]
2019-04-26 02:54:11,252 [salt.utils.decorators:613 ][WARNING ][15591] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 02:54:11,802 [salt.loaded.ext.module.maas:412 ][WARNING ][15591] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-26 02:54:11,803 [salt.loaded.ext.module.maas:92  ][INFO    ][15591] 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=7xpnbr architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-26 02:54:12,897 [salt.loaded.ext.module.maas:412 ][WARNING ][15591] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-26 02:54:12,897 [salt.loaded.ext.module.maas:92  ][INFO    ][15591] 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=d3waqd architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-26 02:54:13,979 [salt.loaded.ext.module.maas:412 ][WARNING ][15591] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-26 02:54:13,980 [salt.loaded.ext.module.maas:92  ][INFO    ][15591] 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=pwqhw3 architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-26 02:54:15,067 [salt.loaded.ext.module.maas:412 ][WARNING ][15591] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-26 02:54:15,068 [salt.loaded.ext.module.maas:92  ][INFO    ][15591] 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=cnkwfh architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-26 02:54:16,195 [salt.loaded.ext.module.maas:412 ][WARNING ][15591] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-26 02:54:16,196 [salt.loaded.ext.module.maas:92  ][INFO    ][15591] 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=apwgpg architecture=amd64/generic power_parameters_power_user=opnfv
2019-04-26 02:54:16,510 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426025416500102
2019-04-26 02:54:16,532 [salt.minion      :1432][INFO    ][15826] Starting a new job with PID 15826
2019-04-26 02:54:16,559 [salt.minion      :1711][INFO    ][15826] Returning information for job: 20190426025416500102
2019-04-26 02:54:17,289 [salt.state       :300 ][INFO    ][15591] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-26 02:54:17,289 [salt.state       :1951][INFO    ][15591] Completed state [maas.process_machines] at time 02:54:17.289695 duration_in_ms=6039.615
2019-04-26 02:54:17,292 [salt.minion      :1711][INFO    ][15591] Returning information for job: 20190426025401463499
2019-04-26 02:54:50,225 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command state.apply with jid 20190426025450216114
2019-04-26 02:54:50,244 [salt.minion      :1432][INFO    ][15904] Starting a new job with PID 15904
2019-04-26 02:54:58,146 [salt.state       :915 ][INFO    ][15904] Loading fresh modules for state activity
2019-04-26 02:54:58,238 [salt.state       :1780][INFO    ][15904] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:58.238734
2019-04-26 02:54:58,239 [salt.state       :1813][INFO    ][15904] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-26 02:54:58,241 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15904] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-26 02:54:59,966 [salt.state       :300 ][INFO    ][15904] {'pid': 15915, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-26 02:54:59,968 [salt.state       :1951][INFO    ][15904] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:59.968092 duration_in_ms=1729.358
2019-04-26 02:54:59,971 [salt.state       :1780][INFO    ][15904] Running state [maas.wait_for_machine_status] at time 02:54:59.971332
2019-04-26 02:54:59,971 [salt.state       :1813][INFO    ][15904] Executing state module.run for [maas.wait_for_machine_status]
2019-04-26 02:54:59,972 [salt.utils.decorators:613 ][WARNING ][15904] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 02:55:02,674 [salt.state       :300 ][INFO    ][15904] {'ret': True}
2019-04-26 02:55:02,675 [salt.state       :1951][INFO    ][15904] Completed state [maas.wait_for_machine_status] at time 02:55:02.675026 duration_in_ms=2703.693
2019-04-26 02:55:02,678 [salt.minion      :1711][INFO    ][15904] Returning information for job: 20190426025450216114
2019-04-26 02:55:03,210 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command state.apply with jid 20190426025503198211
2019-04-26 02:55:03,229 [salt.minion      :1432][INFO    ][15942] Starting a new job with PID 15942
2019-04-26 02:55:04,377 [salt.state       :915 ][INFO    ][15942] Loading fresh modules for state activity
2019-04-26 02:55:04,519 [salt.state       :1780][INFO    ][15942] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:04.519494
2019-04-26 02:55:04,519 [salt.state       :1813][INFO    ][15942] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-26 02:55:04,521 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15942] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-26 02:55:06,188 [salt.state       :300 ][INFO    ][15942] {'pid': 15949, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-26 02:55:06,188 [salt.state       :1951][INFO    ][15942] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:06.188898 duration_in_ms=1669.403
2019-04-26 02:55:06,192 [salt.state       :1780][INFO    ][15942] Running state [maas_machines_storage_cmp002_lvm] at time 02:55:06.192070
2019-04-26 02:55:06,192 [salt.state       :1813][INFO    ][15942] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-26 02:55:06,702 [salt.state       :300 ][INFO    ][15942] Machine cmp002 is not in Ready state.
2019-04-26 02:55:06,702 [salt.state       :1951][INFO    ][15942] Completed state [maas_machines_storage_cmp002_lvm] at time 02:55:06.702748 duration_in_ms=510.679
2019-04-26 02:55:06,703 [salt.state       :1780][INFO    ][15942] Running state [maas_machines_storage_cmp001_lvm] at time 02:55:06.703423
2019-04-26 02:55:06,703 [salt.state       :1813][INFO    ][15942] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-26 02:55:07,285 [salt.state       :300 ][INFO    ][15942] Machine cmp001 is not in Ready state.
2019-04-26 02:55:07,286 [salt.state       :1951][INFO    ][15942] Completed state [maas_machines_storage_cmp001_lvm] at time 02:55:07.286178 duration_in_ms=582.754
2019-04-26 02:55:07,291 [salt.minion      :1711][INFO    ][15942] Returning information for job: 20190426025503198211
2019-04-26 02:55:07,802 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command state.apply with jid 20190426025507792939
2019-04-26 02:55:07,814 [salt.minion      :1432][INFO    ][15959] Starting a new job with PID 15959
2019-04-26 02:55:08,950 [salt.state       :915 ][INFO    ][15959] Loading fresh modules for state activity
2019-04-26 02:55:09,031 [salt.state       :1780][INFO    ][15959] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:09.031504
2019-04-26 02:55:09,031 [salt.state       :1813][INFO    ][15959] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-26 02:55:09,033 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15959] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-26 02:55:10,725 [salt.state       :300 ][INFO    ][15959] {'pid': 15966, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-26 02:55:10,725 [salt.state       :1951][INFO    ][15959] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:10.725575 duration_in_ms=1694.071
2019-04-26 02:55:10,727 [salt.state       :1780][INFO    ][15959] Running state [maas.deploy_machines] at time 02:55:10.727074
2019-04-26 02:55:10,727 [salt.state       :1813][INFO    ][15959] Executing state module.run for [maas.deploy_machines]
2019-04-26 02:55:10,727 [salt.utils.decorators:613 ][WARNING ][15959] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 02:55:11,196 [salt.loaded.ext.module.maas:684 ][INFO    ][15959] deploymachines hwe_kernel=ga-16.04 system_id=apwgpg distro_series=xenial
2019-04-26 02:55:13,309 [salt.state       :300 ][INFO    ][15959] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-04-26 02:55:13,309 [salt.state       :1951][INFO    ][15959] Completed state [maas.deploy_machines] at time 02:55:13.309480 duration_in_ms=2582.404
2019-04-26 02:55:13,315 [salt.minion      :1711][INFO    ][15959] Returning information for job: 20190426025507792939
2019-04-26 02:55:13,902 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command state.apply with jid 20190426025513855236
2019-04-26 02:55:13,919 [salt.minion      :1432][INFO    ][16027] Starting a new job with PID 16027
2019-04-26 02:55:22,049 [salt.state       :915 ][INFO    ][16027] Loading fresh modules for state activity
2019-04-26 02:55:22,140 [salt.state       :1780][INFO    ][16027] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:22.139961
2019-04-26 02:55:22,140 [salt.state       :1813][INFO    ][16027] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-26 02:55:22,142 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16027] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-26 02:55:23,911 [salt.state       :300 ][INFO    ][16027] {'pid': 16083, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-26 02:55:23,912 [salt.state       :1951][INFO    ][16027] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:23.912339 duration_in_ms=1772.379
2019-04-26 02:55:23,913 [salt.state       :1780][INFO    ][16027] Running state [maas.wait_for_machine_status] at time 02:55:23.913769
2019-04-26 02:55:23,914 [salt.state       :1813][INFO    ][16027] Executing state module.run for [maas.wait_for_machine_status]
2019-04-26 02:55:23,914 [salt.utils.decorators:613 ][WARNING ][16027] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-26 02:55:26,565 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.36820292s left)
2019-04-26 02:55:28,905 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426025528891377
2019-04-26 02:55:28,928 [salt.minion      :1432][INFO    ][16107] Starting a new job with PID 16107
2019-04-26 02:55:28,952 [salt.minion      :1711][INFO    ][16107] Returning information for job: 20190426025528891377
2019-04-26 02:55:58,967 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426025558958169
2019-04-26 02:55:58,981 [salt.minion      :1432][INFO    ][16126] Starting a new job with PID 16126
2019-04-26 02:55:59,010 [salt.minion      :1711][INFO    ][16126] Returning information for job: 20190426025558958169
2019-04-26 02:55:59,437 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.49596s left)
2019-04-26 02:56:29,036 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426025629021079
2019-04-26 02:56:29,059 [salt.minion      :1432][INFO    ][16203] Starting a new job with PID 16203
2019-04-26 02:56:29,082 [salt.minion      :1711][INFO    ][16203] Returning information for job: 20190426025629021079
2019-04-26 02:56:32,122 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.81082106s left)
2019-04-26 02:56:59,094 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426025659082254
2019-04-26 02:56:59,116 [salt.minion      :1432][INFO    ][16223] Starting a new job with PID 16223
2019-04-26 02:56:59,146 [salt.minion      :1711][INFO    ][16223] Returning information for job: 20190426025659082254
2019-04-26 02:57:04,993 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.94008183s left)
2019-04-26 02:57:29,159 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426025729146804
2019-04-26 02:57:29,181 [salt.minion      :1432][INFO    ][16329] Starting a new job with PID 16329
2019-04-26 02:57:29,206 [salt.minion      :1711][INFO    ][16329] Returning information for job: 20190426025729146804
2019-04-26 02:57:37,652 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.28044605s left)
2019-04-26 02:57:59,220 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426025759208059
2019-04-26 02:57:59,239 [salt.minion      :1432][INFO    ][16361] Starting a new job with PID 16361
2019-04-26 02:57:59,263 [salt.minion      :1711][INFO    ][16361] Returning information for job: 20190426025759208059
2019-04-26 02:58:10,339 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.59369993s left)
2019-04-26 02:58:29,274 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426025829262500
2019-04-26 02:58:29,297 [salt.minion      :1432][INFO    ][16435] Starting a new job with PID 16435
2019-04-26 02:58:29,320 [salt.minion      :1711][INFO    ][16435] Returning information for job: 20190426025829262500
2019-04-26 02:58:42,979 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.95345402s left)
2019-04-26 02:58:59,346 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426025859330358
2019-04-26 02:58:59,368 [salt.minion      :1432][INFO    ][16468] Starting a new job with PID 16468
2019-04-26 02:58:59,392 [salt.minion      :1711][INFO    ][16468] Returning information for job: 20190426025859330358
2019-04-26 02:59:15,796 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2018.13640189s left)
2019-04-26 02:59:29,372 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426025929362669
2019-04-26 02:59:29,385 [salt.minion      :1432][INFO    ][16564] Starting a new job with PID 16564
2019-04-26 02:59:29,409 [salt.minion      :1711][INFO    ][16564] Returning information for job: 20190426025929362669
2019-04-26 02:59:48,658 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1985.27485204s left)
2019-04-26 02:59:59,432 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426025959420088
2019-04-26 02:59:59,453 [salt.minion      :1432][INFO    ][16622] Starting a new job with PID 16622
2019-04-26 02:59:59,476 [salt.minion      :1711][INFO    ][16622] Returning information for job: 20190426025959420088
2019-04-26 03:00:21,560 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1952.37318087s left)
2019-04-26 03:00:29,529 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426030029517921
2019-04-26 03:00:29,550 [salt.minion      :1432][INFO    ][16767] Starting a new job with PID 16767
2019-04-26 03:00:29,574 [salt.minion      :1711][INFO    ][16767] Returning information for job: 20190426030029517921
2019-04-26 03:00:54,317 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1919.61594391s left)
2019-04-26 03:00:59,610 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426030059599130
2019-04-26 03:00:59,632 [salt.minion      :1432][INFO    ][16804] Starting a new job with PID 16804
2019-04-26 03:00:59,654 [salt.minion      :1711][INFO    ][16804] Returning information for job: 20190426030059599130
2019-04-26 03:01:27,221 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1886.71167493s left)
2019-04-26 03:01:29,718 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426030129704363
2019-04-26 03:01:29,737 [salt.minion      :1432][INFO    ][16968] Starting a new job with PID 16968
2019-04-26 03:01:29,760 [salt.minion      :1711][INFO    ][16968] Returning information for job: 20190426030129704363
2019-04-26 03:01:59,804 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426030159795827
2019-04-26 03:01:59,818 [salt.minion      :1432][INFO    ][16997] Starting a new job with PID 16997
2019-04-26 03:01:59,841 [salt.minion      :1711][INFO    ][16997] Returning information for job: 20190426030159795827
2019-04-26 03:02:00,005 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1853.92806196s left)
2019-04-26 03:02:29,926 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426030229915635
2019-04-26 03:02:29,946 [salt.minion      :1432][INFO    ][17110] Starting a new job with PID 17110
2019-04-26 03:02:29,969 [salt.minion      :1711][INFO    ][17110] Returning information for job: 20190426030229915635
2019-04-26 03:02:32,831 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1821.1017499s left)
2019-04-26 03:03:00,019 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426030300008703
2019-04-26 03:03:00,042 [salt.minion      :1432][INFO    ][17139] Starting a new job with PID 17139
2019-04-26 03:03:00,064 [salt.minion      :1711][INFO    ][17139] Returning information for job: 20190426030300008703
2019-04-26 03:03:05,663 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1788.27018189s left)
2019-04-26 03:03:30,150 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426030330135280
2019-04-26 03:03:30,173 [salt.minion      :1432][INFO    ][17286] Starting a new job with PID 17286
2019-04-26 03:03:30,196 [salt.minion      :1711][INFO    ][17286] Returning information for job: 20190426030330135280
2019-04-26 03:03:38,419 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1755.51432085s left)
2019-04-26 03:04:00,265 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426030400254770
2019-04-26 03:04:00,284 [salt.minion      :1432][INFO    ][17321] Starting a new job with PID 17321
2019-04-26 03:04:00,308 [salt.minion      :1711][INFO    ][17321] Returning information for job: 20190426030400254770
2019-04-26 03:04:11,254 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1722.67894483s left)
2019-04-26 03:04:30,294 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426030430281299
2019-04-26 03:04:30,320 [salt.minion      :1432][INFO    ][17474] Starting a new job with PID 17474
2019-04-26 03:04:30,343 [salt.minion      :1711][INFO    ][17474] Returning information for job: 20190426030430281299
2019-04-26 03:04:43,978 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1689.95475388s left)
2019-04-26 03:05:00,431 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426030500424280
2019-04-26 03:05:00,445 [salt.minion      :1432][INFO    ][17503] Starting a new job with PID 17503
2019-04-26 03:05:00,467 [salt.minion      :1711][INFO    ][17503] Returning information for job: 20190426030500424280
2019-04-26 03:05:16,801 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1657.13206482s left)
2019-04-26 03:05:30,581 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426030530568216
2019-04-26 03:05:30,599 [salt.minion      :1432][INFO    ][17578] Starting a new job with PID 17578
2019-04-26 03:05:30,624 [salt.minion      :1711][INFO    ][17578] Returning information for job: 20190426030530568216
2019-04-26 03:05:49,846 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1624.08689785s left)
2019-04-26 03:06:00,743 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426030600731599
2019-04-26 03:06:00,764 [salt.minion      :1432][INFO    ][17607] Starting a new job with PID 17607
2019-04-26 03:06:00,787 [salt.minion      :1711][INFO    ][17607] Returning information for job: 20190426030600731599
2019-04-26 03:06:22,648 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1591.284796s left)
2019-04-26 03:06:30,877 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426030630867663
2019-04-26 03:06:30,898 [salt.minion      :1432][INFO    ][17681] Starting a new job with PID 17681
2019-04-26 03:06:30,926 [salt.minion      :1711][INFO    ][17681] Returning information for job: 20190426030630867663
2019-04-26 03:06:55,368 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1558.56458497s left)
2019-04-26 03:07:01,049 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426030701037433
2019-04-26 03:07:01,067 [salt.minion      :1432][INFO    ][17714] Starting a new job with PID 17714
2019-04-26 03:07:01,091 [salt.minion      :1711][INFO    ][17714] Returning information for job: 20190426030701037433
2019-04-26 03:07:28,205 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1525.72830296s left)
2019-04-26 03:07:31,208 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426030731191849
2019-04-26 03:07:31,231 [salt.minion      :1432][INFO    ][17952] Starting a new job with PID 17952
2019-04-26 03:07:31,254 [salt.minion      :1711][INFO    ][17952] Returning information for job: 20190426030731191849
2019-04-26 03:08:01,094 [salt.loaded.ext.module.maas:1023][INFO    ][16027] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1492.83881688s left)
2019-04-26 03:08:01,389 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426030801376625
2019-04-26 03:08:01,413 [salt.minion      :1432][INFO    ][17982] Starting a new job with PID 17982
2019-04-26 03:08:01,437 [salt.minion      :1711][INFO    ][17982] Returning information for job: 20190426030801376625
2019-04-26 03:08:24,955 [salt.utils.schedule:1377][INFO    ][6154] Running scheduled job: __mine_interval
2019-04-26 03:08:31,566 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command saltutil.find_job with jid 20190426030831557921
2019-04-26 03:08:31,583 [salt.minion      :1432][INFO    ][18095] Starting a new job with PID 18095
2019-04-26 03:08:31,608 [salt.minion      :1711][INFO    ][18095] Returning information for job: 20190426030831557921
2019-04-26 03:08:33,840 [salt.state       :300 ][INFO    ][16027] {'ret': True}
2019-04-26 03:08:33,841 [salt.state       :1951][INFO    ][16027] Completed state [maas.wait_for_machine_status] at time 03:08:33.841332 duration_in_ms=789927.554
2019-04-26 03:08:33,847 [salt.minion      :1711][INFO    ][16027] Returning information for job: 20190426025513855236
2019-04-26 04:08:24,954 [salt.utils.schedule:1377][INFO    ][6154] Running scheduled job: __mine_interval
2019-04-26 04:49:56,050 [salt.minion      :1308][INFO    ][6154] User sudo_ubuntu Executing command cp.push_dir with jid 20190426044956040108
2019-04-26 04:49:56,071 [salt.minion      :1432][INFO    ][28469] Starting a new job with PID 28469
