2019-03-14 14:43:59,122 [salt.utils.decorators:613 ][WARNING ][2329] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-14 14:44:00,023 [salt.utils.decorators:613 ][WARNING ][2329] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-14 14:44:02,563 [salt.loaded.int.states.file:2298][WARNING ][2544] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-03-14 14:44:27,349 [salt.state       :2022][WARNING ][2950] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-14 14:44:30,346 [salt.utils.decorators:613 ][WARNING ][2950] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-14 14:46:17,251 [salt.utils.decorators:613 ][WARNING ][2950] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-14 14:46:59,385 [salt.utils.decorators:613 ][WARNING ][2950] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-14 14:47:00,440 [salt.utils.decorators:613 ][WARNING ][2950] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-14 14:47:02,453 [salt.loaded.ext.module.maasng:1008][WARNING ][2950] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-03-14 14:47:02,454 [salt.loaded.ext.module.maasng:1011][WARNING ][2950] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-03-14 14:47:03,243 [salt.loaded.ext.module.maasng:1235][WARNING ][2950] Ignoring parameter vlan:0
2019-03-14 14:47:06,561 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8229] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-14 14:47:06,600 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8229] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-03-14 14:47:06,635 [salt.utils.parsers:1051][WARNING ][379] Minion received a SIGTERM. Exiting.
2019-03-14 14:47:07,609 [salt.cli.daemons :293 ][INFO    ][8305] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-03-14 14:47:07,720 [salt.cli.daemons :82  ][INFO    ][8305] Starting up the Salt Minion
2019-03-14 14:47:07,720 [salt.utils.event :1017][INFO    ][8305] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-03-14 14:47:08,743 [salt.minion      :976 ][INFO    ][8305] Creating minion process manager
2019-03-14 14:47:10,313 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][8305] Executing command ['date', '+%z'] in directory '/root'
2019-03-14 14:47:10,332 [salt.utils.schedule:568 ][INFO    ][8305] Updating job settings for scheduled job: __mine_interval
2019-03-14 14:47:10,334 [salt.minion      :1108][INFO    ][8305] Added mine.update to scheduler
2019-03-14 14:47:10,341 [salt.minion      :1975][INFO    ][8305] Minion is starting as user 'root'
2019-03-14 14:47:10,355 [salt.minion      :2336][INFO    ][8305] Minion is ready to receive requests!
2019-03-14 14:47:12,406 [salt.utils.decorators:613 ][WARNING ][8237] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-14 14:47:12,468 [salt.loaded.ext.module.maas:412 ][WARNING ][8237] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-14 14:47:14,013 [salt.loaded.ext.module.maas:412 ][WARNING ][8237] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-14 14:47:15,384 [salt.loaded.ext.module.maas:412 ][WARNING ][8237] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-14 14:47:16,653 [salt.loaded.ext.module.maas:412 ][WARNING ][8237] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-14 14:47:18,977 [salt.loaded.ext.module.maas:412 ][WARNING ][8237] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-14 14:47:19,657 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314144719649006
2019-03-14 14:47:19,675 [salt.minion      :1432][INFO    ][8661] Starting a new job with PID 8661
2019-03-14 14:47:19,701 [salt.minion      :1711][INFO    ][8661] Returning information for job: 20190314144719649006
2019-03-14 14:47:51,322 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command state.apply with jid 20190314144751309902
2019-03-14 14:47:51,344 [salt.minion      :1432][INFO    ][8739] Starting a new job with PID 8739
2019-03-14 14:47:56,882 [salt.state       :915 ][INFO    ][8739] Loading fresh modules for state activity
2019-03-14 14:47:56,932 [salt.fileclient  :1219][INFO    ][8739] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-03-14 14:47:56,972 [salt.state       :1780][INFO    ][8739] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:47:56.972455
2019-03-14 14:47:56,972 [salt.state       :1813][INFO    ][8739] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-14 14:47:56,974 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8739] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-14 14:47:58,784 [salt.state       :300 ][INFO    ][8739] {'pid': 8746, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-14 14:47:58,785 [salt.state       :1951][INFO    ][8739] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:47:58.785276 duration_in_ms=1812.821
2019-03-14 14:47:58,788 [salt.state       :1780][INFO    ][8739] Running state [maas.wait_for_machine_status] at time 14:47:58.788359
2019-03-14 14:47:58,789 [salt.state       :1813][INFO    ][8739] Executing state module.run for [maas.wait_for_machine_status]
2019-03-14 14:47:58,789 [salt.utils.decorators:613 ][WARNING ][8739] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-14 14:47:59,676 [salt.loaded.ext.module.maas:1023][INFO    ][8739] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.12316799s left)
2019-03-14 14:48:06,380 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314144806367932
2019-03-14 14:48:06,404 [salt.minion      :1432][INFO    ][8776] Starting a new job with PID 8776
2019-03-14 14:48:06,425 [salt.minion      :1711][INFO    ][8776] Returning information for job: 20190314144806367932
2019-03-14 14:48:30,464 [salt.loaded.ext.module.maas:1023][INFO    ][8739] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.33526802s left)
2019-03-14 14:48:36,575 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314144836565306
2019-03-14 14:48:36,588 [salt.minion      :1432][INFO    ][8826] Starting a new job with PID 8826
2019-03-14 14:48:36,609 [salt.minion      :1711][INFO    ][8826] Returning information for job: 20190314144836565306
2019-03-14 14:49:01,265 [salt.loaded.ext.module.maas:1023][INFO    ][8739] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.53427696s left)
2019-03-14 14:49:06,618 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314144906604697
2019-03-14 14:49:06,634 [salt.minion      :1432][INFO    ][8872] Starting a new job with PID 8872
2019-03-14 14:49:06,656 [salt.minion      :1711][INFO    ][8872] Returning information for job: 20190314144906604697
2019-03-14 14:49:32,068 [salt.loaded.ext.module.maas:1023][INFO    ][8739] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.73052096s left)
2019-03-14 14:49:36,670 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314144936660884
2019-03-14 14:49:36,682 [salt.minion      :1432][INFO    ][8927] Starting a new job with PID 8927
2019-03-14 14:49:36,702 [salt.minion      :1711][INFO    ][8927] Returning information for job: 20190314144936660884
2019-03-14 14:50:03,080 [salt.loaded.ext.module.maas:1023][INFO    ][8739] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.71922302s left)
2019-03-14 14:50:06,862 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314145006853732
2019-03-14 14:50:06,885 [salt.minion      :1432][INFO    ][8999] Starting a new job with PID 8999
2019-03-14 14:50:06,907 [salt.minion      :1711][INFO    ][8999] Returning information for job: 20190314145006853732
2019-03-14 14:50:34,278 [salt.loaded.ext.module.maas:1023][INFO    ][8739] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.52056003s left)
2019-03-14 14:50:36,954 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314145036942940
2019-03-14 14:50:36,969 [salt.minion      :1432][INFO    ][9225] Starting a new job with PID 9225
2019-03-14 14:50:36,992 [salt.minion      :1711][INFO    ][9225] Returning information for job: 20190314145036942940
2019-03-14 14:51:05,556 [salt.loaded.ext.module.maas:1023][INFO    ][8739] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.24323392s left)
2019-03-14 14:51:07,028 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314145107010613
2019-03-14 14:51:07,051 [salt.minion      :1432][INFO    ][9357] Starting a new job with PID 9357
2019-03-14 14:51:07,072 [salt.minion      :1711][INFO    ][9357] Returning information for job: 20190314145107010613
2019-03-14 14:51:37,049 [salt.loaded.ext.module.maas:1023][INFO    ][8739] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.74998689s left)
2019-03-14 14:51:37,111 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314145137103296
2019-03-14 14:51:37,125 [salt.minion      :1432][INFO    ][9550] Starting a new job with PID 9550
2019-03-14 14:51:37,147 [salt.minion      :1711][INFO    ][9550] Returning information for job: 20190314145137103296
2019-03-14 14:52:07,175 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314145207165379
2019-03-14 14:52:07,195 [salt.minion      :1432][INFO    ][9727] Starting a new job with PID 9727
2019-03-14 14:52:07,214 [salt.minion      :1711][INFO    ][9727] Returning information for job: 20190314145207165379
2019-03-14 14:52:09,067 [salt.loaded.ext.module.maas:1023][INFO    ][8739] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.73246479s left)
2019-03-14 14:52:37,310 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314145237301971
2019-03-14 14:52:37,322 [salt.minion      :1432][INFO    ][10187] Starting a new job with PID 10187
2019-03-14 14:52:37,345 [salt.minion      :1711][INFO    ][10187] Returning information for job: 20190314145237301971
2019-03-14 14:52:41,166 [salt.loaded.ext.module.maas:1023][INFO    ][8739] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.63298893s left)
2019-03-14 14:53:07,387 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314145307378493
2019-03-14 14:53:07,401 [salt.minion      :1432][INFO    ][10362] Starting a new job with PID 10362
2019-03-14 14:53:07,424 [salt.minion      :1711][INFO    ][10362] Returning information for job: 20190314145307378493
2019-03-14 14:53:13,325 [salt.loaded.ext.module.maas:1023][INFO    ][8739] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.47338581s left)
2019-03-14 14:53:37,464 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314145337453720
2019-03-14 14:53:37,483 [salt.minion      :1432][INFO    ][10434] Starting a new job with PID 10434
2019-03-14 14:53:37,507 [salt.minion      :1711][INFO    ][10434] Returning information for job: 20190314145337453720
2019-03-14 14:53:46,370 [salt.state       :300 ][INFO    ][8739] {'ret': True}
2019-03-14 14:53:46,372 [salt.state       :1951][INFO    ][8739] Completed state [maas.wait_for_machine_status] at time 14:53:46.372496 duration_in_ms=347584.136
2019-03-14 14:53:46,375 [salt.minion      :1711][INFO    ][8739] Returning information for job: 20190314144751309902
2019-03-14 14:53:46,907 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command state.apply with jid 20190314145346898688
2019-03-14 14:53:46,927 [salt.minion      :1432][INFO    ][10553] Starting a new job with PID 10553
2019-03-14 14:53:52,601 [salt.state       :915 ][INFO    ][10553] Loading fresh modules for state activity
2019-03-14 14:53:52,660 [salt.fileclient  :1219][INFO    ][10553] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-03-14 14:53:52,755 [salt.state       :1780][INFO    ][10553] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:53:52.755750
2019-03-14 14:53:52,756 [salt.state       :1813][INFO    ][10553] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-14 14:53:52,757 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10553] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-14 14:53:54,586 [salt.state       :300 ][INFO    ][10553] {'pid': 10565, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-14 14:53:54,588 [salt.state       :1951][INFO    ][10553] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:53:54.588000 duration_in_ms=1832.25
2019-03-14 14:53:54,593 [salt.state       :1780][INFO    ][10553] Running state [maas_machines_storage_cmp002_lvm] at time 14:53:54.593478
2019-03-14 14:53:54,594 [salt.state       :1813][INFO    ][10553] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-14 14:53:55,706 [salt.loaded.ext.module.maasng:610 ][INFO    ][10553] ptmaad
2019-03-14 14:53:55,708 [salt.loaded.ext.module.maasng:626 ][INFO    ][10553] sda
2019-03-14 14:53:56,242 [salt.loaded.ext.module.maasng:361 ][INFO    ][10553] ptmaad
2019-03-14 14:53:56,326 [salt.loaded.ext.module.maasng:367 ][INFO    ][10553] [{u'size': 800109715456, u'partition_table_type': u'MBR', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ptmaad', u'resource_uri': u'/MAAS/api/2.0/nodes/ptmaad/blockdevices/1/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'fb337e87-112e-4934-941a-7d0563281de9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ptmaad', u'device_id': 1, u'filesystem': {u'uuid': u'9d367953-6e55-4af5-93a7-94fd7551f2a4', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/ptmaad/blockdevices/1/partition/1'}]}, {u'size': 800097042432, u'partition_table_type': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'uuid': u'ba6a6f83-93da-408b-b7f8-6461c5bf1f44', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ptmaad', u'resource_uri': u'/MAAS/api/2.0/nodes/ptmaad/blockdevices/3/', u'filesystem': {u'uuid': u'3da133ca-432b-4168-86a4-45fe781b8b67', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'path': u'/dev/disk/by-dname/lvroot', u'type': u'virtual', u'id': 3, u'serial': None, u'partitions': []}]
2019-03-14 14:53:56,326 [salt.loaded.ext.module.maasng:632 ][INFO    ][10553] vgroot
2019-03-14 14:53:56,327 [salt.loaded.ext.module.maasng:635 ][INFO    ][10553] lvroot
2019-03-14 14:53:56,327 [salt.loaded.ext.module.maasng:639 ][INFO    ][10553] 107374182400
2019-03-14 14:53:56,932 [salt.loaded.ext.module.maasng:645 ][INFO    ][10553] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dawg7m', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dawg7m', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dawg7m', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'params': u'', u'system_id': u'ptmaad', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ptmaad/interfaces/4/'}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'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'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'3d9ca5f9-51a1-4e3c-afa3-7bdeae46fdf8', u'resource_uri': u'/MAAS/api/2.0/nodes/ptmaad/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ptmaad', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'61a7a4e6-4f72-4db8-b39f-d36ef6cd507f', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ptmaad', 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'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/ptmaad/blockdevices/1/'}, {u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'6be0a5d0-0892-4193-a6aa-abb941500612', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'ptmaad', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'cdaf6505-f744-42da-bb63-7616e28f1ee0', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/ptmaad/blockdevices/11/'}], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/ptmaad/', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'ptmaad', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ptmaad/blockdevices/11/', u'used_size': 107374182400, u'tags': [], u'uuid': u'6be0a5d0-0892-4193-a6aa-abb941500612', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'ptmaad', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'cdaf6505-f744-42da-bb63-7616e28f1ee0', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'partitions': []}], u'min_hwe_kernel': u'ga-16.04', u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ptmaad', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ptmaad/blockdevices/1/', u'used_size': 800106479616, 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'system_id': u'ptmaad', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'partitions': [{u'uuid': u'3d9ca5f9-51a1-4e3c-afa3-7bdeae46fdf8', u'resource_uri': u'/MAAS/api/2.0/nodes/ptmaad/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ptmaad', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'61a7a4e6-4f72-4db8-b39f-d36ef6cd507f', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dawg7m', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dawg7m', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dawg7m', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'params': u'', u'system_id': u'ptmaad', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ptmaad/interfaces/4/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'params': u'', u'system_id': u'ptmaad', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/ptmaad/interfaces/14/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'params': u'', u'system_id': u'ptmaad', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/ptmaad/interfaces/15/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'params': u'', u'system_id': u'ptmaad', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/ptmaad/interfaces/11/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'params': u'', u'system_id': u'ptmaad', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/ptmaad/interfaces/12/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dawg7m', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dawg7m', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dawg7m', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'params': u'', u'system_id': u'ptmaad', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/ptmaad/interfaces/13/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ptmaad/blockdevices/1/', u'used_size': 800106479616, 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'system_id': u'ptmaad', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'partitions': [{u'uuid': u'3d9ca5f9-51a1-4e3c-afa3-7bdeae46fdf8', u'resource_uri': u'/MAAS/api/2.0/nodes/ptmaad/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ptmaad', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'61a7a4e6-4f72-4db8-b39f-d36ef6cd507f', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}]}}
2019-03-14 14:53:56,934 [salt.state       :300 ][INFO    ][10553] {'new': {'storage_layout': 'lvm'}}
2019-03-14 14:53:56,935 [salt.state       :1951][INFO    ][10553] Completed state [maas_machines_storage_cmp002_lvm] at time 14:53:56.935292 duration_in_ms=2341.814
2019-03-14 14:53:56,935 [salt.state       :1780][INFO    ][10553] Running state [maas_machines_storage_cmp001_lvm] at time 14:53:56.935834
2019-03-14 14:53:56,936 [salt.state       :1813][INFO    ][10553] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-14 14:53:58,042 [salt.loaded.ext.module.maasng:610 ][INFO    ][10553] qxxcer
2019-03-14 14:53:58,043 [salt.loaded.ext.module.maasng:626 ][INFO    ][10553] sda
2019-03-14 14:53:58,696 [salt.loaded.ext.module.maasng:361 ][INFO    ][10553] qxxcer
2019-03-14 14:53:58,785 [salt.loaded.ext.module.maasng:367 ][INFO    ][10553] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'235490d6-b48e-4981-80a2-b85fa92718bf', u'resource_uri': u'/MAAS/api/2.0/nodes/qxxcer/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qxxcer', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'da87250a-ca5a-4ac3-a803-0238ff8d00a7', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'qxxcer', 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'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/qxxcer/blockdevices/2/'}, {u'size': 800097042432, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'ba57c2d6-9d2a-41f2-ae35-6bec966624cd', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'qxxcer', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'331b6be3-7268-4729-8005-c7b959de5a6b', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/qxxcer/blockdevices/4/'}]
2019-03-14 14:53:58,785 [salt.loaded.ext.module.maasng:632 ][INFO    ][10553] vgroot
2019-03-14 14:53:58,786 [salt.loaded.ext.module.maasng:635 ][INFO    ][10553] lvroot
2019-03-14 14:53:58,786 [salt.loaded.ext.module.maasng:639 ][INFO    ][10553] 107374182400
2019-03-14 14:53:59,372 [salt.loaded.ext.module.maasng:645 ][INFO    ][10553] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'dawg7m', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'dawg7m', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'dawg7m', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'qxxcer', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/qxxcer/interfaces/5/'}, u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/qxxcer/blockdevices/2/', u'type': u'physical', u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qxxcer', 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'id': 2, u'partitions': [{u'uuid': u'c500de9a-c1b5-474e-af2b-35fa299088bb', u'resource_uri': u'/MAAS/api/2.0/nodes/qxxcer/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qxxcer', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd32fad06-dd8f-4c72-8723-87ce0129d49b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}]}, u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'qxxcer', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/qxxcer/blockdevices/12/', u'type': u'virtual', u'tags': [], u'uuid': u'6180a505-ffcd-4fcd-bc7a-bf6f9ff2300c', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qxxcer', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'cf972b7c-918e-4dfb-b3d0-338a42e2e105', 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'id': 12, u'partitions': []}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'available_size': 0, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/qxxcer/blockdevices/2/', u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'c500de9a-c1b5-474e-af2b-35fa299088bb', u'resource_uri': u'/MAAS/api/2.0/nodes/qxxcer/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qxxcer', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd32fad06-dd8f-4c72-8723-87ce0129d49b', 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'name': u'sda', u'system_id': u'qxxcer', 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'id': 2, u'size': 800109715456}, {u'model': None, u'available_size': 0, u'uuid': u'6180a505-ffcd-4fcd-bc7a-bf6f9ff2300c', u'resource_uri': u'/MAAS/api/2.0/nodes/qxxcer/blockdevices/12/', u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'tags': [], u'name': u'vgroot-lvroot', u'system_id': u'qxxcer', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'cf972b7c-918e-4dfb-b3d0-338a42e2e105', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/qxxcer/blockdevices/2/', u'type': u'physical', u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'qxxcer', 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'id': 2, u'partitions': [{u'uuid': u'c500de9a-c1b5-474e-af2b-35fa299088bb', u'resource_uri': u'/MAAS/api/2.0/nodes/qxxcer/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qxxcer', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd32fad06-dd8f-4c72-8723-87ce0129d49b', 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'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'qxxcer', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'dawg7m', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'dawg7m', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'dawg7m', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'qxxcer', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/qxxcer/interfaces/5/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'ens2f0', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'qxxcer', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/qxxcer/interfaces/19/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'ens2f1', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'qxxcer', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/qxxcer/interfaces/20/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'qxxcer', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/qxxcer/interfaces/16/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'qxxcer', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/qxxcer/interfaces/18/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'dawg7m', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'dawg7m', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'dawg7m', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'qxxcer', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/qxxcer/interfaces/17/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'current_commissioning_result_id': 4, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/qxxcer/'}
2019-03-14 14:53:59,375 [salt.state       :300 ][INFO    ][10553] {'new': {'storage_layout': 'lvm'}}
2019-03-14 14:53:59,375 [salt.state       :1951][INFO    ][10553] Completed state [maas_machines_storage_cmp001_lvm] at time 14:53:59.375703 duration_in_ms=2439.869
2019-03-14 14:53:59,378 [salt.minion      :1711][INFO    ][10553] Returning information for job: 20190314145346898688
2019-03-14 14:53:59,976 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command state.apply with jid 20190314145359968226
2019-03-14 14:53:59,996 [salt.minion      :1432][INFO    ][10610] Starting a new job with PID 10610
2019-03-14 14:54:00,726 [salt.state       :915 ][INFO    ][10610] Loading fresh modules for state activity
2019-03-14 14:54:00,778 [salt.fileclient  :1219][INFO    ][10610] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-03-14 14:54:00,826 [salt.state       :1780][INFO    ][10610] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:54:00.825957
2019-03-14 14:54:00,826 [salt.state       :1813][INFO    ][10610] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-14 14:54:00,828 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10610] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-14 14:54:02,649 [salt.state       :300 ][INFO    ][10610] {'pid': 10620, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-14 14:54:02,649 [salt.state       :1951][INFO    ][10610] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:54:02.649639 duration_in_ms=1823.682
2019-03-14 14:54:02,651 [salt.state       :1780][INFO    ][10610] Running state [maas.deploy_machines] at time 14:54:02.651858
2019-03-14 14:54:02,652 [salt.state       :1813][INFO    ][10610] Executing state module.run for [maas.deploy_machines]
2019-03-14 14:54:02,652 [salt.utils.decorators:613 ][WARNING ][10610] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-14 14:54:03,341 [salt.loaded.ext.module.maas:684 ][INFO    ][10610] deploymachines hwe_kernel=ga-16.04 system_id=ptmaad distro_series=xenial
2019-03-14 14:54:05,672 [salt.loaded.ext.module.maas:684 ][INFO    ][10610] deploymachines hwe_kernel=ga-16.04 system_id=qxxcer distro_series=xenial
2019-03-14 14:54:07,964 [salt.loaded.ext.module.maas:684 ][INFO    ][10610] deploymachines hwe_kernel=ga-16.04 system_id=bet7hb distro_series=xenial
2019-03-14 14:54:10,255 [salt.loaded.ext.module.maas:684 ][INFO    ][10610] deploymachines hwe_kernel=ga-16.04 system_id=cpdqqp distro_series=xenial
2019-03-14 14:54:12,450 [salt.loaded.ext.module.maas:684 ][INFO    ][10610] deploymachines hwe_kernel=ga-16.04 system_id=nsp8px distro_series=xenial
2019-03-14 14:54:14,349 [salt.state       :300 ][INFO    ][10610] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-14 14:54:14,349 [salt.state       :1951][INFO    ][10610] Completed state [maas.deploy_machines] at time 14:54:14.349668 duration_in_ms=11697.81
2019-03-14 14:54:14,352 [salt.minion      :1711][INFO    ][10610] Returning information for job: 20190314145359968226
2019-03-14 14:54:14,890 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command state.apply with jid 20190314145414881668
2019-03-14 14:54:14,904 [salt.minion      :1432][INFO    ][10911] Starting a new job with PID 10911
2019-03-14 14:54:20,709 [salt.state       :915 ][INFO    ][10911] Loading fresh modules for state activity
2019-03-14 14:54:20,758 [salt.fileclient  :1219][INFO    ][10911] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-03-14 14:54:20,806 [salt.state       :1780][INFO    ][10911] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:54:20.806088
2019-03-14 14:54:20,806 [salt.state       :1813][INFO    ][10911] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-14 14:54:20,808 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10911] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-14 14:54:22,497 [salt.state       :300 ][INFO    ][10911] {'pid': 10941, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-14 14:54:22,497 [salt.state       :1951][INFO    ][10911] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:54:22.497618 duration_in_ms=1691.529
2019-03-14 14:54:22,499 [salt.state       :1780][INFO    ][10911] Running state [maas.wait_for_machine_status] at time 14:54:22.499501
2019-03-14 14:54:22,499 [salt.state       :1813][INFO    ][10911] Executing state module.run for [maas.wait_for_machine_status]
2019-03-14 14:54:22,500 [salt.utils.decorators:613 ][WARNING ][10911] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-14 14:54:24,918 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.59074688s left)
2019-03-14 14:54:29,990 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314145429982000
2019-03-14 14:54:30,014 [salt.minion      :1432][INFO    ][10964] Starting a new job with PID 10964
2019-03-14 14:54:30,038 [salt.minion      :1711][INFO    ][10964] Returning information for job: 20190314145429982000
2019-03-14 14:54:57,678 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.8313508s left)
2019-03-14 14:55:00,068 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314145500054754
2019-03-14 14:55:00,086 [salt.minion      :1432][INFO    ][11049] Starting a new job with PID 11049
2019-03-14 14:55:00,108 [salt.minion      :1711][INFO    ][11049] Returning information for job: 20190314145500054754
2019-03-14 14:55:30,128 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314145530115853
2019-03-14 14:55:30,151 [salt.minion      :1432][INFO    ][11090] Starting a new job with PID 11090
2019-03-14 14:55:30,170 [salt.minion      :1711][INFO    ][11090] Returning information for job: 20190314145530115853
2019-03-14 14:55:30,332 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2182.17712784s left)
2019-03-14 14:56:00,180 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314145600166350
2019-03-14 14:56:00,200 [salt.minion      :1432][INFO    ][11156] Starting a new job with PID 11156
2019-03-14 14:56:00,221 [salt.minion      :1711][INFO    ][11156] Returning information for job: 20190314145600166350
2019-03-14 14:56:03,107 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2149.40194488s left)
2019-03-14 14:56:30,225 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314145630211006
2019-03-14 14:56:30,247 [salt.minion      :1432][INFO    ][11185] Starting a new job with PID 11185
2019-03-14 14:56:30,271 [salt.minion      :1711][INFO    ][11185] Returning information for job: 20190314145630211006
2019-03-14 14:56:35,894 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2116.61468887s left)
2019-03-14 14:57:00,288 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314145700274667
2019-03-14 14:57:00,308 [salt.minion      :1432][INFO    ][11280] Starting a new job with PID 11280
2019-03-14 14:57:00,331 [salt.minion      :1711][INFO    ][11280] Returning information for job: 20190314145700274667
2019-03-14 14:57:08,442 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2084.06664395s left)
2019-03-14 14:57:30,354 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314145730345392
2019-03-14 14:57:30,372 [salt.minion      :1432][INFO    ][11397] Starting a new job with PID 11397
2019-03-14 14:57:30,396 [salt.minion      :1711][INFO    ][11397] Returning information for job: 20190314145730345392
2019-03-14 14:57:41,329 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2051.17971587s left)
2019-03-14 14:58:00,446 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314145800436801
2019-03-14 14:58:00,466 [salt.minion      :1432][INFO    ][11652] Starting a new job with PID 11652
2019-03-14 14:58:00,490 [salt.minion      :1711][INFO    ][11652] Returning information for job: 20190314145800436801
2019-03-14 14:58:14,316 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2018.19282198s left)
2019-03-14 14:58:30,648 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314145830635285
2019-03-14 14:58:30,673 [salt.minion      :1432][INFO    ][11793] Starting a new job with PID 11793
2019-03-14 14:58:30,708 [salt.minion      :1711][INFO    ][11793] Returning information for job: 20190314145830635285
2019-03-14 14:58:47,136 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1985.37336898s left)
2019-03-14 14:59:00,677 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314145900668057
2019-03-14 14:59:00,693 [salt.minion      :1432][INFO    ][12011] Starting a new job with PID 12011
2019-03-14 14:59:00,715 [salt.minion      :1711][INFO    ][12011] Returning information for job: 20190314145900668057
2019-03-14 14:59:20,072 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1952.43688679s left)
2019-03-14 14:59:30,768 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314145930757654
2019-03-14 14:59:30,793 [salt.minion      :1432][INFO    ][12127] Starting a new job with PID 12127
2019-03-14 14:59:30,828 [salt.minion      :1711][INFO    ][12127] Returning information for job: 20190314145930757654
2019-03-14 14:59:52,914 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1919.59448981s left)
2019-03-14 15:00:00,837 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314150000824328
2019-03-14 15:00:00,860 [salt.minion      :1432][INFO    ][12474] Starting a new job with PID 12474
2019-03-14 15:00:00,884 [salt.minion      :1711][INFO    ][12474] Returning information for job: 20190314150000824328
2019-03-14 15:00:25,941 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1886.568362s left)
2019-03-14 15:00:31,056 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314150031045060
2019-03-14 15:00:31,081 [salt.minion      :1432][INFO    ][12670] Starting a new job with PID 12670
2019-03-14 15:00:31,114 [salt.minion      :1711][INFO    ][12670] Returning information for job: 20190314150031045060
2019-03-14 15:00:58,877 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1853.63178277s left)
2019-03-14 15:01:01,100 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314150101085526
2019-03-14 15:01:01,122 [salt.minion      :1432][INFO    ][12925] Starting a new job with PID 12925
2019-03-14 15:01:01,145 [salt.minion      :1711][INFO    ][12925] Returning information for job: 20190314150101085526
2019-03-14 15:01:31,318 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314150131308041
2019-03-14 15:01:31,344 [salt.minion      :1432][INFO    ][13076] Starting a new job with PID 13076
2019-03-14 15:01:31,378 [salt.minion      :1711][INFO    ][13076] Returning information for job: 20190314150131308041
2019-03-14 15:01:32,437 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1820.07173395s left)
2019-03-14 15:02:01,362 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314150201346549
2019-03-14 15:02:01,385 [salt.minion      :1432][INFO    ][13240] Starting a new job with PID 13240
2019-03-14 15:02:01,408 [salt.minion      :1711][INFO    ][13240] Returning information for job: 20190314150201346549
2019-03-14 15:02:05,234 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1787.275038s left)
2019-03-14 15:02:31,373 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314150231364369
2019-03-14 15:02:31,393 [salt.minion      :1432][INFO    ][13322] Starting a new job with PID 13322
2019-03-14 15:02:31,413 [salt.minion      :1711][INFO    ][13322] Returning information for job: 20190314150231364369
2019-03-14 15:02:38,138 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1754.37066984s left)
2019-03-14 15:03:01,445 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314150301435705
2019-03-14 15:03:01,462 [salt.minion      :1432][INFO    ][13444] Starting a new job with PID 13444
2019-03-14 15:03:01,484 [salt.minion      :1711][INFO    ][13444] Returning information for job: 20190314150301435705
2019-03-14 15:03:11,045 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1721.46398878s left)
2019-03-14 15:03:31,501 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314150331490238
2019-03-14 15:03:31,526 [salt.minion      :1432][INFO    ][13626] Starting a new job with PID 13626
2019-03-14 15:03:31,568 [salt.minion      :1711][INFO    ][13626] Returning information for job: 20190314150331490238
2019-03-14 15:03:43,927 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1688.5815289s left)
2019-03-14 15:04:01,550 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314150401542982
2019-03-14 15:04:01,567 [salt.minion      :1432][INFO    ][13783] Starting a new job with PID 13783
2019-03-14 15:04:01,586 [salt.minion      :1711][INFO    ][13783] Returning information for job: 20190314150401542982
2019-03-14 15:04:16,831 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1655.6779108s left)
2019-03-14 15:04:31,572 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314150431565086
2019-03-14 15:04:31,592 [salt.minion      :1432][INFO    ][13972] Starting a new job with PID 13972
2019-03-14 15:04:31,614 [salt.minion      :1711][INFO    ][13972] Returning information for job: 20190314150431565086
2019-03-14 15:04:49,739 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1622.76998687s left)
2019-03-14 15:05:01,597 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314150501585250
2019-03-14 15:05:01,617 [salt.minion      :1432][INFO    ][14029] Starting a new job with PID 14029
2019-03-14 15:05:01,639 [salt.minion      :1711][INFO    ][14029] Returning information for job: 20190314150501585250
2019-03-14 15:05:22,579 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1589.9298408s left)
2019-03-14 15:05:31,629 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314150531617755
2019-03-14 15:05:31,650 [salt.minion      :1432][INFO    ][14083] Starting a new job with PID 14083
2019-03-14 15:05:31,669 [salt.minion      :1711][INFO    ][14083] Returning information for job: 20190314150531617755
2019-03-14 15:05:55,451 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1557.05850792s left)
2019-03-14 15:06:01,694 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314150601686545
2019-03-14 15:06:01,713 [salt.minion      :1432][INFO    ][14232] Starting a new job with PID 14232
2019-03-14 15:06:01,735 [salt.minion      :1711][INFO    ][14232] Returning information for job: 20190314150601686545
2019-03-14 15:06:28,333 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1524.1754818s left)
2019-03-14 15:06:31,740 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314150631730558
2019-03-14 15:06:31,761 [salt.minion      :1432][INFO    ][14450] Starting a new job with PID 14450
2019-03-14 15:06:31,790 [salt.minion      :1711][INFO    ][14450] Returning information for job: 20190314150631730558
2019-03-14 15:07:01,357 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1491.15164995s left)
2019-03-14 15:07:01,812 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314150701801754
2019-03-14 15:07:01,831 [salt.minion      :1432][INFO    ][14574] Starting a new job with PID 14574
2019-03-14 15:07:01,854 [salt.minion      :1711][INFO    ][14574] Returning information for job: 20190314150701801754
2019-03-14 15:07:31,857 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314150731849822
2019-03-14 15:07:31,872 [salt.minion      :1432][INFO    ][14627] Starting a new job with PID 14627
2019-03-14 15:07:31,893 [salt.minion      :1711][INFO    ][14627] Returning information for job: 20190314150731849822
2019-03-14 15:07:34,158 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1458.35115886s left)
2019-03-14 15:08:01,909 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314150801897956
2019-03-14 15:08:01,930 [salt.minion      :1432][INFO    ][14673] Starting a new job with PID 14673
2019-03-14 15:08:01,950 [salt.minion      :1711][INFO    ][14673] Returning information for job: 20190314150801897956
2019-03-14 15:08:06,949 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1425.55946589s left)
2019-03-14 15:08:31,973 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314150831965120
2019-03-14 15:08:31,992 [salt.minion      :1432][INFO    ][14727] Starting a new job with PID 14727
2019-03-14 15:08:32,013 [salt.minion      :1711][INFO    ][14727] Returning information for job: 20190314150831965120
2019-03-14 15:08:39,697 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1392.812356s left)
2019-03-14 15:09:02,075 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314150902061744
2019-03-14 15:09:02,094 [salt.minion      :1432][INFO    ][14774] Starting a new job with PID 14774
2019-03-14 15:09:02,119 [salt.minion      :1711][INFO    ][14774] Returning information for job: 20190314150902061744
2019-03-14 15:09:12,611 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1359.89766097s left)
2019-03-14 15:09:32,149 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314150932142805
2019-03-14 15:09:32,162 [salt.minion      :1432][INFO    ][14828] Starting a new job with PID 14828
2019-03-14 15:09:32,184 [salt.minion      :1711][INFO    ][14828] Returning information for job: 20190314150932142805
2019-03-14 15:09:45,582 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1326.92722797s left)
2019-03-14 15:10:02,184 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314151002171503
2019-03-14 15:10:02,205 [salt.minion      :1432][INFO    ][14888] Starting a new job with PID 14888
2019-03-14 15:10:02,224 [salt.minion      :1711][INFO    ][14888] Returning information for job: 20190314151002171503
2019-03-14 15:10:18,316 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1294.1925478s left)
2019-03-14 15:10:32,237 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314151032227798
2019-03-14 15:10:32,256 [salt.minion      :1432][INFO    ][14942] Starting a new job with PID 14942
2019-03-14 15:10:32,279 [salt.minion      :1711][INFO    ][14942] Returning information for job: 20190314151032227798
2019-03-14 15:10:50,970 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1261.53885984s left)
2019-03-14 15:11:02,277 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314151102265129
2019-03-14 15:11:02,297 [salt.minion      :1432][INFO    ][14998] Starting a new job with PID 14998
2019-03-14 15:11:02,317 [salt.minion      :1711][INFO    ][14998] Returning information for job: 20190314151102265129
2019-03-14 15:11:23,634 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1228.87483478s left)
2019-03-14 15:11:32,347 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314151132336816
2019-03-14 15:11:32,370 [salt.minion      :1432][INFO    ][15054] Starting a new job with PID 15054
2019-03-14 15:11:32,396 [salt.minion      :1711][INFO    ][15054] Returning information for job: 20190314151132336816
2019-03-14 15:11:56,282 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1196.22682786s left)
2019-03-14 15:12:02,419 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314151202405607
2019-03-14 15:12:02,438 [salt.minion      :1432][INFO    ][15103] Starting a new job with PID 15103
2019-03-14 15:12:02,459 [salt.minion      :1711][INFO    ][15103] Returning information for job: 20190314151202405607
2019-03-14 15:12:29,073 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1163.43580198s left)
2019-03-14 15:12:32,533 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314151232518098
2019-03-14 15:12:32,554 [salt.minion      :1432][INFO    ][15157] Starting a new job with PID 15157
2019-03-14 15:12:32,574 [salt.minion      :1711][INFO    ][15157] Returning information for job: 20190314151232518098
2019-03-14 15:13:01,972 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1130.53655982s left)
2019-03-14 15:13:02,605 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314151302594767
2019-03-14 15:13:02,626 [salt.minion      :1432][INFO    ][15208] Starting a new job with PID 15208
2019-03-14 15:13:02,648 [salt.minion      :1711][INFO    ][15208] Returning information for job: 20190314151302594767
2019-03-14 15:13:32,742 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314151332731893
2019-03-14 15:13:32,758 [salt.minion      :1432][INFO    ][15259] Starting a new job with PID 15259
2019-03-14 15:13:32,778 [salt.minion      :1711][INFO    ][15259] Returning information for job: 20190314151332731893
2019-03-14 15:13:34,714 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1097.79538989s left)
2019-03-14 15:14:02,849 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314151402834721
2019-03-14 15:14:02,873 [salt.minion      :1432][INFO    ][15306] Starting a new job with PID 15306
2019-03-14 15:14:02,892 [salt.minion      :1711][INFO    ][15306] Returning information for job: 20190314151402834721
2019-03-14 15:14:07,438 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1065.07104182s left)
2019-03-14 15:14:32,973 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314151432959564
2019-03-14 15:14:32,998 [salt.minion      :1432][INFO    ][15375] Starting a new job with PID 15375
2019-03-14 15:14:33,026 [salt.minion      :1711][INFO    ][15375] Returning information for job: 20190314151432959564
2019-03-14 15:14:40,208 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1032.30136991s left)
2019-03-14 15:15:03,045 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314151503030219
2019-03-14 15:15:03,066 [salt.minion      :1432][INFO    ][15432] Starting a new job with PID 15432
2019-03-14 15:15:03,085 [salt.minion      :1711][INFO    ][15432] Returning information for job: 20190314151503030219
2019-03-14 15:15:12,850 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (999.659280777s left)
2019-03-14 15:15:33,119 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314151533111475
2019-03-14 15:15:33,133 [salt.minion      :1432][INFO    ][15486] Starting a new job with PID 15486
2019-03-14 15:15:33,156 [salt.minion      :1711][INFO    ][15486] Returning information for job: 20190314151533111475
2019-03-14 15:15:45,449 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (967.059741974s left)
2019-03-14 15:16:03,215 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314151603205145
2019-03-14 15:16:03,229 [salt.minion      :1432][INFO    ][15540] Starting a new job with PID 15540
2019-03-14 15:16:03,249 [salt.minion      :1711][INFO    ][15540] Returning information for job: 20190314151603205145
2019-03-14 15:16:18,230 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (934.279340982s left)
2019-03-14 15:16:33,408 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314151633393711
2019-03-14 15:16:33,427 [salt.minion      :1432][INFO    ][15594] Starting a new job with PID 15594
2019-03-14 15:16:33,448 [salt.minion      :1711][INFO    ][15594] Returning information for job: 20190314151633393711
2019-03-14 15:16:50,977 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (901.531955004s left)
2019-03-14 15:17:03,428 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314151703414026
2019-03-14 15:17:03,450 [salt.minion      :1432][INFO    ][15646] Starting a new job with PID 15646
2019-03-14 15:17:03,469 [salt.minion      :1711][INFO    ][15646] Returning information for job: 20190314151703414026
2019-03-14 15:17:23,730 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (868.778859854s left)
2019-03-14 15:17:33,638 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314151733630904
2019-03-14 15:17:33,655 [salt.minion      :1432][INFO    ][15700] Starting a new job with PID 15700
2019-03-14 15:17:33,676 [salt.minion      :1711][INFO    ][15700] Returning information for job: 20190314151733630904
2019-03-14 15:17:56,407 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (836.101498842s left)
2019-03-14 15:18:03,850 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314151803842326
2019-03-14 15:18:03,871 [salt.minion      :1432][INFO    ][15748] Starting a new job with PID 15748
2019-03-14 15:18:03,889 [salt.minion      :1711][INFO    ][15748] Returning information for job: 20190314151803842326
2019-03-14 15:18:29,056 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (803.452535868s left)
2019-03-14 15:18:34,058 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314151834050323
2019-03-14 15:18:34,078 [salt.minion      :1432][INFO    ][15802] Starting a new job with PID 15802
2019-03-14 15:18:34,099 [salt.minion      :1711][INFO    ][15802] Returning information for job: 20190314151834050323
2019-03-14 15:19:01,858 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (770.651187897s left)
2019-03-14 15:19:04,154 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314151904141233
2019-03-14 15:19:04,172 [salt.minion      :1432][INFO    ][15856] Starting a new job with PID 15856
2019-03-14 15:19:04,192 [salt.minion      :1711][INFO    ][15856] Returning information for job: 20190314151904141233
2019-03-14 15:19:34,184 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314151934175602
2019-03-14 15:19:34,200 [salt.minion      :1432][INFO    ][15910] Starting a new job with PID 15910
2019-03-14 15:19:34,220 [salt.minion      :1711][INFO    ][15910] Returning information for job: 20190314151934175602
2019-03-14 15:19:34,583 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (737.926287889s left)
2019-03-14 15:20:04,317 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314152004303115
2019-03-14 15:20:04,339 [salt.minion      :1432][INFO    ][15954] Starting a new job with PID 15954
2019-03-14 15:20:04,362 [salt.minion      :1711][INFO    ][15954] Returning information for job: 20190314152004303115
2019-03-14 15:20:07,401 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (705.108372927s left)
2019-03-14 15:20:34,386 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314152034376714
2019-03-14 15:20:34,400 [salt.minion      :1432][INFO    ][16010] Starting a new job with PID 16010
2019-03-14 15:20:34,421 [salt.minion      :1711][INFO    ][16010] Returning information for job: 20190314152034376714
2019-03-14 15:20:40,158 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (672.351398945s left)
2019-03-14 15:21:04,555 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314152104541886
2019-03-14 15:21:04,577 [salt.minion      :1432][INFO    ][16059] Starting a new job with PID 16059
2019-03-14 15:21:04,598 [salt.minion      :1711][INFO    ][16059] Returning information for job: 20190314152104541886
2019-03-14 15:21:12,801 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (639.707494974s left)
2019-03-14 15:21:34,691 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314152134676719
2019-03-14 15:21:34,712 [salt.minion      :1432][INFO    ][16113] Starting a new job with PID 16113
2019-03-14 15:21:34,736 [salt.minion      :1711][INFO    ][16113] Returning information for job: 20190314152134676719
2019-03-14 15:21:45,472 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (607.036816835s left)
2019-03-14 15:22:04,896 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314152204883926
2019-03-14 15:22:04,917 [salt.minion      :1432][INFO    ][16162] Starting a new job with PID 16162
2019-03-14 15:22:04,939 [salt.minion      :1711][INFO    ][16162] Returning information for job: 20190314152204883926
2019-03-14 15:22:18,300 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (574.208792925s left)
2019-03-14 15:22:35,085 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314152235077760
2019-03-14 15:22:35,100 [salt.minion      :1432][INFO    ][16216] Starting a new job with PID 16216
2019-03-14 15:22:35,121 [salt.minion      :1711][INFO    ][16216] Returning information for job: 20190314152235077760
2019-03-14 15:22:50,963 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (541.545863867s left)
2019-03-14 15:23:05,270 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314152305256827
2019-03-14 15:23:05,290 [salt.minion      :1432][INFO    ][16265] Starting a new job with PID 16265
2019-03-14 15:23:05,313 [salt.minion      :1711][INFO    ][16265] Returning information for job: 20190314152305256827
2019-03-14 15:23:23,745 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (508.763670921s left)
2019-03-14 15:23:35,291 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314152335282363
2019-03-14 15:23:35,311 [salt.minion      :1432][INFO    ][16317] Starting a new job with PID 16317
2019-03-14 15:23:35,334 [salt.minion      :1711][INFO    ][16317] Returning information for job: 20190314152335282363
2019-03-14 15:23:56,617 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (475.891897917s left)
2019-03-14 15:24:05,493 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314152405485580
2019-03-14 15:24:05,507 [salt.minion      :1432][INFO    ][16366] Starting a new job with PID 16366
2019-03-14 15:24:05,530 [salt.minion      :1711][INFO    ][16366] Returning information for job: 20190314152405485580
2019-03-14 15:24:29,282 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (443.227210999s left)
2019-03-14 15:24:35,600 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314152435584636
2019-03-14 15:24:35,623 [salt.minion      :1432][INFO    ][16558] Starting a new job with PID 16558
2019-03-14 15:24:35,645 [salt.minion      :1711][INFO    ][16558] Returning information for job: 20190314152435584636
2019-03-14 15:25:02,143 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (410.365653992s left)
2019-03-14 15:25:05,653 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314152505636671
2019-03-14 15:25:05,674 [salt.minion      :1432][INFO    ][16618] Starting a new job with PID 16618
2019-03-14 15:25:05,695 [salt.minion      :1711][INFO    ][16618] Returning information for job: 20190314152505636671
2019-03-14 15:25:34,770 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (377.739062786s left)
2019-03-14 15:25:35,745 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314152535734420
2019-03-14 15:25:35,766 [salt.minion      :1432][INFO    ][16672] Starting a new job with PID 16672
2019-03-14 15:25:35,794 [salt.minion      :1711][INFO    ][16672] Returning information for job: 20190314152535734420
2019-03-14 15:26:05,826 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314152605817622
2019-03-14 15:26:05,841 [salt.minion      :1432][INFO    ][16717] Starting a new job with PID 16717
2019-03-14 15:26:05,864 [salt.minion      :1711][INFO    ][16717] Returning information for job: 20190314152605817622
2019-03-14 15:26:07,461 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (345.047454834s left)
2019-03-14 15:26:36,017 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314152636000301
2019-03-14 15:26:36,035 [salt.minion      :1432][INFO    ][16769] Starting a new job with PID 16769
2019-03-14 15:26:36,058 [salt.minion      :1711][INFO    ][16769] Returning information for job: 20190314152636000301
2019-03-14 15:26:40,121 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (312.388194799s left)
2019-03-14 15:27:06,108 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314152706100327
2019-03-14 15:27:06,129 [salt.minion      :1432][INFO    ][16816] Starting a new job with PID 16816
2019-03-14 15:27:06,152 [salt.minion      :1711][INFO    ][16816] Returning information for job: 20190314152706100327
2019-03-14 15:27:13,039 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (279.470068932s left)
2019-03-14 15:27:36,309 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314152736299427
2019-03-14 15:27:36,326 [salt.minion      :1432][INFO    ][16874] Starting a new job with PID 16874
2019-03-14 15:27:36,348 [salt.minion      :1711][INFO    ][16874] Returning information for job: 20190314152736299427
2019-03-14 15:27:45,765 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (246.744250774s left)
2019-03-14 15:28:06,440 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314152806428945
2019-03-14 15:28:06,462 [salt.minion      :1432][INFO    ][16924] Starting a new job with PID 16924
2019-03-14 15:28:06,485 [salt.minion      :1711][INFO    ][16924] Returning information for job: 20190314152806428945
2019-03-14 15:28:18,451 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (214.058016777s left)
2019-03-14 15:28:36,502 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314152836487728
2019-03-14 15:28:36,523 [salt.minion      :1432][INFO    ][16981] Starting a new job with PID 16981
2019-03-14 15:28:36,545 [salt.minion      :1711][INFO    ][16981] Returning information for job: 20190314152836487728
2019-03-14 15:28:51,145 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (181.364371777s left)
2019-03-14 15:29:06,528 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314152906520537
2019-03-14 15:29:06,541 [salt.minion      :1432][INFO    ][17028] Starting a new job with PID 17028
2019-03-14 15:29:06,563 [salt.minion      :1711][INFO    ][17028] Returning information for job: 20190314152906520537
2019-03-14 15:29:23,673 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (148.83630991s left)
2019-03-14 15:29:36,564 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314152936557536
2019-03-14 15:29:36,578 [salt.minion      :1432][INFO    ][17085] Starting a new job with PID 17085
2019-03-14 15:29:36,606 [salt.minion      :1711][INFO    ][17085] Returning information for job: 20190314152936557536
2019-03-14 15:29:56,425 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (116.083614826s left)
2019-03-14 15:30:06,749 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314153006733610
2019-03-14 15:30:06,766 [salt.minion      :1432][INFO    ][17132] Starting a new job with PID 17132
2019-03-14 15:30:06,788 [salt.minion      :1711][INFO    ][17132] Returning information for job: 20190314153006733610
2019-03-14 15:30:29,194 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (83.3147227764s left)
2019-03-14 15:30:36,863 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314153036856298
2019-03-14 15:30:36,877 [salt.minion      :1432][INFO    ][17192] Starting a new job with PID 17192
2019-03-14 15:30:36,900 [salt.minion      :1711][INFO    ][17192] Returning information for job: 20190314153036856298
2019-03-14 15:31:02,171 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (50.3377859592s left)
2019-03-14 15:31:07,005 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314153106990633
2019-03-14 15:31:07,027 [salt.minion      :1432][INFO    ][17237] Starting a new job with PID 17237
2019-03-14 15:31:07,050 [salt.minion      :1711][INFO    ][17237] Returning information for job: 20190314153106990633
2019-03-14 15:31:34,889 [salt.loaded.ext.module.maas:1023][INFO    ][10911] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (17.6199359894s left)
2019-03-14 15:31:37,077 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314153137067681
2019-03-14 15:31:37,093 [salt.minion      :1432][INFO    ][17301] Starting a new job with PID 17301
2019-03-14 15:31:37,120 [salt.minion      :1711][INFO    ][17301] Returning information for job: 20190314153137067681
2019-03-14 15:32:07,260 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314153207250742
2019-03-14 15:32:07,273 [salt.minion      :1432][INFO    ][17343] Starting a new job with PID 17343
2019-03-14 15:32:07,297 [salt.minion      :1711][INFO    ][17343] Returning information for job: 20190314153207250742
2019-03-14 15:32:07,570 [salt.state       :302 ][ERROR   ][10911] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-03-14 15:32:07,571 [salt.state       :1951][INFO    ][10911] Completed state [maas.wait_for_machine_status] at time 15:32:07.570744 duration_in_ms=2265071.227
2019-03-14 15:32:07,578 [salt.minion      :1711][INFO    ][10911] Returning information for job: 20190314145414881668
2019-03-14 15:32:18,346 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command pillar.get with jid 20190314153218339306
2019-03-14 15:32:18,365 [salt.minion      :1432][INFO    ][17364] Starting a new job with PID 17364
2019-03-14 15:32:18,374 [salt.minion      :1711][INFO    ][17364] Returning information for job: 20190314153218339306
2019-03-14 15:32:18,919 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command service.status with jid 20190314153218906227
2019-03-14 15:32:18,939 [salt.minion      :1432][INFO    ][17369] Starting a new job with PID 17369
2019-03-14 15:32:19,453 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][17369] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-03-14 15:32:19,487 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][17369] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-03-14 15:32:19,505 [salt.minion      :1711][INFO    ][17369] Returning information for job: 20190314153218906227
2019-03-14 15:32:20,048 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command state.apply with jid 20190314153220038445
2019-03-14 15:32:20,069 [salt.minion      :1432][INFO    ][17380] Starting a new job with PID 17380
2019-03-14 15:32:25,672 [salt.state       :915 ][INFO    ][17380] Loading fresh modules for state activity
2019-03-14 15:32:26,176 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17380] Executing command 'salt-minion --version' in directory '/root'
2019-03-14 15:32:26,458 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17380] Executing command 'salt-minion --version' in directory '/root'
2019-03-14 15:32:27,353 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17380] Executing command 'salt-minion --version' in directory '/root'
2019-03-14 15:32:27,637 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17380] Executing command 'salt-minion --version' in directory '/root'
2019-03-14 15:32:29,401 [salt.state       :1780][INFO    ][17380] Running state [salt-minion] at time 15:32:29.401797
2019-03-14 15:32:29,402 [salt.state       :1813][INFO    ][17380] Executing state pkg.installed for [salt-minion]
2019-03-14 15:32:29,403 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17380] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-14 15:32:29,505 [salt.state       :300 ][INFO    ][17380] All specified packages are already installed
2019-03-14 15:32:29,505 [salt.state       :1951][INFO    ][17380] Completed state [salt-minion] at time 15:32:29.505345 duration_in_ms=103.549
2019-03-14 15:32:29,505 [salt.state       :1780][INFO    ][17380] Running state [salt_minion_dependency_packages] at time 15:32:29.505678
2019-03-14 15:32:29,505 [salt.state       :1813][INFO    ][17380] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-03-14 15:32:29,516 [salt.state       :300 ][INFO    ][17380] All specified packages are already installed
2019-03-14 15:32:29,516 [salt.state       :1951][INFO    ][17380] Completed state [salt_minion_dependency_packages] at time 15:32:29.516379 duration_in_ms=10.701
2019-03-14 15:32:29,519 [salt.state       :1780][INFO    ][17380] Running state [/etc/salt/minion.d/minion.conf] at time 15:32:29.519452
2019-03-14 15:32:29,519 [salt.state       :1813][INFO    ][17380] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-03-14 15:32:29,788 [salt.state       :300 ][INFO    ][17380] File /etc/salt/minion.d/minion.conf is in the correct state
2019-03-14 15:32:29,788 [salt.state       :1951][INFO    ][17380] Completed state [/etc/salt/minion.d/minion.conf] at time 15:32:29.788675 duration_in_ms=269.223
2019-03-14 15:32:29,788 [salt.state       :1780][INFO    ][17380] Running state [python-netaddr] at time 15:32:29.788933
2019-03-14 15:32:29,789 [salt.state       :1813][INFO    ][17380] Executing state pkg.installed for [python-netaddr]
2019-03-14 15:32:29,797 [salt.state       :300 ][INFO    ][17380] All specified packages are already installed
2019-03-14 15:32:29,797 [salt.state       :1951][INFO    ][17380] Completed state [python-netaddr] at time 15:32:29.797919 duration_in_ms=8.986
2019-03-14 15:32:29,801 [salt.state       :1780][INFO    ][17380] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 15:32:29.801193
2019-03-14 15:32:29,801 [salt.state       :1813][INFO    ][17380] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-03-14 15:32:29,810 [salt.state       :300 ][INFO    ][17380] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-03-14 15:32:29,810 [salt.state       :1951][INFO    ][17380] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 15:32:29.810676 duration_in_ms=9.482
2019-03-14 15:32:29,814 [salt.state       :1780][INFO    ][17380] Running state [salt-minion] at time 15:32:29.814127
2019-03-14 15:32:29,814 [salt.state       :1813][INFO    ][17380] Executing state service.running for [salt-minion]
2019-03-14 15:32:29,815 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17380] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-14 15:32:29,854 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17380] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-03-14 15:32:29,870 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17380] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-03-14 15:32:29,888 [salt.state       :300 ][INFO    ][17380] The service salt-minion is already running
2019-03-14 15:32:29,889 [salt.state       :1951][INFO    ][17380] Completed state [salt-minion] at time 15:32:29.889316 duration_in_ms=75.187
2019-03-14 15:32:29,892 [salt.state       :1780][INFO    ][17380] Running state [/etc/salt/grains.d] at time 15:32:29.892274
2019-03-14 15:32:29,892 [salt.state       :1813][INFO    ][17380] Executing state file.directory for [/etc/salt/grains.d]
2019-03-14 15:32:29,894 [salt.state       :300 ][INFO    ][17380] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-03-14 15:32:29,894 [salt.state       :1951][INFO    ][17380] Completed state [/etc/salt/grains.d] at time 15:32:29.894431 duration_in_ms=2.156
2019-03-14 15:32:29,897 [salt.state       :1780][INFO    ][17380] Running state [/etc/salt/grains] at time 15:32:29.897328
2019-03-14 15:32:29,897 [salt.state       :1813][INFO    ][17380] Executing state file.managed for [/etc/salt/grains]
2019-03-14 15:32:29,898 [salt.state       :300 ][INFO    ][17380] File /etc/salt/grains exists with proper permissions. No changes made.
2019-03-14 15:32:29,898 [salt.state       :1951][INFO    ][17380] Completed state [/etc/salt/grains] at time 15:32:29.898495 duration_in_ms=1.167
2019-03-14 15:32:29,899 [salt.state       :1780][INFO    ][17380] Running state [/etc/salt/grains.d/placeholder] at time 15:32:29.899066
2019-03-14 15:32:29,899 [salt.state       :1813][INFO    ][17380] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-03-14 15:32:29,899 [salt.state       :300 ][INFO    ][17380] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-03-14 15:32:29,900 [salt.state       :1951][INFO    ][17380] Completed state [/etc/salt/grains.d/placeholder] at time 15:32:29.900188 duration_in_ms=1.122
2019-03-14 15:32:29,900 [salt.state       :1780][INFO    ][17380] Running state [/etc/salt/grains.d/sphinx] at time 15:32:29.900743
2019-03-14 15:32:29,901 [salt.state       :1813][INFO    ][17380] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-03-14 15:32:29,902 [salt.state       :300 ][INFO    ][17380] File /etc/salt/grains.d/sphinx is in the correct state
2019-03-14 15:32:29,903 [salt.state       :1951][INFO    ][17380] Completed state [/etc/salt/grains.d/sphinx] at time 15:32:29.903152 duration_in_ms=2.408
2019-03-14 15:32:29,908 [salt.state       :1780][INFO    ][17380] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 15:32:29.908789
2019-03-14 15:32:29,909 [salt.state       :1813][INFO    ][17380] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-03-14 15:32:29,909 [salt.state       :300 ][INFO    ][17380] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-03-14 15:32:29,909 [salt.state       :1951][INFO    ][17380] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 15:32:29.909567 duration_in_ms=0.778
2019-03-14 15:32:29,910 [salt.state       :1780][INFO    ][17380] Running state [/etc/salt/grains.d/dns_records] at time 15:32:29.910039
2019-03-14 15:32:29,910 [salt.state       :1813][INFO    ][17380] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-03-14 15:32:29,911 [salt.state       :300 ][INFO    ][17380] File /etc/salt/grains.d/dns_records is in the correct state
2019-03-14 15:32:29,911 [salt.state       :1951][INFO    ][17380] Completed state [/etc/salt/grains.d/dns_records] at time 15:32:29.911593 duration_in_ms=1.554
2019-03-14 15:32:29,912 [salt.state       :1780][INFO    ][17380] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 15:32:29.912498
2019-03-14 15:32:29,912 [salt.state       :1813][INFO    ][17380] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-03-14 15:32:29,913 [salt.state       :300 ][INFO    ][17380] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-03-14 15:32:29,913 [salt.state       :1951][INFO    ][17380] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 15:32:29.913247 duration_in_ms=0.75
2019-03-14 15:32:29,913 [salt.state       :1780][INFO    ][17380] Running state [/etc/salt/grains.d/salt] at time 15:32:29.913718
2019-03-14 15:32:29,913 [salt.state       :1813][INFO    ][17380] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-03-14 15:32:29,915 [salt.state       :300 ][INFO    ][17380] File /etc/salt/grains.d/salt is in the correct state
2019-03-14 15:32:29,916 [salt.state       :1951][INFO    ][17380] Completed state [/etc/salt/grains.d/salt] at time 15:32:29.916038 duration_in_ms=2.319
2019-03-14 15:32:29,917 [salt.state       :1780][INFO    ][17380] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 15:32:29.917475
2019-03-14 15:32:29,917 [salt.state       :1813][INFO    ][17380] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-03-14 15:32:29,918 [salt.state       :300 ][INFO    ][17380] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-03-14 15:32:29,918 [salt.state       :1951][INFO    ][17380] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 15:32:29.918641 duration_in_ms=1.167
2019-03-14 15:32:29,922 [salt.state       :1780][INFO    ][17380] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 15:32:29.922532
2019-03-14 15:32:29,922 [salt.state       :1813][INFO    ][17380] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-03-14 15:32:29,923 [salt.state       :300 ][INFO    ][17380] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-03-14 15:32:29,923 [salt.state       :1951][INFO    ][17380] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 15:32:29.923326 duration_in_ms=0.795
2019-03-14 15:32:29,924 [salt.state       :1780][INFO    ][17380] Running state [mine.update] at time 15:32:29.923979
2019-03-14 15:32:29,924 [salt.state       :1813][INFO    ][17380] Executing state module.wait for [mine.update]
2019-03-14 15:32:29,924 [salt.state       :300 ][INFO    ][17380] No changes made for mine.update
2019-03-14 15:32:29,924 [salt.state       :1951][INFO    ][17380] Completed state [mine.update] at time 15:32:29.924663 duration_in_ms=0.684
2019-03-14 15:32:29,924 [salt.state       :1780][INFO    ][17380] Running state [ca-certificates] at time 15:32:29.924895
2019-03-14 15:32:29,925 [salt.state       :1813][INFO    ][17380] Executing state pkg.installed for [ca-certificates]
2019-03-14 15:32:29,935 [salt.state       :300 ][INFO    ][17380] All specified packages are already installed
2019-03-14 15:32:29,935 [salt.state       :1951][INFO    ][17380] Completed state [ca-certificates] at time 15:32:29.935339 duration_in_ms=10.444
2019-03-14 15:32:29,936 [salt.state       :1780][INFO    ][17380] Running state [update-ca-certificates] at time 15:32:29.936017
2019-03-14 15:32:29,936 [salt.state       :1813][INFO    ][17380] Executing state cmd.wait for [update-ca-certificates]
2019-03-14 15:32:29,936 [salt.state       :300 ][INFO    ][17380] No changes made for update-ca-certificates
2019-03-14 15:32:29,936 [salt.state       :1951][INFO    ][17380] Completed state [update-ca-certificates] at time 15:32:29.936717 duration_in_ms=0.7
2019-03-14 15:32:29,936 [salt.state       :1780][INFO    ][17380] Running state [iptables] at time 15:32:29.936948
2019-03-14 15:32:29,937 [salt.state       :1813][INFO    ][17380] Executing state pkg.installed for [iptables]
2019-03-14 15:32:29,945 [salt.state       :300 ][INFO    ][17380] All specified packages are already installed
2019-03-14 15:32:29,946 [salt.state       :1951][INFO    ][17380] Completed state [iptables] at time 15:32:29.946085 duration_in_ms=9.137
2019-03-14 15:32:29,946 [salt.state       :1780][INFO    ][17380] Running state [iptables-persistent] at time 15:32:29.946325
2019-03-14 15:32:29,946 [salt.state       :1813][INFO    ][17380] Executing state pkg.installed for [iptables-persistent]
2019-03-14 15:32:29,955 [salt.state       :300 ][INFO    ][17380] All specified packages are already installed
2019-03-14 15:32:29,956 [salt.state       :1951][INFO    ][17380] Completed state [iptables-persistent] at time 15:32:29.956115 duration_in_ms=9.79
2019-03-14 15:32:29,957 [salt.state       :1780][INFO    ][17380] Running state [iptables_modules_v4_load] at time 15:32:29.957266
2019-03-14 15:32:29,957 [salt.state       :1813][INFO    ][17380] Executing state kmod.present for [iptables_modules_v4_load]
2019-03-14 15:32:29,958 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17380] Executing command 'lsmod' in directory '/root'
2019-03-14 15:32:29,977 [salt.state       :300 ][INFO    ][17380] Kernel modules iptable_filter, ip_tables are already present
2019-03-14 15:32:29,978 [salt.state       :1951][INFO    ][17380] Completed state [iptables_modules_v4_load] at time 15:32:29.978501 duration_in_ms=21.235
2019-03-14 15:32:29,981 [salt.state       :1780][INFO    ][17380] Running state [/etc/iptables/rules.v4] at time 15:32:29.980985
2019-03-14 15:32:29,981 [salt.state       :1813][INFO    ][17380] Executing state file.managed for [/etc/iptables/rules.v4]
2019-03-14 15:32:30,078 [salt.state       :300 ][INFO    ][17380] File /etc/iptables/rules.v4 is in the correct state
2019-03-14 15:32:30,078 [salt.state       :1951][INFO    ][17380] Completed state [/etc/iptables/rules.v4] at time 15:32:30.078425 duration_in_ms=97.44
2019-03-14 15:32:30,079 [salt.state       :1780][INFO    ][17380] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 15:32:30.079509
2019-03-14 15:32:30,079 [salt.state       :1813][INFO    ][17380] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-03-14 15:32:30,080 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17380] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-03-14 15:32:30,097 [salt.state       :300 ][INFO    ][17380] onlyif execution failed
2019-03-14 15:32:30,098 [salt.state       :1951][INFO    ][17380] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 15:32:30.098095 duration_in_ms=18.586
2019-03-14 15:32:30,101 [salt.state       :1780][INFO    ][17380] Running state [netfilter-persistent] at time 15:32:30.101704
2019-03-14 15:32:30,102 [salt.state       :1813][INFO    ][17380] Executing state service.running for [netfilter-persistent]
2019-03-14 15:32:30,103 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17380] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-03-14 15:32:30,125 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17380] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-03-14 15:32:30,143 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17380] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-03-14 15:32:30,164 [salt.state       :300 ][INFO    ][17380] The service netfilter-persistent is already running
2019-03-14 15:32:30,165 [salt.state       :1951][INFO    ][17380] Completed state [netfilter-persistent] at time 15:32:30.165035 duration_in_ms=63.33
2019-03-14 15:32:30,166 [salt.state       :1780][INFO    ][17380] Running state [iptables_extra.remove_stale_tables] at time 15:32:30.166695
2019-03-14 15:32:30,167 [salt.state       :1813][INFO    ][17380] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-03-14 15:32:30,167 [salt.state       :300 ][INFO    ][17380] No changes made for iptables_extra.remove_stale_tables
2019-03-14 15:32:30,168 [salt.state       :1951][INFO    ][17380] Completed state [iptables_extra.remove_stale_tables] at time 15:32:30.168365 duration_in_ms=1.67
2019-03-14 15:32:30,168 [salt.state       :1780][INFO    ][17380] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 15:32:30.168911
2019-03-14 15:32:30,169 [salt.state       :1813][INFO    ][17380] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-03-14 15:32:30,170 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17380] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-03-14 15:32:30,188 [salt.state       :300 ][INFO    ][17380] onlyif execution failed
2019-03-14 15:32:30,188 [salt.state       :1951][INFO    ][17380] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 15:32:30.188539 duration_in_ms=19.629
2019-03-14 15:32:30,190 [salt.state       :1780][INFO    ][17380] Running state [/etc/iptables/rules.v6] at time 15:32:30.189986
2019-03-14 15:32:30,190 [salt.state       :1813][INFO    ][17380] Executing state file.absent for [/etc/iptables/rules.v6]
2019-03-14 15:32:30,191 [salt.state       :300 ][INFO    ][17380] File /etc/iptables/rules.v6 is not present
2019-03-14 15:32:30,191 [salt.state       :1951][INFO    ][17380] Completed state [/etc/iptables/rules.v6] at time 15:32:30.191367 duration_in_ms=1.381
2019-03-14 15:32:30,192 [salt.state       :1780][INFO    ][17380] Running state [iptables_extra.flush_all] at time 15:32:30.192418
2019-03-14 15:32:30,192 [salt.state       :1813][INFO    ][17380] Executing state module.wait for [iptables_extra.flush_all]
2019-03-14 15:32:30,193 [salt.state       :300 ][INFO    ][17380] No changes made for iptables_extra.flush_all
2019-03-14 15:32:30,193 [salt.state       :1951][INFO    ][17380] Completed state [iptables_extra.flush_all] at time 15:32:30.193561 duration_in_ms=1.143
2019-03-14 15:32:30,199 [salt.minion      :1711][INFO    ][17380] Returning information for job: 20190314153220038445
2019-03-14 15:32:30,809 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command state.apply with jid 20190314153230794070
2019-03-14 15:32:30,824 [salt.minion      :1432][INFO    ][17478] Starting a new job with PID 17478
2019-03-14 15:32:31,547 [salt.state       :915 ][INFO    ][17478] Loading fresh modules for state activity
2019-03-14 15:32:32,421 [salt.state       :1780][INFO    ][17478] Running state [maas-rack-controller] at time 15:32:32.421538
2019-03-14 15:32:32,421 [salt.state       :1813][INFO    ][17478] Executing state pkg.installed for [maas-rack-controller]
2019-03-14 15:32:32,422 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17478] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-14 15:32:32,534 [salt.state       :300 ][INFO    ][17478] All specified packages are already installed
2019-03-14 15:32:32,534 [salt.state       :1951][INFO    ][17478] Completed state [maas-rack-controller] at time 15:32:32.534709 duration_in_ms=113.171
2019-03-14 15:32:32,536 [salt.state       :1780][INFO    ][17478] Running state [ipmitool] at time 15:32:32.536236
2019-03-14 15:32:32,536 [salt.state       :1813][INFO    ][17478] Executing state pkg.installed for [ipmitool]
2019-03-14 15:32:32,545 [salt.state       :300 ][INFO    ][17478] All specified packages are already installed
2019-03-14 15:32:32,545 [salt.state       :1951][INFO    ][17478] Completed state [ipmitool] at time 15:32:32.545584 duration_in_ms=9.349
2019-03-14 15:32:32,550 [salt.state       :1780][INFO    ][17478] Running state [/etc/maas/rackd.conf] at time 15:32:32.550404
2019-03-14 15:32:32,550 [salt.state       :1813][INFO    ][17478] Executing state file.line for [/etc/maas/rackd.conf]
2019-03-14 15:32:32,552 [salt.state       :300 ][INFO    ][17478] No changes needed to be made
2019-03-14 15:32:32,552 [salt.state       :1951][INFO    ][17478] Completed state [/etc/maas/rackd.conf] at time 15:32:32.552349 duration_in_ms=1.945
2019-03-14 15:32:32,552 [salt.state       :1780][INFO    ][17478] Running state [/etc/maas/rackd.conf] at time 15:32:32.552593
2019-03-14 15:32:32,552 [salt.state       :1813][INFO    ][17478] Executing state file.managed for [/etc/maas/rackd.conf]
2019-03-14 15:32:32,553 [salt.loaded.int.states.file:2298][WARNING ][17478] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-03-14 15:32:32,553 [salt.state       :300 ][INFO    ][17478] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-03-14 15:32:32,553 [salt.state       :1951][INFO    ][17478] Completed state [/etc/maas/rackd.conf] at time 15:32:32.553917 duration_in_ms=1.324
2019-03-14 15:32:32,555 [salt.state       :1780][INFO    ][17478] Running state [maas-rackd] at time 15:32:32.555249
2019-03-14 15:32:32,555 [salt.state       :1813][INFO    ][17478] Executing state service.running for [maas-rackd]
2019-03-14 15:32:32,556 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17478] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-03-14 15:32:32,585 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17478] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-03-14 15:32:32,602 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17478] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-03-14 15:32:32,617 [salt.state       :300 ][INFO    ][17478] The service maas-rackd is already running
2019-03-14 15:32:32,617 [salt.state       :1951][INFO    ][17478] Completed state [maas-rackd] at time 15:32:32.617847 duration_in_ms=62.597
2019-03-14 15:32:32,622 [salt.minion      :1711][INFO    ][17478] Returning information for job: 20190314153230794070
2019-03-14 15:32:33,168 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command state.apply with jid 20190314153233156210
2019-03-14 15:32:33,188 [salt.minion      :1432][INFO    ][17508] Starting a new job with PID 17508
2019-03-14 15:32:33,878 [salt.state       :915 ][INFO    ][17508] Loading fresh modules for state activity
2019-03-14 15:32:34,886 [salt.state       :1780][INFO    ][17508] Running state [maas-region-controller] at time 15:32:34.886589
2019-03-14 15:32:34,888 [salt.state       :1813][INFO    ][17508] Executing state pkg.installed for [maas-region-controller]
2019-03-14 15:32:34,889 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17508] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-14 15:32:34,993 [salt.state       :300 ][INFO    ][17508] All specified packages are already installed
2019-03-14 15:32:34,994 [salt.state       :1951][INFO    ][17508] Completed state [maas-region-controller] at time 15:32:34.994123 duration_in_ms=107.535
2019-03-14 15:32:34,994 [salt.state       :1780][INFO    ][17508] Running state [python-oauth] at time 15:32:34.994443
2019-03-14 15:32:34,994 [salt.state       :1813][INFO    ][17508] Executing state pkg.installed for [python-oauth]
2019-03-14 15:32:35,004 [salt.state       :300 ][INFO    ][17508] All specified packages are already installed
2019-03-14 15:32:35,004 [salt.state       :1951][INFO    ][17508] Completed state [python-oauth] at time 15:32:35.004491 duration_in_ms=10.048
2019-03-14 15:32:35,008 [salt.state       :1780][INFO    ][17508] Running state [/etc/maas/regiond.conf] at time 15:32:35.008491
2019-03-14 15:32:35,008 [salt.state       :1813][INFO    ][17508] Executing state file.replace for [/etc/maas/regiond.conf]
2019-03-14 15:32:35,013 [salt.state       :300 ][INFO    ][17508] No changes needed to be made
2019-03-14 15:32:35,013 [salt.state       :1951][INFO    ][17508] Completed state [/etc/maas/regiond.conf] at time 15:32:35.013386 duration_in_ms=4.895
2019-03-14 15:32:35,013 [salt.state       :1780][INFO    ][17508] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 15:32:35.013855
2019-03-14 15:32:35,014 [salt.state       :1813][INFO    ][17508] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-03-14 15:32:35,071 [salt.state       :300 ][INFO    ][17508] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-03-14 15:32:35,071 [salt.state       :1951][INFO    ][17508] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 15:32:35.071242 duration_in_ms=57.387
2019-03-14 15:32:35,071 [salt.state       :1780][INFO    ][17508] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 15:32:35.071728
2019-03-14 15:32:35,072 [salt.state       :1813][INFO    ][17508] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-14 15:32:35,075 [salt.state       :300 ][INFO    ][17508] No changes needed to be made
2019-03-14 15:32:35,076 [salt.state       :1951][INFO    ][17508] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 15:32:35.075969 duration_in_ms=4.241
2019-03-14 15:32:35,076 [salt.state       :1780][INFO    ][17508] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 15:32:35.076443
2019-03-14 15:32:35,076 [salt.state       :1813][INFO    ][17508] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-14 15:32:35,080 [salt.state       :300 ][INFO    ][17508] No changes needed to be made
2019-03-14 15:32:35,081 [salt.state       :1951][INFO    ][17508] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 15:32:35.081098 duration_in_ms=4.655
2019-03-14 15:32:35,081 [salt.state       :1780][INFO    ][17508] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 15:32:35.081572
2019-03-14 15:32:35,081 [salt.state       :1813][INFO    ][17508] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-03-14 15:32:35,103 [salt.state       :300 ][INFO    ][17508] No changes needed to be made
2019-03-14 15:32:35,104 [salt.state       :1951][INFO    ][17508] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 15:32:35.104160 duration_in_ms=22.588
2019-03-14 15:32:35,104 [salt.state       :1780][INFO    ][17508] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 15:32:35.104630
2019-03-14 15:32:35,104 [salt.state       :1813][INFO    ][17508] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-03-14 15:32:35,117 [salt.state       :300 ][INFO    ][17508] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-03-14 15:32:35,118 [salt.state       :1951][INFO    ][17508] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 15:32:35.118111 duration_in_ms=13.48
2019-03-14 15:32:35,120 [salt.state       :1780][INFO    ][17508] Running state [a2enmod headers] at time 15:32:35.120035
2019-03-14 15:32:35,120 [salt.state       :1813][INFO    ][17508] Executing state cmd.run for [a2enmod headers]
2019-03-14 15:32:35,121 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17508] Executing command 'a2enmod headers' in directory '/root'
2019-03-14 15:32:35,202 [salt.state       :300 ][INFO    ][17508] {'pid': 17527, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-03-14 15:32:35,203 [salt.state       :1951][INFO    ][17508] Completed state [a2enmod headers] at time 15:32:35.203069 duration_in_ms=83.034
2019-03-14 15:32:35,203 [salt.state       :1780][INFO    ][17508] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 15:32:35.203558
2019-03-14 15:32:35,204 [salt.state       :1813][INFO    ][17508] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-03-14 15:32:35,220 [salt.state       :300 ][INFO    ][17508] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-03-14 15:32:35,220 [salt.state       :1951][INFO    ][17508] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 15:32:35.220503 duration_in_ms=16.945
2019-03-14 15:32:35,221 [salt.state       :1780][INFO    ][17508] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 15:32:35.221078
2019-03-14 15:32:35,221 [salt.state       :1813][INFO    ][17508] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-03-14 15:32:35,270 [salt.state       :300 ][INFO    ][17508] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-03-14 15:32:35,272 [salt.state       :1951][INFO    ][17508] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 15:32:35.272149 duration_in_ms=51.071
2019-03-14 15:32:35,272 [salt.state       :1780][INFO    ][17508] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 15:32:35.272622
2019-03-14 15:32:35,272 [salt.state       :1813][INFO    ][17508] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-03-14 15:32:35,330 [salt.state       :300 ][INFO    ][17508] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-03-14 15:32:35,330 [salt.state       :1951][INFO    ][17508] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 15:32:35.330615 duration_in_ms=57.993
2019-03-14 15:32:35,331 [salt.state       :1780][INFO    ][17508] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 15:32:35.331703
2019-03-14 15:32:35,331 [salt.state       :1813][INFO    ][17508] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-03-14 15:32:35,408 [salt.state       :300 ][INFO    ][17508] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-03-14 15:32:35,408 [salt.state       :1951][INFO    ][17508] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 15:32:35.408668 duration_in_ms=76.964
2019-03-14 15:32:35,408 [salt.state       :1780][INFO    ][17508] Running state [/root/.pgpass] at time 15:32:35.408926
2019-03-14 15:32:35,409 [salt.state       :1813][INFO    ][17508] Executing state file.managed for [/root/.pgpass]
2019-03-14 15:32:35,452 [salt.state       :300 ][INFO    ][17508] File /root/.pgpass is in the correct state
2019-03-14 15:32:35,452 [salt.state       :1951][INFO    ][17508] Completed state [/root/.pgpass] at time 15:32:35.452219 duration_in_ms=43.293
2019-03-14 15:32:35,456 [salt.state       :1780][INFO    ][17508] Running state [maas-region syncdb --noinput] at time 15:32:35.456918
2019-03-14 15:32:35,457 [salt.state       :1813][INFO    ][17508] Executing state cmd.run for [maas-region syncdb --noinput]
2019-03-14 15:32:35,457 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17508] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-03-14 15:32:38,115 [salt.state       :300 ][INFO    ][17508] {'pid': 17540, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: piston3, auth, metadataserver, sessions, sites, maasserver, contenttypes\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-03-14 15:32:38,116 [salt.state       :1951][INFO    ][17508] Completed state [maas-region syncdb --noinput] at time 15:32:38.116315 duration_in_ms=2659.396
2019-03-14 15:32:38,116 [salt.state       :2022][WARNING ][17508] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-14 15:32:38,118 [salt.state       :1780][INFO    ][17508] Running state [maas-regiond] at time 15:32:38.118315
2019-03-14 15:32:38,118 [salt.state       :1813][INFO    ][17508] Executing state service.running for [maas-regiond]
2019-03-14 15:32:38,119 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17508] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-03-14 15:32:38,157 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17508] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-03-14 15:32:38,176 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17508] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-03-14 15:32:38,194 [salt.state       :300 ][INFO    ][17508] The service maas-regiond is already running
2019-03-14 15:32:38,195 [salt.state       :1951][INFO    ][17508] Completed state [maas-regiond] at time 15:32:38.195364 duration_in_ms=77.049
2019-03-14 15:32:38,197 [salt.state       :1780][INFO    ][17508] Running state [bind9] at time 15:32:38.197752
2019-03-14 15:32:38,198 [salt.state       :1813][INFO    ][17508] Executing state service.running for [bind9]
2019-03-14 15:32:38,200 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17508] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-03-14 15:32:38,217 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17508] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-03-14 15:32:38,231 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17508] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-03-14 15:32:38,241 [salt.state       :300 ][INFO    ][17508] The service bind9 is already running
2019-03-14 15:32:38,242 [salt.state       :1951][INFO    ][17508] Completed state [bind9] at time 15:32:38.242190 duration_in_ms=44.438
2019-03-14 15:32:38,243 [salt.state       :1780][INFO    ][17508] Running state [apache2] at time 15:32:38.243835
2019-03-14 15:32:38,244 [salt.state       :1813][INFO    ][17508] Executing state service.running for [apache2]
2019-03-14 15:32:38,244 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17508] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-03-14 15:32:38,256 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17508] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-03-14 15:32:38,269 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17508] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-03-14 15:32:38,291 [salt.state       :300 ][INFO    ][17508] The service apache2 is already running
2019-03-14 15:32:38,292 [salt.state       :1951][INFO    ][17508] Completed state [apache2] at time 15:32:38.292375 duration_in_ms=48.54
2019-03-14 15:32:38,294 [salt.state       :1780][INFO    ][17508] Running state [maasng.wait_for_http_code] at time 15:32:38.294097
2019-03-14 15:32:38,294 [salt.state       :1813][INFO    ][17508] Executing state module.run for [maasng.wait_for_http_code]
2019-03-14 15:32:38,297 [salt.utils.decorators:613 ][WARNING ][17508] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-14 15:32:38,409 [salt.state       :300 ][INFO    ][17508] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-03-14 15:32:38,410 [salt.state       :1951][INFO    ][17508] Completed state [maasng.wait_for_http_code] at time 15:32:38.410244 duration_in_ms=116.146
2019-03-14 15:32:38,412 [salt.state       :1780][INFO    ][17508] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 15:32:38.412343
2019-03-14 15:32:38,412 [salt.state       :1813][INFO    ][17508] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-03-14 15:32:38,413 [salt.state       :300 ][INFO    ][17508] /var/lib/maas/.setup_admin exists
2019-03-14 15:32:38,413 [salt.state       :1951][INFO    ][17508] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 15:32:38.413886 duration_in_ms=1.543
2019-03-14 15:32:38,416 [salt.state       :1780][INFO    ][17508] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:32:38.416630
2019-03-14 15:32:38,416 [salt.state       :1813][INFO    ][17508] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-14 15:32:38,417 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17508] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-14 15:32:40,184 [salt.state       :300 ][INFO    ][17508] {'pid': 17573, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-14 15:32:40,185 [salt.state       :1951][INFO    ][17508] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:32:40.185425 duration_in_ms=1768.793
2019-03-14 15:32:40,194 [salt.state       :1780][INFO    ][17508] Running state [maas_region_boot_source_resources_mirror] at time 15:32:40.194460
2019-03-14 15:32:40,194 [salt.state       :1813][INFO    ][17508] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-03-14 15:32:40,281 [salt.state       :300 ][INFO    ][17508] {'changes': {}}
2019-03-14 15:32:40,282 [salt.state       :1951][INFO    ][17508] Completed state [maas_region_boot_source_resources_mirror] at time 15:32:40.282334 duration_in_ms=87.873
2019-03-14 15:32:40,283 [salt.state       :1780][INFO    ][17508] Running state [maasng.boot_resources_import] at time 15:32:40.283676
2019-03-14 15:32:40,284 [salt.state       :1813][INFO    ][17508] Executing state module.run for [maasng.boot_resources_import]
2019-03-14 15:32:40,284 [salt.utils.decorators:613 ][WARNING ][17508] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-14 15:32:40,380 [salt.loaded.ext.module.maasng:1600][INFO    ][17508] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-03-14 15:32:45,429 [salt.loaded.ext.module.maasng:1600][INFO    ][17508] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-03-14 15:32:48,250 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314153248243564
2019-03-14 15:32:48,270 [salt.minion      :1432][INFO    ][17736] Starting a new job with PID 17736
2019-03-14 15:32:48,293 [salt.minion      :1711][INFO    ][17736] Returning information for job: 20190314153248243564
2019-03-14 15:32:50,504 [salt.state       :300 ][INFO    ][17508] {'ret': True}
2019-03-14 15:32:50,504 [salt.state       :1951][INFO    ][17508] Completed state [maasng.boot_resources_import] at time 15:32:50.504807 duration_in_ms=10221.13
2019-03-14 15:32:50,505 [salt.state       :1780][INFO    ][17508] Running state [maas_region_boot_sources_selection_xenial] at time 15:32:50.505533
2019-03-14 15:32:50,505 [salt.state       :1813][INFO    ][17508] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-03-14 15:32:50,635 [salt.state       :300 ][INFO    ][17508] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-03-14 15:32:50,635 [salt.state       :1951][INFO    ][17508] Completed state [maas_region_boot_sources_selection_xenial] at time 15:32:50.635462 duration_in_ms=129.929
2019-03-14 15:32:50,636 [salt.state       :1780][INFO    ][17508] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 15:32:50.636326
2019-03-14 15:32:50,636 [salt.state       :1813][INFO    ][17508] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-03-14 15:32:50,636 [salt.utils.decorators:613 ][WARNING ][17508] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-14 15:32:50,637 [salt.loaded.ext.module.maasng:1771][INFO    ][17508] boot-sources sync initiated for ALL Rack's
2019-03-14 15:32:51,577 [salt.state       :300 ][INFO    ][17508] {'ret': True}
2019-03-14 15:32:51,577 [salt.state       :1951][INFO    ][17508] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 15:32:51.577378 duration_in_ms=941.051
2019-03-14 15:32:51,578 [salt.state       :1780][INFO    ][17508] Running state [maas.process_maas_config] at time 15:32:51.578646
2019-03-14 15:32:51,580 [salt.state       :1813][INFO    ][17508] Executing state module.run for [maas.process_maas_config]
2019-03-14 15:32:51,580 [salt.utils.decorators:613 ][WARNING ][17508] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-14 15:32:51,581 [salt.loaded.ext.module.maas:92  ][INFO    ][17508] maasconfig name=enable_http_proxy value=True
2019-03-14 15:32:51,621 [salt.loaded.ext.module.maas:92  ][INFO    ][17508] maasconfig name=upstream_dns value=8.8.8.8
2019-03-14 15:32:51,669 [salt.loaded.ext.module.maas:92  ][INFO    ][17508] maasconfig name=commissioning_distro_series value=xenial
2019-03-14 15:32:51,728 [salt.loaded.ext.module.maas:92  ][INFO    ][17508] maasconfig name=default_osystem value=ubuntu
2019-03-14 15:32:52,969 [salt.loaded.ext.module.maas:92  ][INFO    ][17508] maasconfig name=active_discovery_interval value=600
2019-03-14 15:32:53,012 [salt.loaded.ext.module.maas:92  ][INFO    ][17508] maasconfig name=dnssec_validation value=no
2019-03-14 15:32:53,069 [salt.loaded.ext.module.maas:92  ][INFO    ][17508] maasconfig name=maas_name value=mas01
2019-03-14 15:32:53,110 [salt.loaded.ext.module.maas:92  ][INFO    ][17508] maasconfig name=network_discovery value=enabled
2019-03-14 15:32:53,241 [salt.loaded.ext.module.maas:92  ][INFO    ][17508] maasconfig name=enable_third_party_drivers value=True
2019-03-14 15:32:53,291 [salt.loaded.ext.module.maas:92  ][INFO    ][17508] maasconfig name=default_storage_layout value=lvm
2019-03-14 15:32:53,347 [salt.loaded.ext.module.maas:92  ][INFO    ][17508] maasconfig name=ntp_external_only value=True
2019-03-14 15:32:53,388 [salt.loaded.ext.module.maas:92  ][INFO    ][17508] maasconfig name=disk_erase_with_secure_erase value=False
2019-03-14 15:32:53,428 [salt.loaded.ext.module.maas:92  ][INFO    ][17508] maasconfig name=default_distro_series value=xenial
2019-03-14 15:32:53,478 [salt.loaded.ext.module.maas:92  ][INFO    ][17508] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-03-14 15:32:53,592 [salt.state       :300 ][INFO    ][17508] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-03-14 15:32:53,593 [salt.state       :1951][INFO    ][17508] Completed state [maas.process_maas_config] at time 15:32:53.592947 duration_in_ms=2014.301
2019-03-14 15:32:53,593 [salt.state       :1780][INFO    ][17508] Running state [pxe_admin] at time 15:32:53.593624
2019-03-14 15:32:53,594 [salt.state       :1813][INFO    ][17508] Executing state maasng.fabric_present for [pxe_admin]
2019-03-14 15:32:53,645 [salt.loaded.ext.module.maasng:945 ][INFO    ][17508] [{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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'dawg7m', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-03-14 15:32:53,698 [salt.loaded.ext.module.maasng:1008][WARNING ][17508] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-03-14 15:32:53,699 [salt.loaded.ext.module.maasng:1011][WARNING ][17508] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-03-14 15:32:53,758 [salt.state       :300 ][INFO    ][17508] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-03-14 15:32:53,759 [salt.state       :1951][INFO    ][17508] Completed state [pxe_admin] at time 15:32:53.758961 duration_in_ms=165.337
2019-03-14 15:32:53,759 [salt.state       :1780][INFO    ][17508] Running state [vlan 0] at time 15:32:53.759264
2019-03-14 15:32:53,759 [salt.state       :1813][INFO    ][17508] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-14 15:32:53,809 [salt.loaded.ext.module.maasng:945 ][INFO    ][17508] [{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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'dawg7m', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-03-14 15:32:53,932 [salt.loaded.ext.module.maasng:945 ][INFO    ][17508] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dawg7m', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-03-14 15:32:54,165 [salt.loaded.ext.module.maasng:945 ][INFO    ][17508] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dawg7m', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-03-14 15:32:54,257 [salt.state       :300 ][INFO    ][17508] {'new': 'Vlan untagged was updated'}
2019-03-14 15:32:54,257 [salt.state       :1951][INFO    ][17508] Completed state [vlan 0] at time 15:32:54.257402 duration_in_ms=498.137
2019-03-14 15:32:54,258 [salt.state       :1780][INFO    ][17508] Running state [192.168.11.0/24] at time 15:32:54.258650
2019-03-14 15:32:54,259 [salt.state       :1813][INFO    ][17508] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-03-14 15:32:54,431 [salt.loaded.ext.module.maasng:945 ][INFO    ][17508] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dawg7m', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-03-14 15:32:54,431 [salt.loaded.ext.module.maasng:1235][WARNING ][17508] Ignoring parameter vlan:0
2019-03-14 15:32:54,498 [salt.state       :300 ][INFO    ][17508] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-03-14 15:32:54,498 [salt.state       :1951][INFO    ][17508] Completed state [192.168.11.0/24] at time 15:32:54.498498 duration_in_ms=239.847
2019-03-14 15:32:54,499 [salt.state       :1780][INFO    ][17508] Running state [maas_create_iprange_1] at time 15:32:54.499633
2019-03-14 15:32:54,499 [salt.state       :1813][INFO    ][17508] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-03-14 15:32:54,541 [salt.state       :300 ][INFO    ][17508] Iprange maas_create_iprange_1 already exist.
2019-03-14 15:32:54,542 [salt.state       :1951][INFO    ][17508] Completed state [maas_create_iprange_1] at time 15:32:54.541986 duration_in_ms=42.351
2019-03-14 15:32:54,542 [salt.state       :1780][INFO    ][17508] Running state [vlan 0] at time 15:32:54.542254
2019-03-14 15:32:54,542 [salt.state       :1813][INFO    ][17508] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-14 15:32:54,588 [salt.loaded.ext.module.maasng:945 ][INFO    ][17508] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dawg7m', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-03-14 15:32:54,671 [salt.loaded.ext.module.maasng:945 ][INFO    ][17508] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dawg7m', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-03-14 15:32:54,878 [salt.loaded.ext.module.maasng:945 ][INFO    ][17508] [{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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'dawg7m', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-03-14 15:32:54,972 [salt.state       :300 ][INFO    ][17508] {'new': 'Vlan untagged was updated'}
2019-03-14 15:32:54,972 [salt.state       :1951][INFO    ][17508] Completed state [vlan 0] at time 15:32:54.972299 duration_in_ms=430.044
2019-03-14 15:32:54,973 [salt.state       :1780][INFO    ][17508] Running state [opnfv] at time 15:32:54.972985
2019-03-14 15:32:54,973 [salt.state       :1813][INFO    ][17508] Executing state maasng.sshkey_present for [opnfv]
2019-03-14 15:32:55,018 [salt.loaded.ext.module.maasng:1903][INFO    ][17508] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H', u'keysource': u''}]
2019-03-14 15:32:55,018 [salt.state       :300 ][INFO    ][17508] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H already exist for user opnfv.
2019-03-14 15:32:55,018 [salt.state       :1951][INFO    ][17508] Completed state [opnfv] at time 15:32:55.018787 duration_in_ms=45.802
2019-03-14 15:32:55,021 [salt.minion      :1711][INFO    ][17508] Returning information for job: 20190314153233156210
2019-03-14 15:32:55,630 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command state.apply with jid 20190314153255620494
2019-03-14 15:32:55,646 [salt.minion      :1432][INFO    ][17961] Starting a new job with PID 17961
2019-03-14 15:33:01,333 [salt.state       :915 ][INFO    ][17961] Loading fresh modules for state activity
2019-03-14 15:33:01,416 [salt.state       :1780][INFO    ][17961] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:33:01.416221
2019-03-14 15:33:01,416 [salt.state       :1813][INFO    ][17961] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-14 15:33:01,418 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17961] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-14 15:33:03,215 [salt.state       :300 ][INFO    ][17961] {'pid': 18002, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-14 15:33:03,215 [salt.state       :1951][INFO    ][17961] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:33:03.215786 duration_in_ms=1799.566
2019-03-14 15:33:03,217 [salt.state       :1780][INFO    ][17961] Running state [maas.process_machines] at time 15:33:03.217198
2019-03-14 15:33:03,217 [salt.state       :1813][INFO    ][17961] Executing state module.run for [maas.process_machines]
2019-03-14 15:33:03,218 [salt.utils.decorators:613 ][WARNING ][17961] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-14 15:33:03,728 [salt.loaded.ext.module.maas:412 ][WARNING ][17961] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-14 15:33:03,729 [salt.loaded.ext.module.maas:92  ][INFO    ][17961] 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=ptmaad architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-14 15:33:04,855 [salt.loaded.ext.module.maas:412 ][WARNING ][17961] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-14 15:33:04,856 [salt.loaded.ext.module.maas:92  ][INFO    ][17961] 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=qxxcer architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-14 15:33:05,951 [salt.loaded.ext.module.maas:412 ][WARNING ][17961] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-14 15:33:05,952 [salt.loaded.ext.module.maas:92  ][INFO    ][17961] 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=bet7hb architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-14 15:33:07,058 [salt.loaded.ext.module.maas:412 ][WARNING ][17961] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-14 15:33:07,061 [salt.loaded.ext.module.maas:92  ][INFO    ][17961] 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=cpdqqp architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-14 15:33:08,183 [salt.loaded.ext.module.maas:412 ][WARNING ][17961] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-14 15:33:08,184 [salt.loaded.ext.module.maas:92  ][INFO    ][17961] 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=nsp8px architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-14 15:33:09,254 [salt.state       :300 ][INFO    ][17961] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-14 15:33:09,254 [salt.state       :1951][INFO    ][17961] Completed state [maas.process_machines] at time 15:33:09.254694 duration_in_ms=6037.495
2019-03-14 15:33:09,258 [salt.minion      :1711][INFO    ][17961] Returning information for job: 20190314153255620494
2019-03-14 15:33:42,174 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command state.apply with jid 20190314153342160842
2019-03-14 15:33:42,196 [salt.minion      :1432][INFO    ][18272] Starting a new job with PID 18272
2019-03-14 15:33:47,784 [salt.state       :915 ][INFO    ][18272] Loading fresh modules for state activity
2019-03-14 15:33:47,879 [salt.state       :1780][INFO    ][18272] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:33:47.879749
2019-03-14 15:33:47,880 [salt.state       :1813][INFO    ][18272] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-14 15:33:47,881 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18272] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-14 15:33:49,674 [salt.state       :300 ][INFO    ][18272] {'pid': 18283, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-14 15:33:49,675 [salt.state       :1951][INFO    ][18272] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:33:49.675429 duration_in_ms=1795.681
2019-03-14 15:33:49,677 [salt.state       :1780][INFO    ][18272] Running state [maas.wait_for_machine_status] at time 15:33:49.677139
2019-03-14 15:33:49,677 [salt.state       :1813][INFO    ][18272] Executing state module.run for [maas.wait_for_machine_status]
2019-03-14 15:33:49,677 [salt.utils.decorators:613 ][WARNING ][18272] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-14 15:33:52,441 [salt.state       :300 ][INFO    ][18272] {'ret': True}
2019-03-14 15:33:52,441 [salt.state       :1951][INFO    ][18272] Completed state [maas.wait_for_machine_status] at time 15:33:52.441839 duration_in_ms=2764.698
2019-03-14 15:33:52,446 [salt.minion      :1711][INFO    ][18272] Returning information for job: 20190314153342160842
2019-03-14 15:33:52,978 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command state.apply with jid 20190314153352968150
2019-03-14 15:33:53,000 [salt.minion      :1432][INFO    ][18299] Starting a new job with PID 18299
2019-03-14 15:33:53,692 [salt.state       :915 ][INFO    ][18299] Loading fresh modules for state activity
2019-03-14 15:33:53,829 [salt.state       :1780][INFO    ][18299] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:33:53.829717
2019-03-14 15:33:53,830 [salt.state       :1813][INFO    ][18299] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-14 15:33:53,831 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18299] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-14 15:33:55,617 [salt.state       :300 ][INFO    ][18299] {'pid': 18306, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-14 15:33:55,618 [salt.state       :1951][INFO    ][18299] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:33:55.618177 duration_in_ms=1788.459
2019-03-14 15:33:55,622 [salt.state       :1780][INFO    ][18299] Running state [maas_machines_storage_cmp002_lvm] at time 15:33:55.622133
2019-03-14 15:33:55,622 [salt.state       :1813][INFO    ][18299] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-14 15:33:56,155 [salt.state       :300 ][INFO    ][18299] Machine cmp002 is not in Ready state.
2019-03-14 15:33:56,155 [salt.state       :1951][INFO    ][18299] Completed state [maas_machines_storage_cmp002_lvm] at time 15:33:56.155876 duration_in_ms=533.743
2019-03-14 15:33:56,156 [salt.state       :1780][INFO    ][18299] Running state [maas_machines_storage_cmp001_lvm] at time 15:33:56.156342
2019-03-14 15:33:56,156 [salt.state       :1813][INFO    ][18299] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-14 15:33:56,784 [salt.state       :300 ][INFO    ][18299] Machine cmp001 is not in Ready state.
2019-03-14 15:33:56,784 [salt.state       :1951][INFO    ][18299] Completed state [maas_machines_storage_cmp001_lvm] at time 15:33:56.784718 duration_in_ms=628.374
2019-03-14 15:33:56,789 [salt.minion      :1711][INFO    ][18299] Returning information for job: 20190314153352968150
2019-03-14 15:33:57,320 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command state.apply with jid 20190314153357307747
2019-03-14 15:33:57,342 [salt.minion      :1432][INFO    ][18316] Starting a new job with PID 18316
2019-03-14 15:33:58,046 [salt.state       :915 ][INFO    ][18316] Loading fresh modules for state activity
2019-03-14 15:33:58,132 [salt.state       :1780][INFO    ][18316] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:33:58.132526
2019-03-14 15:33:58,132 [salt.state       :1813][INFO    ][18316] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-14 15:33:58,134 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18316] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-14 15:33:59,986 [salt.state       :300 ][INFO    ][18316] {'pid': 18323, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-14 15:33:59,987 [salt.state       :1951][INFO    ][18316] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:33:59.986994 duration_in_ms=1854.469
2019-03-14 15:33:59,988 [salt.state       :1780][INFO    ][18316] Running state [maas.deploy_machines] at time 15:33:59.988416
2019-03-14 15:33:59,988 [salt.state       :1813][INFO    ][18316] Executing state module.run for [maas.deploy_machines]
2019-03-14 15:33:59,989 [salt.utils.decorators:613 ][WARNING ][18316] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-14 15:34:00,562 [salt.loaded.ext.module.maas:684 ][INFO    ][18316] deploymachines hwe_kernel=ga-16.04 system_id=nsp8px distro_series=xenial
2019-03-14 15:34:02,817 [salt.state       :300 ][INFO    ][18316] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-03-14 15:34:02,817 [salt.state       :1951][INFO    ][18316] Completed state [maas.deploy_machines] at time 15:34:02.817724 duration_in_ms=2829.307
2019-03-14 15:34:02,820 [salt.minion      :1711][INFO    ][18316] Returning information for job: 20190314153357307747
2019-03-14 15:34:03,366 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command state.apply with jid 20190314153403356436
2019-03-14 15:34:03,384 [salt.minion      :1432][INFO    ][18404] Starting a new job with PID 18404
2019-03-14 15:34:04,084 [salt.state       :915 ][INFO    ][18404] Loading fresh modules for state activity
2019-03-14 15:34:04,176 [salt.state       :1780][INFO    ][18404] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:34:04.176255
2019-03-14 15:34:04,176 [salt.state       :1813][INFO    ][18404] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-14 15:34:04,178 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18404] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-14 15:34:05,853 [salt.state       :300 ][INFO    ][18404] {'pid': 18411, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-14 15:34:05,854 [salt.state       :1951][INFO    ][18404] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 15:34:05.854425 duration_in_ms=1678.17
2019-03-14 15:34:05,857 [salt.state       :1780][INFO    ][18404] Running state [maas.wait_for_machine_status] at time 15:34:05.857033
2019-03-14 15:34:05,857 [salt.state       :1813][INFO    ][18404] Executing state module.run for [maas.wait_for_machine_status]
2019-03-14 15:34:05,857 [salt.utils.decorators:613 ][WARNING ][18404] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-14 15:34:08,510 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.35668206s left)
2019-03-14 15:34:18,428 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314153418417363
2019-03-14 15:34:18,450 [salt.minion      :1432][INFO    ][18447] Starting a new job with PID 18447
2019-03-14 15:34:18,473 [salt.minion      :1711][INFO    ][18447] Returning information for job: 20190314153418417363
2019-03-14 15:34:40,954 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.91312408s left)
2019-03-14 15:34:48,510 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314153448454394
2019-03-14 15:34:48,536 [salt.minion      :1432][INFO    ][18520] Starting a new job with PID 18520
2019-03-14 15:34:48,560 [salt.minion      :1711][INFO    ][18520] Returning information for job: 20190314153448454394
2019-03-14 15:35:13,636 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2182.230896s left)
2019-03-14 15:35:18,512 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314153518496783
2019-03-14 15:35:18,534 [salt.minion      :1432][INFO    ][18565] Starting a new job with PID 18565
2019-03-14 15:35:18,556 [salt.minion      :1711][INFO    ][18565] Returning information for job: 20190314153518496783
2019-03-14 15:35:46,170 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.69718194s left)
2019-03-14 15:35:48,568 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314153548555779
2019-03-14 15:35:48,586 [salt.minion      :1432][INFO    ][18624] Starting a new job with PID 18624
2019-03-14 15:35:48,608 [salt.minion      :1711][INFO    ][18624] Returning information for job: 20190314153548555779
2019-03-14 15:36:18,630 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314153618621907
2019-03-14 15:36:18,647 [salt.minion      :1432][INFO    ][18673] Starting a new job with PID 18673
2019-03-14 15:36:18,667 [salt.minion      :1711][INFO    ][18673] Returning information for job: 20190314153618621907
2019-03-14 15:36:18,916 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.95083213s left)
2019-03-14 15:36:48,686 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314153648678244
2019-03-14 15:36:48,703 [salt.minion      :1432][INFO    ][18725] Starting a new job with PID 18725
2019-03-14 15:36:48,722 [salt.minion      :1711][INFO    ][18725] Returning information for job: 20190314153648678244
2019-03-14 15:36:51,509 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2084.35816813s left)
2019-03-14 15:37:18,756 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314153718742418
2019-03-14 15:37:18,775 [salt.minion      :1432][INFO    ][18773] Starting a new job with PID 18773
2019-03-14 15:37:18,799 [salt.minion      :1711][INFO    ][18773] Returning information for job: 20190314153718742418
2019-03-14 15:37:24,513 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2051.35413909s left)
2019-03-14 15:37:48,845 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314153748828130
2019-03-14 15:37:48,862 [salt.minion      :1432][INFO    ][18830] Starting a new job with PID 18830
2019-03-14 15:37:48,889 [salt.minion      :1711][INFO    ][18830] Returning information for job: 20190314153748828130
2019-03-14 15:37:57,049 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2018.81816792s left)
2019-03-14 15:38:19,041 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314153819028790
2019-03-14 15:38:19,066 [salt.minion      :1432][INFO    ][18877] Starting a new job with PID 18877
2019-03-14 15:38:19,090 [salt.minion      :1711][INFO    ][18877] Returning information for job: 20190314153819028790
2019-03-14 15:38:29,820 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1986.04713798s left)
2019-03-14 15:38:49,245 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314153849237730
2019-03-14 15:38:49,268 [salt.minion      :1432][INFO    ][18971] Starting a new job with PID 18971
2019-03-14 15:38:49,291 [salt.minion      :1711][INFO    ][18971] Returning information for job: 20190314153849237730
2019-03-14 15:39:02,390 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1953.47733808s left)
2019-03-14 15:39:19,329 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314153919319832
2019-03-14 15:39:19,350 [salt.minion      :1432][INFO    ][19052] Starting a new job with PID 19052
2019-03-14 15:39:19,372 [salt.minion      :1711][INFO    ][19052] Returning information for job: 20190314153919319832
2019-03-14 15:39:35,129 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1920.73841095s left)
2019-03-14 15:39:49,349 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314153949335799
2019-03-14 15:39:49,368 [salt.minion      :1432][INFO    ][19235] Starting a new job with PID 19235
2019-03-14 15:39:49,397 [salt.minion      :1711][INFO    ][19235] Returning information for job: 20190314153949335799
2019-03-14 15:40:07,877 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1887.98984313s left)
2019-03-14 15:40:19,558 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314154019550050
2019-03-14 15:40:19,580 [salt.minion      :1432][INFO    ][19300] Starting a new job with PID 19300
2019-03-14 15:40:19,602 [salt.minion      :1711][INFO    ][19300] Returning information for job: 20190314154019550050
2019-03-14 15:40:40,750 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1855.116714s left)
2019-03-14 15:40:49,759 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314154049750542
2019-03-14 15:40:49,786 [salt.minion      :1432][INFO    ][19361] Starting a new job with PID 19361
2019-03-14 15:40:49,809 [salt.minion      :1711][INFO    ][19361] Returning information for job: 20190314154049750542
2019-03-14 15:41:13,429 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1822.43753099s left)
2019-03-14 15:41:19,980 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314154119968344
2019-03-14 15:41:20,004 [salt.minion      :1432][INFO    ][19428] Starting a new job with PID 19428
2019-03-14 15:41:20,029 [salt.minion      :1711][INFO    ][19428] Returning information for job: 20190314154119968344
2019-03-14 15:41:46,189 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1789.67771506s left)
2019-03-14 15:41:49,993 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314154149984870
2019-03-14 15:41:50,016 [salt.minion      :1432][INFO    ][19565] Starting a new job with PID 19565
2019-03-14 15:41:50,039 [salt.minion      :1711][INFO    ][19565] Returning information for job: 20190314154149984870
2019-03-14 15:42:18,833 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1757.033499s left)
2019-03-14 15:42:20,114 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314154220101873
2019-03-14 15:42:20,134 [salt.minion      :1432][INFO    ][19626] Starting a new job with PID 19626
2019-03-14 15:42:20,156 [salt.minion      :1711][INFO    ][19626] Returning information for job: 20190314154220101873
2019-03-14 15:42:50,244 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314154250236548
2019-03-14 15:42:50,258 [salt.minion      :1432][INFO    ][19681] Starting a new job with PID 19681
2019-03-14 15:42:50,280 [salt.minion      :1711][INFO    ][19681] Returning information for job: 20190314154250236548
2019-03-14 15:42:51,587 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1724.27993608s left)
2019-03-14 15:43:20,366 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314154320356591
2019-03-14 15:43:20,381 [salt.minion      :1432][INFO    ][19725] Starting a new job with PID 19725
2019-03-14 15:43:20,402 [salt.minion      :1711][INFO    ][19725] Returning information for job: 20190314154320356591
2019-03-14 15:43:24,166 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1691.70069814s left)
2019-03-14 15:43:50,381 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314154350372059
2019-03-14 15:43:50,399 [salt.minion      :1432][INFO    ][19784] Starting a new job with PID 19784
2019-03-14 15:43:50,421 [salt.minion      :1711][INFO    ][19784] Returning information for job: 20190314154350372059
2019-03-14 15:43:56,974 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1658.89269304s left)
2019-03-14 15:44:20,496 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314154420488893
2019-03-14 15:44:20,509 [salt.minion      :1432][INFO    ][19835] Starting a new job with PID 19835
2019-03-14 15:44:20,533 [salt.minion      :1711][INFO    ][19835] Returning information for job: 20190314154420488893
2019-03-14 15:44:29,389 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1626.47776008s left)
2019-03-14 15:44:50,629 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314154450616080
2019-03-14 15:44:50,650 [salt.minion      :1432][INFO    ][19907] Starting a new job with PID 19907
2019-03-14 15:44:50,673 [salt.minion      :1711][INFO    ][19907] Returning information for job: 20190314154450616080
2019-03-14 15:45:02,442 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1593.42470813s left)
2019-03-14 15:45:20,673 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314154520661055
2019-03-14 15:45:20,695 [salt.minion      :1432][INFO    ][19951] Starting a new job with PID 19951
2019-03-14 15:45:20,722 [salt.minion      :1711][INFO    ][19951] Returning information for job: 20190314154520661055
2019-03-14 15:45:35,039 [salt.loaded.ext.module.maas:1023][INFO    ][18404] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1560.82786202s left)
2019-03-14 15:45:50,724 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command saltutil.find_job with jid 20190314154550715447
2019-03-14 15:45:50,744 [salt.minion      :1432][INFO    ][20039] Starting a new job with PID 20039
2019-03-14 15:45:50,767 [salt.minion      :1711][INFO    ][20039] Returning information for job: 20190314154550715447
2019-03-14 15:46:08,077 [salt.state       :300 ][INFO    ][18404] {'ret': True}
2019-03-14 15:46:08,077 [salt.state       :1951][INFO    ][18404] Completed state [maas.wait_for_machine_status] at time 15:46:08.077718 duration_in_ms=722220.683
2019-03-14 15:46:08,082 [salt.minion      :1711][INFO    ][18404] Returning information for job: 20190314153403356436
2019-03-14 15:47:11,357 [salt.utils.schedule:1377][INFO    ][8305] Running scheduled job: __mine_interval
2019-03-14 16:47:11,357 [salt.utils.schedule:1377][INFO    ][8305] Running scheduled job: __mine_interval
2019-03-14 16:57:35,759 [salt.minion      :1308][INFO    ][8305] User sudo_ubuntu Executing command cp.push_dir with jid 20190314165735748796
2019-03-14 16:57:35,780 [salt.minion      :1432][INFO    ][27760] Starting a new job with PID 27760
