2019-05-02 05:13:03,314 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-02 05:13:53,368 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-02 05:14:43,412 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-02 05:15:33,460 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-02 05:16:23,505 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-02 05:18:32,475 [salt.utils.decorators:613 ][WARNING ][2853] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-02 05:18:33,089 [salt.utils.decorators:613 ][WARNING ][2853] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-02 05:18:35,312 [salt.loaded.int.states.file:2298][WARNING ][2987] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-02 05:18:40,980 [salt.state       :2022][WARNING ][3101] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-02 05:18:43,552 [salt.utils.decorators:613 ][WARNING ][3101] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-02 05:18:57,571 [salt.utils.decorators:613 ][WARNING ][3101] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-02 05:19:31,513 [salt.utils.decorators:613 ][WARNING ][3101] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-02 05:19:32,531 [salt.utils.decorators:613 ][WARNING ][3101] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-02 05:19:36,364 [salt.loaded.ext.module.maasng:1008][WARNING ][3101] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-05-02 05:19:36,364 [salt.loaded.ext.module.maasng:1011][WARNING ][3101] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-05-02 05:19:37,090 [salt.loaded.ext.module.maasng:1235][WARNING ][3101] Ignoring parameter vlan:0
2019-05-02 05:19:38,201 [salt.utils.decorators:613 ][WARNING ][3101] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-02 05:19:43,901 [salt.utils.decorators:613 ][WARNING ][5999] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-02 05:19:43,979 [salt.loaded.ext.module.maas:412 ][WARNING ][5999] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-02 05:19:45,142 [salt.loaded.ext.module.maas:412 ][WARNING ][5999] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-02 05:19:46,381 [salt.loaded.ext.module.maas:412 ][WARNING ][5999] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-02 05:19:47,644 [salt.loaded.ext.module.maas:412 ][WARNING ][5999] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-02 05:19:48,836 [salt.loaded.ext.module.maas:412 ][WARNING ][5999] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-02 05:19:53,761 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6649] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-02 05:19:53,793 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6649] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-02 05:19:53,817 [salt.utils.parsers:1051][WARNING ][364] Minion received a SIGTERM. Exiting.
2019-05-02 05:19:54,849 [salt.cli.daemons :293 ][INFO    ][6702] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-05-02 05:19:54,947 [salt.cli.daemons :82  ][INFO    ][6702] Starting up the Salt Minion
2019-05-02 05:19:54,947 [salt.utils.event :1017][INFO    ][6702] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-05-02 05:19:55,850 [salt.minion      :976 ][INFO    ][6702] Creating minion process manager
2019-05-02 05:19:57,351 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6702] Executing command ['date', '+%z'] in directory '/root'
2019-05-02 05:19:57,372 [salt.utils.schedule:568 ][INFO    ][6702] Updating job settings for scheduled job: __mine_interval
2019-05-02 05:19:57,373 [salt.minion      :1108][INFO    ][6702] Added mine.update to scheduler
2019-05-02 05:19:57,377 [salt.minion      :1975][INFO    ][6702] Minion is starting as user 'root'
2019-05-02 05:19:57,389 [salt.minion      :2336][INFO    ][6702] Minion is ready to receive requests!
2019-05-02 05:20:21,162 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190502052021148623
2019-05-02 05:20:21,186 [salt.minion      :1432][INFO    ][6791] Starting a new job with PID 6791
2019-05-02 05:20:25,007 [salt.state       :915 ][INFO    ][6791] Loading fresh modules for state activity
2019-05-02 05:20:25,058 [salt.fileclient  :1219][INFO    ][6791] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-02 05:20:25,103 [salt.state       :1780][INFO    ][6791] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:20:25.103185
2019-05-02 05:20:25,103 [salt.state       :1813][INFO    ][6791] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-02 05:20:25,105 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6791] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-02 05:20:26,490 [salt.state       :300 ][INFO    ][6791] {'pid': 6799, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-02 05:20:26,491 [salt.state       :1951][INFO    ][6791] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:20:26.490977 duration_in_ms=1387.794
2019-05-02 05:20:26,492 [salt.state       :1780][INFO    ][6791] Running state [maas.wait_for_machine_status] at time 05:20:26.492394
2019-05-02 05:20:26,492 [salt.state       :1813][INFO    ][6791] Executing state module.run for [maas.wait_for_machine_status]
2019-05-02 05:20:26,492 [salt.utils.decorators:613 ][WARNING ][6791] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-02 05:20:27,366 [salt.loaded.ext.module.maas:1023][INFO    ][6791] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.13096714s left)
2019-05-02 05:20:36,293 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502052036277025
2019-05-02 05:20:36,317 [salt.minion      :1432][INFO    ][6811] Starting a new job with PID 6811
2019-05-02 05:20:36,338 [salt.minion      :1711][INFO    ][6811] Returning information for job: 20190502052036277025
2019-05-02 05:20:58,314 [salt.loaded.ext.module.maas:1023][INFO    ][6791] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.18288398s left)
2019-05-02 05:21:06,341 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502052106328355
2019-05-02 05:21:06,365 [salt.minion      :1432][INFO    ][6864] Starting a new job with PID 6864
2019-05-02 05:21:06,389 [salt.minion      :1711][INFO    ][6864] Returning information for job: 20190502052106328355
2019-05-02 05:21:29,663 [salt.loaded.ext.module.maas:1023][INFO    ][6791] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.83393097s left)
2019-05-02 05:21:36,359 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502052136347808
2019-05-02 05:21:36,380 [salt.minion      :1432][INFO    ][6999] Starting a new job with PID 6999
2019-05-02 05:21:36,403 [salt.minion      :1711][INFO    ][6999] Returning information for job: 20190502052136347808
2019-05-02 05:22:01,063 [salt.loaded.ext.module.maas:1023][INFO    ][6791] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.433496s left)
2019-05-02 05:22:06,408 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502052206396393
2019-05-02 05:22:06,432 [salt.minion      :1432][INFO    ][7202] Starting a new job with PID 7202
2019-05-02 05:22:06,456 [salt.minion      :1711][INFO    ][7202] Returning information for job: 20190502052206396393
2019-05-02 05:22:32,831 [salt.loaded.ext.module.maas:1023][INFO    ][6791] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.66553307s left)
2019-05-02 05:22:36,474 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502052236458764
2019-05-02 05:22:36,498 [salt.minion      :1432][INFO    ][7363] Starting a new job with PID 7363
2019-05-02 05:22:36,520 [salt.minion      :1711][INFO    ][7363] Returning information for job: 20190502052236458764
2019-05-02 05:23:04,737 [salt.loaded.ext.module.maas:1023][INFO    ][6791] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.75971603s left)
2019-05-02 05:23:06,535 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502052306523341
2019-05-02 05:23:06,556 [salt.minion      :1432][INFO    ][7916] Starting a new job with PID 7916
2019-05-02 05:23:06,577 [salt.minion      :1711][INFO    ][7916] Returning information for job: 20190502052306523341
2019-05-02 05:23:36,603 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502052336585169
2019-05-02 05:23:36,627 [salt.minion      :1432][INFO    ][8093] Starting a new job with PID 8093
2019-05-02 05:23:36,650 [salt.minion      :1711][INFO    ][8093] Returning information for job: 20190502052336585169
2019-05-02 05:23:37,935 [salt.state       :300 ][INFO    ][6791] {'ret': True}
2019-05-02 05:23:37,936 [salt.state       :1951][INFO    ][6791] Completed state [maas.wait_for_machine_status] at time 05:23:37.936024 duration_in_ms=191443.627
2019-05-02 05:23:37,940 [salt.minion      :1711][INFO    ][6791] Returning information for job: 20190502052021148623
2019-05-02 05:23:38,562 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190502052338552962
2019-05-02 05:23:38,586 [salt.minion      :1432][INFO    ][8119] Starting a new job with PID 8119
2019-05-02 05:23:42,275 [salt.state       :915 ][INFO    ][8119] Loading fresh modules for state activity
2019-05-02 05:23:42,327 [salt.fileclient  :1219][INFO    ][8119] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-02 05:23:42,422 [salt.state       :1780][INFO    ][8119] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:23:42.422220
2019-05-02 05:23:42,422 [salt.state       :1813][INFO    ][8119] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-02 05:23:42,424 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8119] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-02 05:23:43,954 [salt.state       :300 ][INFO    ][8119] {'pid': 8135, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-02 05:23:43,955 [salt.state       :1951][INFO    ][8119] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:23:43.955316 duration_in_ms=1533.095
2019-05-02 05:23:43,959 [salt.state       :1780][INFO    ][8119] Running state [maas_machines_storage_cmp002_lvm] at time 05:23:43.959039
2019-05-02 05:23:43,959 [salt.state       :1813][INFO    ][8119] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-02 05:23:45,217 [salt.loaded.ext.module.maasng:610 ][INFO    ][8119] n34cf6
2019-05-02 05:23:45,218 [salt.loaded.ext.module.maasng:626 ][INFO    ][8119] sda
2019-05-02 05:23:45,904 [salt.loaded.ext.module.maasng:361 ][INFO    ][8119] n34cf6
2019-05-02 05:23:46,035 [salt.loaded.ext.module.maasng:367 ][INFO    ][8119] [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'resource_uri': u'/MAAS/api/2.0/nodes/n34cf6/blockdevices/5/', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'system_id': u'n34cf6', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'a2f32814-42a6-470e-80b1-94ecbf0c6bb5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n34cf6', u'filesystem': {u'uuid': u'58a07dbf-e1ea-4fb5-b9f3-641f8f162d01', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/n34cf6/blockdevices/5/partition/5', u'type': u'partition', u'id': 5, u'device_id': 5}], u'type': u'physical', u'id': 5, u'size': 2397998940160}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'resource_uri': u'/MAAS/api/2.0/nodes/n34cf6/blockdevices/10/', u'uuid': u'867e2f96-447b-4e57-af11-f625471d7c17', u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 2397988454400, u'system_id': u'n34cf6', u'partition_table_type': None, u'filesystem': {u'uuid': u'b944f1a3-20a8-43c3-9d82-f558692818d2', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 10, u'size': 2397988454400}]
2019-05-02 05:23:46,036 [salt.loaded.ext.module.maasng:632 ][INFO    ][8119] vgroot
2019-05-02 05:23:46,037 [salt.loaded.ext.module.maasng:635 ][INFO    ][8119] lvroot
2019-05-02 05:23:46,037 [salt.loaded.ext.module.maasng:639 ][INFO    ][8119] 107374182400
2019-05-02 05:23:46,760 [salt.loaded.ext.module.maasng:645 ][INFO    ][8119] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'boot_interface': {u'children': [], u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n67nwq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 32, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n67nwq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n67nwq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'n34cf6', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/n34cf6/interfaces/4/'}, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/n34cf6/blockdevices/5/', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'n34cf6', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'partitions': [{u'size': 2397992648704, u'uuid': u'6246cc3b-ae35-421c-b322-b069d6cae42c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n34cf6', u'filesystem': {u'uuid': u'840fcac6-f30c-4473-a627-1fc891a16241', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/n34cf6/blockdevices/5/partition/6'}]}, {u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/n34cf6/blockdevices/11/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'5476c9f6-b533-49e3-922e-a15c0c0d273f', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'n34cf6', u'partition_table_type': None, u'filesystem': {u'uuid': u'0abcb97d-911e-434b-9d97-da627ed34d8e', 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'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'partitions': []}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'n34cf6', u'power_state': u'on', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"'cloudinit' running modules for final", u'ip_addresses': [u'192.168.11.42'], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'n34cf6', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/n34cf6/blockdevices/5/', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'n34cf6', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'partitions': [{u'size': 2397992648704, u'uuid': u'6246cc3b-ae35-421c-b322-b069d6cae42c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n34cf6', u'filesystem': {u'uuid': u'840fcac6-f30c-4473-a627-1fc891a16241', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/n34cf6/blockdevices/5/partition/6'}]}, u'interface_set': [{u'children': [], u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n67nwq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 32, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n67nwq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n67nwq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'n34cf6', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/n34cf6/interfaces/4/'}, {u'children': [], u'name': u'enp8s0', u'links': [{u'id': 34, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6c', u'system_id': u'n34cf6', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/n34cf6/interfaces/17/'}, {u'children': [], u'name': u'enp9s0', u'links': [{u'id': 36, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6d', u'system_id': u'n34cf6', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/n34cf6/interfaces/19/'}, {u'children': [], u'name': u'enp7s0', u'links': [{u'id': 38, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6b', u'system_id': u'n34cf6', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/n34cf6/interfaces/22/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/n34cf6/blockdevices/5/', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'n34cf6', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'partitions': [{u'size': 2397992648704, u'uuid': u'6246cc3b-ae35-421c-b322-b069d6cae42c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n34cf6', u'filesystem': {u'uuid': u'840fcac6-f30c-4473-a627-1fc891a16241', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/n34cf6/blockdevices/5/partition/6'}]}], u'netboot': True, u'osystem': u'', u'status_action': u'modules-final', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/n34cf6/blockdevices/11/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'5476c9f6-b533-49e3-922e-a15c0c0d273f', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'n34cf6', u'partition_table_type': None, u'filesystem': {u'uuid': u'0abcb97d-911e-434b-9d97-da627ed34d8e', 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'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 2, u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/n34cf6/'}
2019-05-02 05:23:46,763 [salt.state       :300 ][INFO    ][8119] {'new': {'storage_layout': 'lvm'}}
2019-05-02 05:23:46,764 [salt.state       :1951][INFO    ][8119] Completed state [maas_machines_storage_cmp002_lvm] at time 05:23:46.764376 duration_in_ms=2805.335
2019-05-02 05:23:46,765 [salt.state       :1780][INFO    ][8119] Running state [maas_machines_storage_cmp001_lvm] at time 05:23:46.765200
2019-05-02 05:23:46,765 [salt.state       :1813][INFO    ][8119] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-02 05:23:47,910 [salt.loaded.ext.module.maasng:610 ][INFO    ][8119] cdqc88
2019-05-02 05:23:47,911 [salt.loaded.ext.module.maasng:626 ][INFO    ][8119] sda
2019-05-02 05:23:48,641 [salt.loaded.ext.module.maasng:361 ][INFO    ][8119] cdqc88
2019-05-02 05:23:48,767 [salt.loaded.ext.module.maasng:367 ][INFO    ][8119] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/cdqc88/blockdevices/1/', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cdqc88', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 1, u'partitions': [{u'size': 2397992648704, u'uuid': u'388809ec-0c6d-4cec-9d40-fccdfe2acbf0', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cdqc88', u'filesystem': {u'uuid': u'78424625-8f17-443d-9ebc-d3521a6cf7d4', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/cdqc88/blockdevices/1/partition/1'}]}, {u'size': 2397988454400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cdqc88/blockdevices/6/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'5fe7c886-500a-4f23-a623-3269afa7afc3', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'cdqc88', u'partition_table_type': None, u'filesystem': {u'uuid': u'0adbdc77-37e3-4161-83db-2d2dff83a284', 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'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 6, u'partitions': []}]
2019-05-02 05:23:48,767 [salt.loaded.ext.module.maasng:632 ][INFO    ][8119] vgroot
2019-05-02 05:23:48,768 [salt.loaded.ext.module.maasng:635 ][INFO    ][8119] lvroot
2019-05-02 05:23:48,768 [salt.loaded.ext.module.maasng:639 ][INFO    ][8119] 107374182400
2019-05-02 05:23:49,485 [salt.loaded.ext.module.maasng:645 ][INFO    ][8119] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'boot_interface': {u'children': [], u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n67nwq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 15, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n67nwq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n67nwq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'cdqc88', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/cdqc88/interfaces/5/'}, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/cdqc88/blockdevices/1/', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cdqc88', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 1, u'partitions': [{u'size': 2397992648704, u'uuid': u'828560e2-4205-4658-8d13-5ba4d474da6d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cdqc88', u'filesystem': {u'uuid': u'd542b261-0221-4e56-a620-0aef20e8dccd', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/cdqc88/blockdevices/1/partition/7'}]}, {u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cdqc88/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'53414f7c-2b66-45cb-b432-187912024cf2', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'cdqc88', u'partition_table_type': None, u'filesystem': {u'uuid': u'f11c76b1-fdf0-47f2-8dc6-dcde669f90c9', 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'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'partitions': []}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'cdqc88', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.38'], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'cdqc88', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/cdqc88/blockdevices/1/', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cdqc88', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 1, u'partitions': [{u'size': 2397992648704, u'uuid': u'828560e2-4205-4658-8d13-5ba4d474da6d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cdqc88', u'filesystem': {u'uuid': u'd542b261-0221-4e56-a620-0aef20e8dccd', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/cdqc88/blockdevices/1/partition/7'}]}, u'interface_set': [{u'children': [], u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n67nwq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 15, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n67nwq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n67nwq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'cdqc88', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/cdqc88/interfaces/5/'}, {u'children': [], u'name': u'enp8s0', u'links': [{u'id': 16, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'system_id': u'cdqc88', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/cdqc88/interfaces/9/'}, {u'children': [], u'name': u'enp7s0', u'links': [{u'id': 17, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'system_id': u'cdqc88', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/cdqc88/interfaces/10/'}, {u'children': [], u'name': u'enp9s0', u'links': [{u'id': 18, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'system_id': u'cdqc88', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/cdqc88/interfaces/11/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/cdqc88/blockdevices/1/', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cdqc88', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 1, u'partitions': [{u'size': 2397992648704, u'uuid': u'828560e2-4205-4658-8d13-5ba4d474da6d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cdqc88', u'filesystem': {u'uuid': u'd542b261-0221-4e56-a620-0aef20e8dccd', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/cdqc88/blockdevices/1/partition/7'}]}], u'netboot': True, u'osystem': u'', u'status_action': u'', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cdqc88/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'53414f7c-2b66-45cb-b432-187912024cf2', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'cdqc88', u'partition_table_type': None, u'filesystem': {u'uuid': u'f11c76b1-fdf0-47f2-8dc6-dcde669f90c9', 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'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 4, u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/cdqc88/'}
2019-05-02 05:23:49,487 [salt.state       :300 ][INFO    ][8119] {'new': {'storage_layout': 'lvm'}}
2019-05-02 05:23:49,488 [salt.state       :1951][INFO    ][8119] Completed state [maas_machines_storage_cmp001_lvm] at time 05:23:49.488130 duration_in_ms=2722.93
2019-05-02 05:23:49,492 [salt.minion      :1711][INFO    ][8119] Returning information for job: 20190502052338552962
2019-05-02 05:23:50,133 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190502052350118350
2019-05-02 05:23:50,156 [salt.minion      :1432][INFO    ][8290] Starting a new job with PID 8290
2019-05-02 05:23:50,967 [salt.state       :915 ][INFO    ][8290] Loading fresh modules for state activity
2019-05-02 05:23:50,996 [salt.fileclient  :1219][INFO    ][8290] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-02 05:23:51,022 [salt.state       :1780][INFO    ][8290] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:23:51.022065
2019-05-02 05:23:51,022 [salt.state       :1813][INFO    ][8290] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-02 05:23:51,023 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8290] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-02 05:23:52,473 [salt.state       :300 ][INFO    ][8290] {'pid': 8297, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-02 05:23:52,474 [salt.state       :1951][INFO    ][8290] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:23:52.474232 duration_in_ms=1452.167
2019-05-02 05:23:52,475 [salt.state       :1780][INFO    ][8290] Running state [maas.deploy_machines] at time 05:23:52.475417
2019-05-02 05:23:52,475 [salt.state       :1813][INFO    ][8290] Executing state module.run for [maas.deploy_machines]
2019-05-02 05:23:52,476 [salt.utils.decorators:613 ][WARNING ][8290] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-02 05:23:53,157 [salt.loaded.ext.module.maas:684 ][INFO    ][8290] deploymachines hwe_kernel=hwe-16.04 system_id=n34cf6 distro_series=xenial
2019-05-02 05:23:55,786 [salt.loaded.ext.module.maas:684 ][INFO    ][8290] deploymachines hwe_kernel=hwe-16.04 system_id=cdqc88 distro_series=xenial
2019-05-02 05:23:58,483 [salt.loaded.ext.module.maas:684 ][INFO    ][8290] deploymachines hwe_kernel=hwe-16.04 system_id=dpbx3y distro_series=xenial
2019-05-02 05:24:01,183 [salt.loaded.ext.module.maas:684 ][INFO    ][8290] deploymachines hwe_kernel=hwe-16.04 system_id=4df8xx distro_series=xenial
2019-05-02 05:24:03,938 [salt.loaded.ext.module.maas:684 ][INFO    ][8290] deploymachines hwe_kernel=hwe-16.04 system_id=mwr7bw distro_series=xenial
2019-05-02 05:24:05,246 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502052405236978
2019-05-02 05:24:05,269 [salt.minion      :1432][INFO    ][8540] Starting a new job with PID 8540
2019-05-02 05:24:05,291 [salt.minion      :1711][INFO    ][8540] Returning information for job: 20190502052405236978
2019-05-02 05:24:06,725 [salt.state       :300 ][INFO    ][8290] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-02 05:24:06,725 [salt.state       :1951][INFO    ][8290] Completed state [maas.deploy_machines] at time 05:24:06.725578 duration_in_ms=14250.155
2019-05-02 05:24:06,729 [salt.minion      :1711][INFO    ][8290] Returning information for job: 20190502052350118350
2019-05-02 05:24:07,351 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190502052407338712
2019-05-02 05:24:07,374 [salt.minion      :1432][INFO    ][8574] Starting a new job with PID 8574
2019-05-02 05:24:11,104 [salt.state       :915 ][INFO    ][8574] Loading fresh modules for state activity
2019-05-02 05:24:11,154 [salt.fileclient  :1219][INFO    ][8574] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-02 05:24:11,198 [salt.state       :1780][INFO    ][8574] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:24:11.198607
2019-05-02 05:24:11,199 [salt.state       :1813][INFO    ][8574] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-02 05:24:11,201 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8574] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-02 05:24:12,676 [salt.state       :300 ][INFO    ][8574] {'pid': 8589, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-02 05:24:12,677 [salt.state       :1951][INFO    ][8574] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:24:12.677071 duration_in_ms=1478.464
2019-05-02 05:24:12,680 [salt.state       :1780][INFO    ][8574] Running state [maas.wait_for_machine_status] at time 05:24:12.680099
2019-05-02 05:24:12,680 [salt.state       :1813][INFO    ][8574] Executing state module.run for [maas.wait_for_machine_status]
2019-05-02 05:24:12,681 [salt.utils.decorators:613 ][WARNING ][8574] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-02 05:24:16,105 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.58511615s left)
2019-05-02 05:24:22,396 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502052422379750
2019-05-02 05:24:22,416 [salt.minion      :1432][INFO    ][8602] Starting a new job with PID 8602
2019-05-02 05:24:22,436 [salt.minion      :1711][INFO    ][8602] Returning information for job: 20190502052422379750
2019-05-02 05:24:49,483 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.20710897s left)
2019-05-02 05:24:52,445 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502052452432090
2019-05-02 05:24:52,470 [salt.minion      :1432][INFO    ][8656] Starting a new job with PID 8656
2019-05-02 05:24:52,493 [salt.minion      :1711][INFO    ][8656] Returning information for job: 20190502052452432090
2019-05-02 05:25:22,540 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502052522527797
2019-05-02 05:25:22,563 [salt.minion      :1432][INFO    ][8675] Starting a new job with PID 8675
2019-05-02 05:25:22,586 [salt.minion      :1711][INFO    ][8675] Returning information for job: 20190502052522527797
2019-05-02 05:25:22,738 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.95299101s left)
2019-05-02 05:25:52,579 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502052552573838
2019-05-02 05:25:52,598 [salt.minion      :1432][INFO    ][8821] Starting a new job with PID 8821
2019-05-02 05:25:52,621 [salt.minion      :1711][INFO    ][8821] Returning information for job: 20190502052552573838
2019-05-02 05:25:55,949 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.74101901s left)
2019-05-02 05:26:22,629 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502052622617306
2019-05-02 05:26:22,653 [salt.minion      :1432][INFO    ][8983] Starting a new job with PID 8983
2019-05-02 05:26:22,677 [salt.minion      :1711][INFO    ][8983] Returning information for job: 20190502052622617306
2019-05-02 05:26:29,347 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.34397411s left)
2019-05-02 05:26:52,689 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502052652674798
2019-05-02 05:26:52,713 [salt.minion      :1432][INFO    ][9743] Starting a new job with PID 9743
2019-05-02 05:26:52,737 [salt.minion      :1711][INFO    ][9743] Returning information for job: 20190502052652674798
2019-05-02 05:27:03,057 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.63350415s left)
2019-05-02 05:27:22,750 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502052722736504
2019-05-02 05:27:22,774 [salt.minion      :1432][INFO    ][9764] Starting a new job with PID 9764
2019-05-02 05:27:22,797 [salt.minion      :1711][INFO    ][9764] Returning information for job: 20190502052722736504
2019-05-02 05:27:36,128 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.56220412s left)
2019-05-02 05:27:52,808 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502052752795562
2019-05-02 05:27:52,832 [salt.minion      :1432][INFO    ][9893] Starting a new job with PID 9893
2019-05-02 05:27:52,854 [salt.minion      :1711][INFO    ][9893] Returning information for job: 20190502052752795562
2019-05-02 05:28:09,574 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.11699796s left)
2019-05-02 05:28:22,873 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502052822859600
2019-05-02 05:28:22,896 [salt.minion      :1432][INFO    ][9917] Starting a new job with PID 9917
2019-05-02 05:28:22,919 [salt.minion      :1711][INFO    ][9917] Returning information for job: 20190502052822859600
2019-05-02 05:28:42,161 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.52967715s left)
2019-05-02 05:28:52,943 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502052852929579
2019-05-02 05:28:52,966 [salt.minion      :1432][INFO    ][10553] Starting a new job with PID 10553
2019-05-02 05:28:52,987 [salt.minion      :1711][INFO    ][10553] Returning information for job: 20190502052852929579
2019-05-02 05:29:15,690 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1947.00091314s left)
2019-05-02 05:29:23,012 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502052923000001
2019-05-02 05:29:23,036 [salt.minion      :1432][INFO    ][10605] Starting a new job with PID 10605
2019-05-02 05:29:23,061 [salt.minion      :1711][INFO    ][10605] Returning information for job: 20190502052923000001
2019-05-02 05:29:48,559 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1914.13182306s left)
2019-05-02 05:29:53,087 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502052953074526
2019-05-02 05:29:53,112 [salt.minion      :1432][INFO    ][10983] Starting a new job with PID 10983
2019-05-02 05:29:53,135 [salt.minion      :1711][INFO    ][10983] Returning information for job: 20190502052953074526
2019-05-02 05:30:21,637 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1881.05333805s left)
2019-05-02 05:30:23,169 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502053023156341
2019-05-02 05:30:23,193 [salt.minion      :1432][INFO    ][11002] Starting a new job with PID 11002
2019-05-02 05:30:23,217 [salt.minion      :1711][INFO    ][11002] Returning information for job: 20190502053023156341
2019-05-02 05:30:53,256 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502053053244103
2019-05-02 05:30:53,280 [salt.minion      :1432][INFO    ][11059] Starting a new job with PID 11059
2019-05-02 05:30:53,302 [salt.minion      :1711][INFO    ][11059] Returning information for job: 20190502053053244103
2019-05-02 05:30:55,158 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1847.53215098s left)
2019-05-02 05:31:23,283 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502053123270350
2019-05-02 05:31:23,306 [salt.minion      :1432][INFO    ][11224] Starting a new job with PID 11224
2019-05-02 05:31:23,329 [salt.minion      :1711][INFO    ][11224] Returning information for job: 20190502053123270350
2019-05-02 05:31:28,511 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1814.17919898s left)
2019-05-02 05:31:53,328 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502053153317098
2019-05-02 05:31:53,349 [salt.minion      :1432][INFO    ][11727] Starting a new job with PID 11727
2019-05-02 05:31:53,370 [salt.minion      :1711][INFO    ][11727] Returning information for job: 20190502053153317098
2019-05-02 05:32:02,099 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1780.591012s left)
2019-05-02 05:32:23,425 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502053223412545
2019-05-02 05:32:23,450 [salt.minion      :1432][INFO    ][11746] Starting a new job with PID 11746
2019-05-02 05:32:23,472 [salt.minion      :1711][INFO    ][11746] Returning information for job: 20190502053223412545
2019-05-02 05:32:35,775 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1746.91546202s left)
2019-05-02 05:32:53,536 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502053253523501
2019-05-02 05:32:53,560 [salt.minion      :1432][INFO    ][11821] Starting a new job with PID 11821
2019-05-02 05:32:53,585 [salt.minion      :1711][INFO    ][11821] Returning information for job: 20190502053253523501
2019-05-02 05:33:09,382 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1713.308887s left)
2019-05-02 05:33:23,660 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502053323646426
2019-05-02 05:33:23,684 [salt.minion      :1432][INFO    ][11841] Starting a new job with PID 11841
2019-05-02 05:33:23,705 [salt.minion      :1711][INFO    ][11841] Returning information for job: 20190502053323646426
2019-05-02 05:33:42,806 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1679.88442492s left)
2019-05-02 05:33:53,789 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502053353773624
2019-05-02 05:33:53,813 [salt.minion      :1432][INFO    ][11900] Starting a new job with PID 11900
2019-05-02 05:33:53,837 [salt.minion      :1711][INFO    ][11900] Returning information for job: 20190502053353773624
2019-05-02 05:34:16,376 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1646.31491494s left)
2019-05-02 05:34:23,919 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502053423906663
2019-05-02 05:34:23,943 [salt.minion      :1432][INFO    ][11920] Starting a new job with PID 11920
2019-05-02 05:34:23,966 [salt.minion      :1711][INFO    ][11920] Returning information for job: 20190502053423906663
2019-05-02 05:34:49,855 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1612.83547401s left)
2019-05-02 05:34:54,066 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502053454050426
2019-05-02 05:34:54,090 [salt.minion      :1432][INFO    ][11978] Starting a new job with PID 11978
2019-05-02 05:34:54,115 [salt.minion      :1711][INFO    ][11978] Returning information for job: 20190502053454050426
2019-05-02 05:35:23,711 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1578.97969294s left)
2019-05-02 05:35:24,224 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502053524208280
2019-05-02 05:35:24,248 [salt.minion      :1432][INFO    ][11999] Starting a new job with PID 11999
2019-05-02 05:35:24,271 [salt.minion      :1711][INFO    ][11999] Returning information for job: 20190502053524208280
2019-05-02 05:35:54,395 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502053554374724
2019-05-02 05:35:54,417 [salt.minion      :1432][INFO    ][12055] Starting a new job with PID 12055
2019-05-02 05:35:54,441 [salt.minion      :1711][INFO    ][12055] Returning information for job: 20190502053554374724
2019-05-02 05:35:57,083 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1545.60729003s left)
2019-05-02 05:36:24,569 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502053624554070
2019-05-02 05:36:24,594 [salt.minion      :1432][INFO    ][12081] Starting a new job with PID 12081
2019-05-02 05:36:24,616 [salt.minion      :1711][INFO    ][12081] Returning information for job: 20190502053624554070
2019-05-02 05:36:30,658 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1512.03274298s left)
2019-05-02 05:36:54,752 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502053654740029
2019-05-02 05:36:54,775 [salt.minion      :1432][INFO    ][12142] Starting a new job with PID 12142
2019-05-02 05:36:54,799 [salt.minion      :1711][INFO    ][12142] Returning information for job: 20190502053654740029
2019-05-02 05:37:04,029 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1478.66145515s left)
2019-05-02 05:37:24,942 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502053724929831
2019-05-02 05:37:24,966 [salt.minion      :1432][INFO    ][12162] Starting a new job with PID 12162
2019-05-02 05:37:24,990 [salt.minion      :1711][INFO    ][12162] Returning information for job: 20190502053724929831
2019-05-02 05:37:37,453 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1445.23755002s left)
2019-05-02 05:37:55,143 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502053755131131
2019-05-02 05:37:55,168 [salt.minion      :1432][INFO    ][12218] Starting a new job with PID 12218
2019-05-02 05:37:55,191 [salt.minion      :1711][INFO    ][12218] Returning information for job: 20190502053755131131
2019-05-02 05:38:10,879 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1411.81137204s left)
2019-05-02 05:38:25,350 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502053825337359
2019-05-02 05:38:25,374 [salt.minion      :1432][INFO    ][12238] Starting a new job with PID 12238
2019-05-02 05:38:25,398 [salt.minion      :1711][INFO    ][12238] Returning information for job: 20190502053825337359
2019-05-02 05:38:44,481 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1378.20903516s left)
2019-05-02 05:38:55,573 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502053855560089
2019-05-02 05:38:55,597 [salt.minion      :1432][INFO    ][12437] Starting a new job with PID 12437
2019-05-02 05:38:55,620 [salt.minion      :1711][INFO    ][12437] Returning information for job: 20190502053855560089
2019-05-02 05:39:18,252 [salt.loaded.ext.module.maas:1023][INFO    ][8574] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1344.43863297s left)
2019-05-02 05:39:25,592 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502053925579595
2019-05-02 05:39:25,617 [salt.minion      :1432][INFO    ][12464] Starting a new job with PID 12464
2019-05-02 05:39:25,642 [salt.minion      :1711][INFO    ][12464] Returning information for job: 20190502053925579595
2019-05-02 05:39:50,376 [salt.loaded.ext.module.maas:993 ][INFO    ][8574] Machine dpbx3y mark broken
2019-05-02 05:39:51,173 [salt.loaded.ext.module.maas:996 ][INFO    ][8574] Machine dpbx3y mark fixed
2019-05-02 05:39:52,457 [salt.loaded.ext.module.maas:684 ][INFO    ][8574] deploymachines hwe_kernel=hwe-16.04 system_id=dpbx3y distro_series=xenial
2019-05-02 05:39:55,136 [salt.loaded.ext.module.maas:160 ][ERROR   ][8574] Failed for object kvm01 reason Unable to change power state to 'cycle' for node kvm01: another action is already in progress for that node.
2019-05-02 05:39:55,138 [salt.state       :302 ][ERROR   ][8574] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm03', 'kvm02'], 'errors': {'kvm01': "Unable to change power state to 'cycle' for node kvm01: another action is already in progress for that node."}, 'success': []}
2019-05-02 05:39:55,139 [salt.state       :1951][INFO    ][8574] Completed state [maas.wait_for_machine_status] at time 05:39:55.138920 duration_in_ms=942458.816
2019-05-02 05:39:55,147 [salt.minion      :1711][INFO    ][8574] Returning information for job: 20190502052407338712
2019-05-02 05:40:05,945 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command pillar.get with jid 20190502054005932518
2019-05-02 05:40:05,970 [salt.minion      :1432][INFO    ][12595] Starting a new job with PID 12595
2019-05-02 05:40:05,978 [salt.minion      :1711][INFO    ][12595] Returning information for job: 20190502054005932518
2019-05-02 05:40:06,574 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command service.status with jid 20190502054006562256
2019-05-02 05:40:06,597 [salt.minion      :1432][INFO    ][12600] Starting a new job with PID 12600
2019-05-02 05:40:07,032 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12600] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-02 05:40:07,066 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12600] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-02 05:40:07,081 [salt.minion      :1711][INFO    ][12600] Returning information for job: 20190502054006562256
2019-05-02 05:40:07,583 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190502054007571204
2019-05-02 05:40:07,603 [salt.minion      :1432][INFO    ][12619] Starting a new job with PID 12619
2019-05-02 05:40:11,021 [salt.state       :915 ][INFO    ][12619] Loading fresh modules for state activity
2019-05-02 05:40:11,433 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12619] Executing command 'salt-minion --version' in directory '/root'
2019-05-02 05:40:11,811 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12619] Executing command 'salt-minion --version' in directory '/root'
2019-05-02 05:40:12,659 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12619] Executing command 'salt-minion --version' in directory '/root'
2019-05-02 05:40:13,029 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12619] Executing command 'salt-minion --version' in directory '/root'
2019-05-02 05:40:14,477 [salt.state       :1780][INFO    ][12619] Running state [salt-minion] at time 05:40:14.477215
2019-05-02 05:40:14,477 [salt.state       :1813][INFO    ][12619] Executing state pkg.installed for [salt-minion]
2019-05-02 05:40:14,478 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12619] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-02 05:40:14,558 [salt.state       :300 ][INFO    ][12619] All specified packages are already installed
2019-05-02 05:40:14,559 [salt.state       :1951][INFO    ][12619] Completed state [salt-minion] at time 05:40:14.559262 duration_in_ms=82.046
2019-05-02 05:40:14,559 [salt.state       :1780][INFO    ][12619] Running state [salt_minion_dependency_packages] at time 05:40:14.559572
2019-05-02 05:40:14,559 [salt.state       :1813][INFO    ][12619] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-02 05:40:14,565 [salt.state       :300 ][INFO    ][12619] All specified packages are already installed
2019-05-02 05:40:14,565 [salt.state       :1951][INFO    ][12619] Completed state [salt_minion_dependency_packages] at time 05:40:14.565671 duration_in_ms=6.1
2019-05-02 05:40:14,568 [salt.state       :1780][INFO    ][12619] Running state [/etc/salt/minion.d/minion.conf] at time 05:40:14.568544
2019-05-02 05:40:14,568 [salt.state       :1813][INFO    ][12619] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-02 05:40:14,770 [salt.state       :300 ][INFO    ][12619] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-02 05:40:14,770 [salt.state       :1951][INFO    ][12619] Completed state [/etc/salt/minion.d/minion.conf] at time 05:40:14.770684 duration_in_ms=202.139
2019-05-02 05:40:14,770 [salt.state       :1780][INFO    ][12619] Running state [python-netaddr] at time 05:40:14.770944
2019-05-02 05:40:14,771 [salt.state       :1813][INFO    ][12619] Executing state pkg.installed for [python-netaddr]
2019-05-02 05:40:14,778 [salt.state       :300 ][INFO    ][12619] All specified packages are already installed
2019-05-02 05:40:14,778 [salt.state       :1951][INFO    ][12619] Completed state [python-netaddr] at time 05:40:14.778374 duration_in_ms=7.429
2019-05-02 05:40:14,781 [salt.state       :1780][INFO    ][12619] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:40:14.781576
2019-05-02 05:40:14,781 [salt.state       :1813][INFO    ][12619] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-02 05:40:14,793 [salt.state       :300 ][INFO    ][12619] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-02 05:40:14,793 [salt.state       :1951][INFO    ][12619] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:40:14.793345 duration_in_ms=11.769
2019-05-02 05:40:14,794 [salt.state       :1780][INFO    ][12619] Running state [salt-minion] at time 05:40:14.794321
2019-05-02 05:40:14,794 [salt.state       :1813][INFO    ][12619] Executing state service.running for [salt-minion]
2019-05-02 05:40:14,795 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12619] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-02 05:40:14,832 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12619] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-02 05:40:14,850 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12619] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-02 05:40:14,869 [salt.state       :300 ][INFO    ][12619] The service salt-minion is already running
2019-05-02 05:40:14,869 [salt.state       :1951][INFO    ][12619] Completed state [salt-minion] at time 05:40:14.869679 duration_in_ms=75.357
2019-05-02 05:40:14,872 [salt.state       :1780][INFO    ][12619] Running state [/etc/salt/grains.d] at time 05:40:14.872091
2019-05-02 05:40:14,872 [salt.state       :1813][INFO    ][12619] Executing state file.directory for [/etc/salt/grains.d]
2019-05-02 05:40:14,874 [salt.state       :300 ][INFO    ][12619] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-02 05:40:14,874 [salt.state       :1951][INFO    ][12619] Completed state [/etc/salt/grains.d] at time 05:40:14.874397 duration_in_ms=2.306
2019-05-02 05:40:14,875 [salt.state       :1780][INFO    ][12619] Running state [/etc/salt/grains] at time 05:40:14.875451
2019-05-02 05:40:14,875 [salt.state       :1813][INFO    ][12619] Executing state file.managed for [/etc/salt/grains]
2019-05-02 05:40:14,876 [salt.state       :300 ][INFO    ][12619] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-02 05:40:14,877 [salt.state       :1951][INFO    ][12619] Completed state [/etc/salt/grains] at time 05:40:14.877004 duration_in_ms=1.553
2019-05-02 05:40:14,877 [salt.state       :1780][INFO    ][12619] Running state [/etc/salt/grains.d/placeholder] at time 05:40:14.877689
2019-05-02 05:40:14,878 [salt.state       :1813][INFO    ][12619] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-02 05:40:14,878 [salt.state       :300 ][INFO    ][12619] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-02 05:40:14,879 [salt.state       :1951][INFO    ][12619] Completed state [/etc/salt/grains.d/placeholder] at time 05:40:14.879167 duration_in_ms=1.478
2019-05-02 05:40:14,879 [salt.state       :1780][INFO    ][12619] Running state [/etc/salt/grains.d/sphinx] at time 05:40:14.879866
2019-05-02 05:40:14,880 [salt.state       :1813][INFO    ][12619] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-02 05:40:14,896 [salt.state       :300 ][INFO    ][12619] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-02 05:40:14,896 [salt.state       :1951][INFO    ][12619] Completed state [/etc/salt/grains.d/sphinx] at time 05:40:14.896673 duration_in_ms=16.807
2019-05-02 05:40:14,900 [salt.state       :1780][INFO    ][12619] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:40:14.899930
2019-05-02 05:40:14,900 [salt.state       :1813][INFO    ][12619] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-02 05:40:14,900 [salt.state       :300 ][INFO    ][12619] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-02 05:40:14,901 [salt.state       :1951][INFO    ][12619] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:40:14.901182 duration_in_ms=1.253
2019-05-02 05:40:14,901 [salt.state       :1780][INFO    ][12619] Running state [/etc/salt/grains.d/dns_records] at time 05:40:14.901884
2019-05-02 05:40:14,902 [salt.state       :1813][INFO    ][12619] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-02 05:40:14,914 [salt.state       :300 ][INFO    ][12619] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-02 05:40:14,914 [salt.state       :1951][INFO    ][12619] Completed state [/etc/salt/grains.d/dns_records] at time 05:40:14.914547 duration_in_ms=12.663
2019-05-02 05:40:14,915 [salt.state       :1780][INFO    ][12619] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:40:14.915924
2019-05-02 05:40:14,916 [salt.state       :1813][INFO    ][12619] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-02 05:40:14,916 [salt.state       :300 ][INFO    ][12619] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-02 05:40:14,917 [salt.state       :1951][INFO    ][12619] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:40:14.917138 duration_in_ms=1.214
2019-05-02 05:40:14,917 [salt.state       :1780][INFO    ][12619] Running state [/etc/salt/grains.d/salt] at time 05:40:14.917836
2019-05-02 05:40:14,918 [salt.state       :1813][INFO    ][12619] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-02 05:40:14,932 [salt.state       :300 ][INFO    ][12619] File /etc/salt/grains.d/salt is in the correct state
2019-05-02 05:40:14,932 [salt.state       :1951][INFO    ][12619] Completed state [/etc/salt/grains.d/salt] at time 05:40:14.932541 duration_in_ms=14.705
2019-05-02 05:40:14,933 [salt.state       :1780][INFO    ][12619] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:40:14.933869
2019-05-02 05:40:14,934 [salt.state       :1813][INFO    ][12619] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-02 05:40:14,934 [salt.state       :300 ][INFO    ][12619] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-02 05:40:14,935 [salt.state       :1951][INFO    ][12619] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:40:14.935090 duration_in_ms=1.222
2019-05-02 05:40:14,938 [salt.state       :1780][INFO    ][12619] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:40:14.937962
2019-05-02 05:40:14,938 [salt.state       :1813][INFO    ][12619] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-02 05:40:14,938 [salt.state       :300 ][INFO    ][12619] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-02 05:40:14,939 [salt.state       :1951][INFO    ][12619] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:40:14.939202 duration_in_ms=1.241
2019-05-02 05:40:14,940 [salt.state       :1780][INFO    ][12619] Running state [mine.update] at time 05:40:14.940210
2019-05-02 05:40:14,940 [salt.state       :1813][INFO    ][12619] Executing state module.wait for [mine.update]
2019-05-02 05:40:14,941 [salt.state       :300 ][INFO    ][12619] No changes made for mine.update
2019-05-02 05:40:14,941 [salt.state       :1951][INFO    ][12619] Completed state [mine.update] at time 05:40:14.941355 duration_in_ms=1.145
2019-05-02 05:40:14,941 [salt.state       :1780][INFO    ][12619] Running state [ca-certificates] at time 05:40:14.941732
2019-05-02 05:40:14,942 [salt.state       :1813][INFO    ][12619] Executing state pkg.installed for [ca-certificates]
2019-05-02 05:40:14,952 [salt.state       :300 ][INFO    ][12619] All specified packages are already installed
2019-05-02 05:40:14,952 [salt.state       :1951][INFO    ][12619] Completed state [ca-certificates] at time 05:40:14.952894 duration_in_ms=11.162
2019-05-02 05:40:14,953 [salt.state       :1780][INFO    ][12619] Running state [update-ca-certificates] at time 05:40:14.953837
2019-05-02 05:40:14,954 [salt.state       :1813][INFO    ][12619] Executing state cmd.wait for [update-ca-certificates]
2019-05-02 05:40:14,954 [salt.state       :300 ][INFO    ][12619] No changes made for update-ca-certificates
2019-05-02 05:40:14,954 [salt.state       :1951][INFO    ][12619] Completed state [update-ca-certificates] at time 05:40:14.954915 duration_in_ms=1.078
2019-05-02 05:40:14,955 [salt.state       :1780][INFO    ][12619] Running state [iptables] at time 05:40:14.955229
2019-05-02 05:40:14,955 [salt.state       :1813][INFO    ][12619] Executing state pkg.installed for [iptables]
2019-05-02 05:40:14,964 [salt.state       :300 ][INFO    ][12619] All specified packages are already installed
2019-05-02 05:40:14,965 [salt.state       :1951][INFO    ][12619] Completed state [iptables] at time 05:40:14.965154 duration_in_ms=9.925
2019-05-02 05:40:14,965 [salt.state       :1780][INFO    ][12619] Running state [iptables-persistent] at time 05:40:14.965464
2019-05-02 05:40:14,965 [salt.state       :1813][INFO    ][12619] Executing state pkg.installed for [iptables-persistent]
2019-05-02 05:40:14,974 [salt.state       :300 ][INFO    ][12619] All specified packages are already installed
2019-05-02 05:40:14,975 [salt.state       :1951][INFO    ][12619] Completed state [iptables-persistent] at time 05:40:14.975014 duration_in_ms=9.549
2019-05-02 05:40:14,976 [salt.state       :1780][INFO    ][12619] Running state [iptables_modules_v4_load] at time 05:40:14.976237
2019-05-02 05:40:14,976 [salt.state       :1813][INFO    ][12619] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-02 05:40:14,977 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12619] Executing command 'lsmod' in directory '/root'
2019-05-02 05:40:15,000 [salt.state       :300 ][INFO    ][12619] Kernel modules iptable_filter, ip_tables are already present
2019-05-02 05:40:15,000 [salt.state       :1951][INFO    ][12619] Completed state [iptables_modules_v4_load] at time 05:40:15.000556 duration_in_ms=24.318
2019-05-02 05:40:15,001 [salt.state       :1780][INFO    ][12619] Running state [/etc/iptables/rules.v4] at time 05:40:15.001424
2019-05-02 05:40:15,001 [salt.state       :1813][INFO    ][12619] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-02 05:40:15,111 [salt.state       :300 ][INFO    ][12619] File /etc/iptables/rules.v4 is in the correct state
2019-05-02 05:40:15,112 [salt.state       :1951][INFO    ][12619] Completed state [/etc/iptables/rules.v4] at time 05:40:15.112219 duration_in_ms=110.795
2019-05-02 05:40:15,113 [salt.state       :1780][INFO    ][12619] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:40:15.113323
2019-05-02 05:40:15,113 [salt.state       :1813][INFO    ][12619] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-02 05:40:15,114 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12619] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-02 05:40:15,133 [salt.state       :300 ][INFO    ][12619] onlyif execution failed
2019-05-02 05:40:15,134 [salt.state       :1951][INFO    ][12619] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:40:15.134246 duration_in_ms=20.923
2019-05-02 05:40:15,135 [salt.state       :1780][INFO    ][12619] Running state [netfilter-persistent] at time 05:40:15.135842
2019-05-02 05:40:15,136 [salt.state       :1813][INFO    ][12619] Executing state service.running for [netfilter-persistent]
2019-05-02 05:40:15,137 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12619] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-02 05:40:15,158 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12619] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-02 05:40:15,175 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12619] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-02 05:40:15,191 [salt.state       :300 ][INFO    ][12619] The service netfilter-persistent is already running
2019-05-02 05:40:15,192 [salt.state       :1951][INFO    ][12619] Completed state [netfilter-persistent] at time 05:40:15.192336 duration_in_ms=56.494
2019-05-02 05:40:15,193 [salt.state       :1780][INFO    ][12619] Running state [iptables_extra.remove_stale_tables] at time 05:40:15.193604
2019-05-02 05:40:15,194 [salt.state       :1813][INFO    ][12619] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-02 05:40:15,194 [salt.state       :300 ][INFO    ][12619] No changes made for iptables_extra.remove_stale_tables
2019-05-02 05:40:15,194 [salt.state       :1951][INFO    ][12619] Completed state [iptables_extra.remove_stale_tables] at time 05:40:15.194894 duration_in_ms=1.289
2019-05-02 05:40:15,195 [salt.state       :1780][INFO    ][12619] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:40:15.195246
2019-05-02 05:40:15,195 [salt.state       :1813][INFO    ][12619] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-02 05:40:15,196 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12619] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-02 05:40:15,209 [salt.state       :300 ][INFO    ][12619] onlyif execution failed
2019-05-02 05:40:15,209 [salt.state       :1951][INFO    ][12619] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:40:15.209816 duration_in_ms=14.57
2019-05-02 05:40:15,211 [salt.state       :1780][INFO    ][12619] Running state [/etc/iptables/rules.v6] at time 05:40:15.211171
2019-05-02 05:40:15,211 [salt.state       :1813][INFO    ][12619] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-02 05:40:15,212 [salt.state       :300 ][INFO    ][12619] File /etc/iptables/rules.v6 is not present
2019-05-02 05:40:15,212 [salt.state       :1951][INFO    ][12619] Completed state [/etc/iptables/rules.v6] at time 05:40:15.212594 duration_in_ms=1.424
2019-05-02 05:40:15,213 [salt.state       :1780][INFO    ][12619] Running state [iptables_extra.flush_all] at time 05:40:15.213533
2019-05-02 05:40:15,213 [salt.state       :1813][INFO    ][12619] Executing state module.wait for [iptables_extra.flush_all]
2019-05-02 05:40:15,214 [salt.state       :300 ][INFO    ][12619] No changes made for iptables_extra.flush_all
2019-05-02 05:40:15,214 [salt.state       :1951][INFO    ][12619] Completed state [iptables_extra.flush_all] at time 05:40:15.214637 duration_in_ms=1.105
2019-05-02 05:40:15,218 [salt.minion      :1711][INFO    ][12619] Returning information for job: 20190502054007571204
2019-05-02 05:40:15,835 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190502054015821463
2019-05-02 05:40:15,859 [salt.minion      :1432][INFO    ][12698] Starting a new job with PID 12698
2019-05-02 05:40:16,644 [salt.state       :915 ][INFO    ][12698] Loading fresh modules for state activity
2019-05-02 05:40:17,321 [salt.state       :1780][INFO    ][12698] Running state [maas-rack-controller] at time 05:40:17.321016
2019-05-02 05:40:17,321 [salt.state       :1813][INFO    ][12698] Executing state pkg.installed for [maas-rack-controller]
2019-05-02 05:40:17,321 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12698] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-02 05:40:17,408 [salt.state       :300 ][INFO    ][12698] All specified packages are already installed
2019-05-02 05:40:17,408 [salt.state       :1951][INFO    ][12698] Completed state [maas-rack-controller] at time 05:40:17.408347 duration_in_ms=87.331
2019-05-02 05:40:17,408 [salt.state       :1780][INFO    ][12698] Running state [ipmitool] at time 05:40:17.408672
2019-05-02 05:40:17,408 [salt.state       :1813][INFO    ][12698] Executing state pkg.installed for [ipmitool]
2019-05-02 05:40:17,414 [salt.state       :300 ][INFO    ][12698] All specified packages are already installed
2019-05-02 05:40:17,415 [salt.state       :1951][INFO    ][12698] Completed state [ipmitool] at time 05:40:17.414960 duration_in_ms=6.288
2019-05-02 05:40:17,417 [salt.state       :1780][INFO    ][12698] Running state [/etc/maas/rackd.conf] at time 05:40:17.417767
2019-05-02 05:40:17,418 [salt.state       :1813][INFO    ][12698] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-02 05:40:17,418 [salt.state       :300 ][INFO    ][12698] No changes needed to be made
2019-05-02 05:40:17,419 [salt.state       :1951][INFO    ][12698] Completed state [/etc/maas/rackd.conf] at time 05:40:17.419156 duration_in_ms=1.389
2019-05-02 05:40:17,419 [salt.state       :1780][INFO    ][12698] Running state [/etc/maas/rackd.conf] at time 05:40:17.419362
2019-05-02 05:40:17,419 [salt.state       :1813][INFO    ][12698] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-02 05:40:17,419 [salt.loaded.int.states.file:2298][WARNING ][12698] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-02 05:40:17,420 [salt.state       :300 ][INFO    ][12698] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-02 05:40:17,420 [salt.state       :1951][INFO    ][12698] Completed state [/etc/maas/rackd.conf] at time 05:40:17.420477 duration_in_ms=1.115
2019-05-02 05:40:17,421 [salt.state       :1780][INFO    ][12698] Running state [maas-rackd] at time 05:40:17.421355
2019-05-02 05:40:17,421 [salt.state       :1813][INFO    ][12698] Executing state service.running for [maas-rackd]
2019-05-02 05:40:17,422 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12698] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-02 05:40:17,457 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12698] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-02 05:40:17,475 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12698] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-02 05:40:17,493 [salt.state       :300 ][INFO    ][12698] The service maas-rackd is already running
2019-05-02 05:40:17,493 [salt.state       :1951][INFO    ][12698] Completed state [maas-rackd] at time 05:40:17.493606 duration_in_ms=72.25
2019-05-02 05:40:17,495 [salt.minion      :1711][INFO    ][12698] Returning information for job: 20190502054015821463
2019-05-02 05:40:18,044 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190502054018032024
2019-05-02 05:40:18,067 [salt.minion      :1432][INFO    ][12723] Starting a new job with PID 12723
2019-05-02 05:40:18,815 [salt.state       :915 ][INFO    ][12723] Loading fresh modules for state activity
2019-05-02 05:40:19,477 [salt.state       :1780][INFO    ][12723] Running state [maas-region-controller] at time 05:40:19.477400
2019-05-02 05:40:19,477 [salt.state       :1813][INFO    ][12723] Executing state pkg.installed for [maas-region-controller]
2019-05-02 05:40:19,478 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-02 05:40:19,559 [salt.state       :300 ][INFO    ][12723] All specified packages are already installed
2019-05-02 05:40:19,559 [salt.state       :1951][INFO    ][12723] Completed state [maas-region-controller] at time 05:40:19.559811 duration_in_ms=82.411
2019-05-02 05:40:19,560 [salt.state       :1780][INFO    ][12723] Running state [python-oauth] at time 05:40:19.560097
2019-05-02 05:40:19,560 [salt.state       :1813][INFO    ][12723] Executing state pkg.installed for [python-oauth]
2019-05-02 05:40:19,565 [salt.state       :300 ][INFO    ][12723] All specified packages are already installed
2019-05-02 05:40:19,566 [salt.state       :1951][INFO    ][12723] Completed state [python-oauth] at time 05:40:19.566061 duration_in_ms=5.964
2019-05-02 05:40:19,568 [salt.state       :1780][INFO    ][12723] Running state [/etc/maas/regiond.conf] at time 05:40:19.568724
2019-05-02 05:40:19,568 [salt.state       :1813][INFO    ][12723] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-02 05:40:19,615 [salt.state       :300 ][INFO    ][12723] No changes needed to be made
2019-05-02 05:40:19,616 [salt.state       :1951][INFO    ][12723] Completed state [/etc/maas/regiond.conf] at time 05:40:19.616180 duration_in_ms=47.456
2019-05-02 05:40:19,616 [salt.state       :1780][INFO    ][12723] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:40:19.616900
2019-05-02 05:40:19,617 [salt.state       :1813][INFO    ][12723] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-02 05:40:19,693 [salt.state       :300 ][INFO    ][12723] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-02 05:40:19,693 [salt.state       :1951][INFO    ][12723] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:40:19.693443 duration_in_ms=76.543
2019-05-02 05:40:19,694 [salt.state       :1780][INFO    ][12723] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:40:19.694294
2019-05-02 05:40:19,694 [salt.state       :1813][INFO    ][12723] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-02 05:40:19,707 [salt.state       :300 ][INFO    ][12723] No changes needed to be made
2019-05-02 05:40:19,707 [salt.state       :1951][INFO    ][12723] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:40:19.707668 duration_in_ms=13.374
2019-05-02 05:40:19,708 [salt.state       :1780][INFO    ][12723] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:40:19.708405
2019-05-02 05:40:19,708 [salt.state       :1813][INFO    ][12723] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-02 05:40:19,737 [salt.state       :300 ][INFO    ][12723] No changes needed to be made
2019-05-02 05:40:19,738 [salt.state       :1951][INFO    ][12723] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:40:19.737883 duration_in_ms=29.476
2019-05-02 05:40:19,739 [salt.state       :1780][INFO    ][12723] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:40:19.739020
2019-05-02 05:40:19,739 [salt.state       :1813][INFO    ][12723] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-02 05:40:19,776 [salt.state       :300 ][INFO    ][12723] No changes needed to be made
2019-05-02 05:40:19,777 [salt.state       :1951][INFO    ][12723] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:40:19.777068 duration_in_ms=38.048
2019-05-02 05:40:19,778 [salt.state       :1780][INFO    ][12723] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:40:19.778059
2019-05-02 05:40:19,778 [salt.state       :1813][INFO    ][12723] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-02 05:40:19,791 [salt.state       :300 ][INFO    ][12723] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-02 05:40:19,792 [salt.state       :1951][INFO    ][12723] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:40:19.791940 duration_in_ms=13.881
2019-05-02 05:40:19,793 [salt.state       :1780][INFO    ][12723] Running state [a2enmod headers] at time 05:40:19.793700
2019-05-02 05:40:19,794 [salt.state       :1813][INFO    ][12723] Executing state cmd.run for [a2enmod headers]
2019-05-02 05:40:19,795 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command 'a2enmod headers' in directory '/root'
2019-05-02 05:40:19,867 [salt.state       :300 ][INFO    ][12723] {'pid': 12742, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-02 05:40:19,868 [salt.state       :1951][INFO    ][12723] Completed state [a2enmod headers] at time 05:40:19.868148 duration_in_ms=74.448
2019-05-02 05:40:19,869 [salt.state       :1780][INFO    ][12723] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:40:19.868910
2019-05-02 05:40:19,869 [salt.state       :1813][INFO    ][12723] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-02 05:40:19,889 [salt.state       :300 ][INFO    ][12723] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-02 05:40:19,889 [salt.state       :1951][INFO    ][12723] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:40:19.889688 duration_in_ms=20.778
2019-05-02 05:40:19,890 [salt.state       :1780][INFO    ][12723] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:40:19.890858
2019-05-02 05:40:19,891 [salt.state       :1813][INFO    ][12723] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-02 05:40:19,987 [salt.state       :300 ][INFO    ][12723] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-02 05:40:19,988 [salt.state       :1951][INFO    ][12723] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:40:19.988409 duration_in_ms=97.548
2019-05-02 05:40:19,989 [salt.state       :1780][INFO    ][12723] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:40:19.989558
2019-05-02 05:40:19,990 [salt.state       :1813][INFO    ][12723] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-02 05:40:20,064 [salt.state       :300 ][INFO    ][12723] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-02 05:40:20,064 [salt.state       :1951][INFO    ][12723] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:40:20.064632 duration_in_ms=75.074
2019-05-02 05:40:20,065 [salt.state       :1780][INFO    ][12723] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:40:20.065305
2019-05-02 05:40:20,065 [salt.state       :1813][INFO    ][12723] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-02 05:40:20,118 [salt.state       :300 ][INFO    ][12723] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-02 05:40:20,118 [salt.state       :1951][INFO    ][12723] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:40:20.118325 duration_in_ms=53.02
2019-05-02 05:40:20,118 [salt.state       :1780][INFO    ][12723] Running state [/root/.pgpass] at time 05:40:20.118686
2019-05-02 05:40:20,119 [salt.state       :1813][INFO    ][12723] Executing state file.managed for [/root/.pgpass]
2019-05-02 05:40:20,159 [salt.state       :300 ][INFO    ][12723] File /root/.pgpass is in the correct state
2019-05-02 05:40:20,160 [salt.state       :1951][INFO    ][12723] Completed state [/root/.pgpass] at time 05:40:20.160154 duration_in_ms=41.467
2019-05-02 05:40:20,163 [salt.state       :1780][INFO    ][12723] Running state [maas-region syncdb --noinput] at time 05:40:20.163862
2019-05-02 05:40:20,164 [salt.state       :1813][INFO    ][12723] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-02 05:40:20,164 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-02 05:40:22,403 [salt.state       :300 ][INFO    ][12723] {'pid': 12755, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sites, maasserver, auth, sessions, piston3, metadataserver, contenttypes\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-02 05:40:22,404 [salt.state       :1951][INFO    ][12723] Completed state [maas-region syncdb --noinput] at time 05:40:22.404325 duration_in_ms=2240.46
2019-05-02 05:40:22,405 [salt.state       :2022][WARNING ][12723] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-02 05:40:22,407 [salt.state       :1780][INFO    ][12723] Running state [maas-regiond] at time 05:40:22.407787
2019-05-02 05:40:22,408 [salt.state       :1813][INFO    ][12723] Executing state service.running for [maas-regiond]
2019-05-02 05:40:22,409 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-02 05:40:22,447 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-02 05:40:22,464 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-02 05:40:22,479 [salt.state       :300 ][INFO    ][12723] The service maas-regiond is already running
2019-05-02 05:40:22,479 [salt.state       :1951][INFO    ][12723] Completed state [maas-regiond] at time 05:40:22.479799 duration_in_ms=72.012
2019-05-02 05:40:22,481 [salt.state       :1780][INFO    ][12723] Running state [bind9] at time 05:40:22.481590
2019-05-02 05:40:22,482 [salt.state       :1813][INFO    ][12723] Executing state service.running for [bind9]
2019-05-02 05:40:22,482 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-02 05:40:22,498 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-02 05:40:22,513 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-02 05:40:22,528 [salt.state       :300 ][INFO    ][12723] The service bind9 is already running
2019-05-02 05:40:22,528 [salt.state       :1951][INFO    ][12723] Completed state [bind9] at time 05:40:22.528850 duration_in_ms=47.259
2019-05-02 05:40:22,530 [salt.state       :1780][INFO    ][12723] Running state [apache2] at time 05:40:22.530374
2019-05-02 05:40:22,530 [salt.state       :1813][INFO    ][12723] Executing state service.running for [apache2]
2019-05-02 05:40:22,531 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-02 05:40:22,546 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-02 05:40:22,560 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-02 05:40:22,578 [salt.state       :300 ][INFO    ][12723] The service apache2 is already running
2019-05-02 05:40:22,578 [salt.state       :1951][INFO    ][12723] Completed state [apache2] at time 05:40:22.578292 duration_in_ms=47.918
2019-05-02 05:40:22,579 [salt.state       :1780][INFO    ][12723] Running state [maasng.wait_for_http_code] at time 05:40:22.579427
2019-05-02 05:40:22,579 [salt.state       :1813][INFO    ][12723] Executing state module.run for [maasng.wait_for_http_code]
2019-05-02 05:40:22,580 [salt.utils.decorators:613 ][WARNING ][12723] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-02 05:40:22,696 [salt.state       :300 ][INFO    ][12723] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-02 05:40:22,696 [salt.state       :1951][INFO    ][12723] Completed state [maasng.wait_for_http_code] at time 05:40:22.696846 duration_in_ms=117.418
2019-05-02 05:40:22,698 [salt.state       :1780][INFO    ][12723] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:40:22.698022
2019-05-02 05:40:22,698 [salt.state       :1813][INFO    ][12723] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-02 05:40:22,699 [salt.state       :300 ][INFO    ][12723] /var/lib/maas/.setup_admin exists
2019-05-02 05:40:22,699 [salt.state       :1951][INFO    ][12723] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:40:22.699441 duration_in_ms=1.418
2019-05-02 05:40:22,700 [salt.state       :1780][INFO    ][12723] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:40:22.700454
2019-05-02 05:40:22,700 [salt.state       :1813][INFO    ][12723] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-02 05:40:22,701 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-02 05:40:23,998 [salt.state       :300 ][INFO    ][12723] {'pid': 12777, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-02 05:40:23,999 [salt.state       :1951][INFO    ][12723] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:40:23.999442 duration_in_ms=1298.987
2019-05-02 05:40:24,010 [salt.state       :1780][INFO    ][12723] Running state [maas_region_boot_source_resources_mirror] at time 05:40:24.010680
2019-05-02 05:40:24,011 [salt.state       :1813][INFO    ][12723] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-02 05:40:24,110 [salt.state       :300 ][INFO    ][12723] {'changes': {}}
2019-05-02 05:40:24,110 [salt.state       :1951][INFO    ][12723] Completed state [maas_region_boot_source_resources_mirror] at time 05:40:24.110866 duration_in_ms=100.185
2019-05-02 05:40:24,112 [salt.state       :1780][INFO    ][12723] Running state [maasng.boot_resources_import] at time 05:40:24.111997
2019-05-02 05:40:24,112 [salt.state       :1813][INFO    ][12723] Executing state module.run for [maasng.boot_resources_import]
2019-05-02 05:40:24,113 [salt.utils.decorators:613 ][WARNING ][12723] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-02 05:40:24,194 [salt.loaded.ext.module.maasng:1600][INFO    ][12723] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-02 05:40:29,262 [salt.loaded.ext.module.maasng:1600][INFO    ][12723] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-02 05:40:33,083 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502054033071056
2019-05-02 05:40:33,108 [salt.minion      :1432][INFO    ][12804] Starting a new job with PID 12804
2019-05-02 05:40:33,130 [salt.minion      :1711][INFO    ][12804] Returning information for job: 20190502054033071056
2019-05-02 05:40:34,319 [salt.loaded.ext.module.maasng:1600][INFO    ][12723] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-05-02 05:40:39,433 [salt.state       :300 ][INFO    ][12723] {'ret': True}
2019-05-02 05:40:39,433 [salt.state       :1951][INFO    ][12723] Completed state [maasng.boot_resources_import] at time 05:40:39.433619 duration_in_ms=15321.622
2019-05-02 05:40:39,434 [salt.state       :1780][INFO    ][12723] Running state [maas_region_boot_sources_selection_xenial] at time 05:40:39.434760
2019-05-02 05:40:39,435 [salt.state       :1813][INFO    ][12723] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-02 05:40:39,636 [salt.state       :300 ][INFO    ][12723] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-02 05:40:39,637 [salt.state       :1951][INFO    ][12723] Completed state [maas_region_boot_sources_selection_xenial] at time 05:40:39.637078 duration_in_ms=202.318
2019-05-02 05:40:39,638 [salt.state       :1780][INFO    ][12723] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:40:39.638453
2019-05-02 05:40:39,639 [salt.state       :1813][INFO    ][12723] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-02 05:40:39,639 [salt.utils.decorators:613 ][WARNING ][12723] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-02 05:40:39,640 [salt.loaded.ext.module.maasng:1771][INFO    ][12723] boot-sources sync initiated for ALL Rack's
2019-05-02 05:40:40,612 [salt.state       :300 ][INFO    ][12723] {'ret': True}
2019-05-02 05:40:40,613 [salt.state       :1951][INFO    ][12723] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:40:40.613551 duration_in_ms=975.097
2019-05-02 05:40:40,615 [salt.state       :1780][INFO    ][12723] Running state [maas.process_maas_config] at time 05:40:40.615713
2019-05-02 05:40:40,616 [salt.state       :1813][INFO    ][12723] Executing state module.run for [maas.process_maas_config]
2019-05-02 05:40:40,616 [salt.utils.decorators:613 ][WARNING ][12723] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-02 05:40:40,617 [salt.loaded.ext.module.maas:92  ][INFO    ][12723] maasconfig name=enable_http_proxy value=True
2019-05-02 05:40:40,668 [salt.loaded.ext.module.maas:92  ][INFO    ][12723] maasconfig name=upstream_dns value=8.8.8.8
2019-05-02 05:40:43,659 [salt.loaded.ext.module.maas:92  ][INFO    ][12723] maasconfig name=commissioning_distro_series value=xenial
2019-05-02 05:40:43,798 [salt.loaded.ext.module.maas:92  ][INFO    ][12723] maasconfig name=default_osystem value=ubuntu
2019-05-02 05:40:43,874 [salt.loaded.ext.module.maas:92  ][INFO    ][12723] maasconfig name=active_discovery_interval value=600
2019-05-02 05:40:43,927 [salt.loaded.ext.module.maas:92  ][INFO    ][12723] maasconfig name=dnssec_validation value=no
2019-05-02 05:40:43,989 [salt.loaded.ext.module.maas:92  ][INFO    ][12723] maasconfig name=maas_name value=mas01
2019-05-02 05:40:44,048 [salt.loaded.ext.module.maas:92  ][INFO    ][12723] maasconfig name=network_discovery value=enabled
2019-05-02 05:40:44,155 [salt.loaded.ext.module.maas:92  ][INFO    ][12723] maasconfig name=enable_third_party_drivers value=True
2019-05-02 05:40:44,209 [salt.loaded.ext.module.maas:92  ][INFO    ][12723] maasconfig name=default_storage_layout value=lvm
2019-05-02 05:40:44,265 [salt.loaded.ext.module.maas:92  ][INFO    ][12723] maasconfig name=ntp_external_only value=True
2019-05-02 05:40:44,319 [salt.loaded.ext.module.maas:92  ][INFO    ][12723] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-02 05:40:44,373 [salt.loaded.ext.module.maas:92  ][INFO    ][12723] maasconfig name=default_distro_series value=xenial
2019-05-02 05:40:44,439 [salt.loaded.ext.module.maas:92  ][INFO    ][12723] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-02 05:40:44,571 [salt.state       :300 ][INFO    ][12723] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-02 05:40:44,572 [salt.state       :1951][INFO    ][12723] Completed state [maas.process_maas_config] at time 05:40:44.572268 duration_in_ms=3956.556
2019-05-02 05:40:44,573 [salt.state       :1780][INFO    ][12723] Running state [pxe_admin] at time 05:40:44.573110
2019-05-02 05:40:44,573 [salt.state       :1813][INFO    ][12723] Executing state maasng.fabric_present for [pxe_admin]
2019-05-02 05:40:44,645 [salt.loaded.ext.module.maasng:945 ][INFO    ][12723] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'n67nwq', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-02 05:40:44,723 [salt.loaded.ext.module.maasng:1008][WARNING ][12723] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-02 05:40:44,723 [salt.loaded.ext.module.maasng:1011][WARNING ][12723] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-02 05:40:44,808 [salt.state       :300 ][INFO    ][12723] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-02 05:40:44,808 [salt.state       :1951][INFO    ][12723] Completed state [pxe_admin] at time 05:40:44.808653 duration_in_ms=235.542
2019-05-02 05:40:44,809 [salt.state       :1780][INFO    ][12723] Running state [vlan 0] at time 05:40:44.809077
2019-05-02 05:40:44,809 [salt.state       :1813][INFO    ][12723] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-02 05:40:44,879 [salt.loaded.ext.module.maasng:945 ][INFO    ][12723] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n67nwq', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-05-02 05:40:45,004 [salt.loaded.ext.module.maasng:945 ][INFO    ][12723] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n67nwq', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-05-02 05:40:45,313 [salt.loaded.ext.module.maasng:945 ][INFO    ][12723] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'n67nwq', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-05-02 05:40:45,404 [salt.state       :300 ][INFO    ][12723] {'new': 'Vlan untagged was updated'}
2019-05-02 05:40:45,404 [salt.state       :1951][INFO    ][12723] Completed state [vlan 0] at time 05:40:45.404422 duration_in_ms=595.344
2019-05-02 05:40:45,405 [salt.state       :1780][INFO    ][12723] Running state [192.168.11.0/24] at time 05:40:45.405796
2019-05-02 05:40:45,406 [salt.state       :1813][INFO    ][12723] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-02 05:40:45,622 [salt.loaded.ext.module.maasng:945 ][INFO    ][12723] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n67nwq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-02 05:40:45,622 [salt.loaded.ext.module.maasng:1235][WARNING ][12723] Ignoring parameter vlan:0
2019-05-02 05:40:45,706 [salt.state       :300 ][INFO    ][12723] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-02 05:40:45,706 [salt.state       :1951][INFO    ][12723] Completed state [192.168.11.0/24] at time 05:40:45.706682 duration_in_ms=300.884
2019-05-02 05:40:45,708 [salt.state       :1780][INFO    ][12723] Running state [maas_create_iprange_1] at time 05:40:45.708050
2019-05-02 05:40:45,708 [salt.state       :1813][INFO    ][12723] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-02 05:40:45,759 [salt.state       :300 ][INFO    ][12723] Iprange maas_create_iprange_1 already exist.
2019-05-02 05:40:45,759 [salt.state       :1951][INFO    ][12723] Completed state [maas_create_iprange_1] at time 05:40:45.759809 duration_in_ms=51.759
2019-05-02 05:40:45,760 [salt.state       :1780][INFO    ][12723] Running state [vlan 0] at time 05:40:45.760246
2019-05-02 05:40:45,760 [salt.state       :1813][INFO    ][12723] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-02 05:40:45,826 [salt.loaded.ext.module.maasng:945 ][INFO    ][12723] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'n67nwq', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-05-02 05:40:45,921 [salt.loaded.ext.module.maasng:945 ][INFO    ][12723] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n67nwq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-02 05:40:46,149 [salt.loaded.ext.module.maasng:945 ][INFO    ][12723] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'n67nwq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-02 05:40:46,249 [salt.state       :300 ][INFO    ][12723] {'new': 'Vlan untagged was updated'}
2019-05-02 05:40:46,249 [salt.state       :1951][INFO    ][12723] Completed state [vlan 0] at time 05:40:46.249306 duration_in_ms=489.06
2019-05-02 05:40:46,249 [salt.state       :1780][INFO    ][12723] Running state [opnfv] at time 05:40:46.249935
2019-05-02 05:40:46,250 [salt.state       :1813][INFO    ][12723] Executing state maasng.sshkey_present for [opnfv]
2019-05-02 05:40:46,304 [salt.loaded.ext.module.maasng:1903][INFO    ][12723] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-05-02 05:40:46,304 [salt.state       :300 ][INFO    ][12723] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-05-02 05:40:46,305 [salt.state       :1951][INFO    ][12723] Completed state [opnfv] at time 05:40:46.305135 duration_in_ms=55.199
2019-05-02 05:40:46,305 [salt.state       :1780][INFO    ][12723] Running state [maas.process_tags] at time 05:40:46.305902
2019-05-02 05:40:46,306 [salt.state       :1813][INFO    ][12723] Executing state module.run for [maas.process_tags]
2019-05-02 05:40:46,306 [salt.utils.decorators:613 ][WARNING ][12723] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-02 05:40:46,358 [salt.loaded.ext.module.maas:92  ][INFO    ][12723] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-05-02 05:40:46,421 [salt.state       :300 ][INFO    ][12723] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-05-02 05:40:46,422 [salt.state       :1951][INFO    ][12723] Completed state [maas.process_tags] at time 05:40:46.422334 duration_in_ms=116.431
2019-05-02 05:40:46,425 [salt.minion      :1711][INFO    ][12723] Returning information for job: 20190502054018032024
2019-05-02 05:40:46,953 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190502054046942770
2019-05-02 05:40:46,972 [salt.minion      :1432][INFO    ][13198] Starting a new job with PID 13198
2019-05-02 05:40:50,526 [salt.state       :915 ][INFO    ][13198] Loading fresh modules for state activity
2019-05-02 05:40:50,622 [salt.state       :1780][INFO    ][13198] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:40:50.622840
2019-05-02 05:40:50,623 [salt.state       :1813][INFO    ][13198] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-02 05:40:50,625 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13198] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-02 05:40:51,989 [salt.state       :300 ][INFO    ][13198] {'pid': 13220, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-02 05:40:51,990 [salt.state       :1951][INFO    ][13198] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:40:51.990560 duration_in_ms=1367.719
2019-05-02 05:40:51,993 [salt.state       :1780][INFO    ][13198] Running state [maas.process_machines] at time 05:40:51.993220
2019-05-02 05:40:51,993 [salt.state       :1813][INFO    ][13198] Executing state module.run for [maas.process_machines]
2019-05-02 05:40:51,995 [salt.utils.decorators:613 ][WARNING ][13198] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-02 05:40:52,630 [salt.loaded.ext.module.maas:412 ][WARNING ][13198] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-02 05:40:52,631 [salt.loaded.ext.module.maas:92  ][INFO    ][13198] machine hostname=cmp002 power_type=ipmi mac_addresses=['00:25:b5:a0:00:6a'] power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus system_id=n34cf6 architecture=amd64/generic power_parameters_power_user=admin
2019-05-02 05:40:53,919 [salt.loaded.ext.module.maas:412 ][WARNING ][13198] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-02 05:40:53,920 [salt.loaded.ext.module.maas:92  ][INFO    ][13198] machine hostname=cmp001 power_type=ipmi mac_addresses=['00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=cdqc88 architecture=amd64/generic power_parameters_power_user=admin
2019-05-02 05:40:55,201 [salt.loaded.ext.module.maas:412 ][WARNING ][13198] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-02 05:40:55,202 [salt.loaded.ext.module.maas:92  ][INFO    ][13198] machine hostname=kvm01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=dpbx3y architecture=amd64/generic power_parameters_power_user=admin
2019-05-02 05:40:56,495 [salt.loaded.ext.module.maas:412 ][WARNING ][13198] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-02 05:40:56,496 [salt.loaded.ext.module.maas:92  ][INFO    ][13198] machine hostname=kvm03 power_type=ipmi mac_addresses=['00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=4df8xx architecture=amd64/generic power_parameters_power_user=admin
2019-05-02 05:40:57,752 [salt.loaded.ext.module.maas:412 ][WARNING ][13198] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-02 05:40:57,753 [salt.loaded.ext.module.maas:92  ][INFO    ][13198] machine hostname=kvm02 power_type=ipmi mac_addresses=['00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=mwr7bw architecture=amd64/generic power_parameters_power_user=admin
2019-05-02 05:40:58,920 [salt.state       :300 ][INFO    ][13198] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-02 05:40:58,920 [salt.state       :1951][INFO    ][13198] Completed state [maas.process_machines] at time 05:40:58.920671 duration_in_ms=6927.45
2019-05-02 05:40:58,924 [salt.minion      :1711][INFO    ][13198] Returning information for job: 20190502054046942770
2019-05-02 05:41:31,203 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190502054131160684
2019-05-02 05:41:31,224 [salt.minion      :1432][INFO    ][13451] Starting a new job with PID 13451
2019-05-02 05:41:34,910 [salt.state       :915 ][INFO    ][13451] Loading fresh modules for state activity
2019-05-02 05:41:35,008 [salt.state       :1780][INFO    ][13451] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:41:35.008466
2019-05-02 05:41:35,008 [salt.state       :1813][INFO    ][13451] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-02 05:41:35,011 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13451] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-02 05:41:36,513 [salt.state       :300 ][INFO    ][13451] {'pid': 13458, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-02 05:41:36,513 [salt.state       :1951][INFO    ][13451] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:41:36.513812 duration_in_ms=1505.347
2019-05-02 05:41:36,516 [salt.state       :1780][INFO    ][13451] Running state [maas.wait_for_machine_status] at time 05:41:36.516441
2019-05-02 05:41:36,517 [salt.state       :1813][INFO    ][13451] Executing state module.run for [maas.wait_for_machine_status]
2019-05-02 05:41:36,517 [salt.utils.decorators:613 ][WARNING ][13451] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-02 05:41:38,475 [salt.loaded.ext.module.maas:993 ][INFO    ][13451] Machine dpbx3y mark broken
2019-05-02 05:41:39,105 [salt.loaded.ext.module.maas:996 ][INFO    ][13451] Machine dpbx3y mark fixed
2019-05-02 05:41:40,318 [salt.loaded.ext.module.maas:684 ][INFO    ][13451] deploymachines hwe_kernel=hwe-16.04 system_id=dpbx3y distro_series=xenial
2019-05-02 05:41:44,492 [salt.loaded.ext.module.maas:1023][INFO    ][13451] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1492.03610206s left)
2019-05-02 05:41:46,199 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502054146186884
2019-05-02 05:41:46,223 [salt.minion      :1432][INFO    ][13563] Starting a new job with PID 13563
2019-05-02 05:41:46,250 [salt.minion      :1711][INFO    ][13563] Returning information for job: 20190502054146186884
2019-05-02 05:42:16,254 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502054216241474
2019-05-02 05:42:16,278 [salt.minion      :1432][INFO    ][13586] Starting a new job with PID 13586
2019-05-02 05:42:16,303 [salt.minion      :1711][INFO    ][13586] Returning information for job: 20190502054216241474
2019-05-02 05:42:17,747 [salt.loaded.ext.module.maas:1023][INFO    ][13451] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1458.78178787s left)
2019-05-02 05:42:46,380 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502054246329449
2019-05-02 05:42:46,403 [salt.minion      :1432][INFO    ][13641] Starting a new job with PID 13641
2019-05-02 05:42:46,430 [salt.minion      :1711][INFO    ][13641] Returning information for job: 20190502054246329449
2019-05-02 05:42:51,245 [salt.loaded.ext.module.maas:1023][INFO    ][13451] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1425.28403091s left)
2019-05-02 05:43:16,433 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502054316418001
2019-05-02 05:43:16,457 [salt.minion      :1432][INFO    ][13662] Starting a new job with PID 13662
2019-05-02 05:43:16,482 [salt.minion      :1711][INFO    ][13662] Returning information for job: 20190502054316418001
2019-05-02 05:43:24,313 [salt.loaded.ext.module.maas:1023][INFO    ][13451] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1392.21577191s left)
2019-05-02 05:43:46,493 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502054346478804
2019-05-02 05:43:46,517 [salt.minion      :1432][INFO    ][13799] Starting a new job with PID 13799
2019-05-02 05:43:46,544 [salt.minion      :1711][INFO    ][13799] Returning information for job: 20190502054346478804
2019-05-02 05:43:57,967 [salt.loaded.ext.module.maas:1023][INFO    ][13451] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1358.56156301s left)
2019-05-02 05:44:16,554 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502054416539639
2019-05-02 05:44:16,578 [salt.minion      :1432][INFO    ][13827] Starting a new job with PID 13827
2019-05-02 05:44:16,601 [salt.minion      :1711][INFO    ][13827] Returning information for job: 20190502054416539639
2019-05-02 05:44:31,660 [salt.loaded.ext.module.maas:1023][INFO    ][13451] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1324.868186s left)
2019-05-02 05:44:46,615 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502054446602677
2019-05-02 05:44:46,639 [salt.minion      :1432][INFO    ][13998] Starting a new job with PID 13998
2019-05-02 05:44:46,666 [salt.minion      :1711][INFO    ][13998] Returning information for job: 20190502054446602677
2019-05-02 05:45:05,390 [salt.loaded.ext.module.maas:1023][INFO    ][13451] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1291.13890004s left)
2019-05-02 05:45:16,678 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502054516665910
2019-05-02 05:45:16,703 [salt.minion      :1432][INFO    ][14021] Starting a new job with PID 14021
2019-05-02 05:45:16,728 [salt.minion      :1711][INFO    ][14021] Returning information for job: 20190502054516665910
2019-05-02 05:45:39,103 [salt.loaded.ext.module.maas:1023][INFO    ][13451] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1257.4253149s left)
2019-05-02 05:45:46,747 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502054546735392
2019-05-02 05:45:46,769 [salt.minion      :1432][INFO    ][14105] Starting a new job with PID 14105
2019-05-02 05:45:46,793 [salt.minion      :1711][INFO    ][14105] Returning information for job: 20190502054546735392
2019-05-02 05:46:12,559 [salt.loaded.ext.module.maas:1023][INFO    ][13451] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1223.96944904s left)
2019-05-02 05:46:16,812 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502054616799900
2019-05-02 05:46:16,835 [salt.minion      :1432][INFO    ][14127] Starting a new job with PID 14127
2019-05-02 05:46:16,859 [salt.minion      :1711][INFO    ][14127] Returning information for job: 20190502054616799900
2019-05-02 05:46:45,974 [salt.loaded.ext.module.maas:1023][INFO    ][13451] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1190.55418301s left)
2019-05-02 05:46:46,887 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502054646874914
2019-05-02 05:46:46,911 [salt.minion      :1432][INFO    ][14288] Starting a new job with PID 14288
2019-05-02 05:46:46,937 [salt.minion      :1711][INFO    ][14288] Returning information for job: 20190502054646874914
2019-05-02 05:47:16,964 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502054716951821
2019-05-02 05:47:16,988 [salt.minion      :1432][INFO    ][14311] Starting a new job with PID 14311
2019-05-02 05:47:17,014 [salt.minion      :1711][INFO    ][14311] Returning information for job: 20190502054716951821
2019-05-02 05:47:19,333 [salt.loaded.ext.module.maas:1023][INFO    ][13451] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1157.19603896s left)
2019-05-02 05:47:47,061 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502054747048637
2019-05-02 05:47:47,086 [salt.minion      :1432][INFO    ][14434] Starting a new job with PID 14434
2019-05-02 05:47:47,113 [salt.minion      :1711][INFO    ][14434] Returning information for job: 20190502054747048637
2019-05-02 05:47:52,580 [salt.loaded.ext.module.maas:1023][INFO    ][13451] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1123.94840908s left)
2019-05-02 05:48:17,152 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502054817139739
2019-05-02 05:48:17,176 [salt.minion      :1432][INFO    ][14453] Starting a new job with PID 14453
2019-05-02 05:48:17,202 [salt.minion      :1711][INFO    ][14453] Returning information for job: 20190502054817139739
2019-05-02 05:48:26,219 [salt.loaded.ext.module.maas:1023][INFO    ][13451] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1090.30996299s left)
2019-05-02 05:48:47,250 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502054847238208
2019-05-02 05:48:47,274 [salt.minion      :1432][INFO    ][14545] Starting a new job with PID 14545
2019-05-02 05:48:47,298 [salt.minion      :1711][INFO    ][14545] Returning information for job: 20190502054847238208
2019-05-02 05:48:59,446 [salt.loaded.ext.module.maas:1023][INFO    ][13451] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1057.08210802s left)
2019-05-02 05:49:17,307 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190502054917294046
2019-05-02 05:49:17,331 [salt.minion      :1432][INFO    ][14599] Starting a new job with PID 14599
2019-05-02 05:49:17,358 [salt.minion      :1711][INFO    ][14599] Returning information for job: 20190502054917294046
2019-05-02 05:49:33,107 [salt.state       :300 ][INFO    ][13451] {'ret': True}
2019-05-02 05:49:33,107 [salt.state       :1951][INFO    ][13451] Completed state [maas.wait_for_machine_status] at time 05:49:33.107820 duration_in_ms=476591.378
2019-05-02 05:49:33,111 [salt.minion      :1711][INFO    ][13451] Returning information for job: 20190502054131160684
2019-05-02 05:49:33,765 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190502054933752618
2019-05-02 05:49:33,795 [salt.minion      :1432][INFO    ][14625] Starting a new job with PID 14625
2019-05-02 05:49:37,425 [salt.state       :915 ][INFO    ][14625] Loading fresh modules for state activity
2019-05-02 05:49:37,520 [salt.state       :1780][INFO    ][14625] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:49:37.520597
2019-05-02 05:49:37,520 [salt.state       :1813][INFO    ][14625] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-02 05:49:37,522 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14625] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-02 05:49:39,088 [salt.state       :300 ][INFO    ][14625] {'pid': 14648, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-02 05:49:39,089 [salt.state       :1951][INFO    ][14625] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:49:39.089253 duration_in_ms=1568.654
2019-05-02 05:49:39,092 [salt.state       :1780][INFO    ][14625] Running state [maas_machines_storage_cmp002_lvm] at time 05:49:39.092659
2019-05-02 05:49:39,093 [salt.state       :1813][INFO    ][14625] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-02 05:49:39,732 [salt.state       :300 ][INFO    ][14625] Machine cmp002 is not in Ready state.
2019-05-02 05:49:39,733 [salt.state       :1951][INFO    ][14625] Completed state [maas_machines_storage_cmp002_lvm] at time 05:49:39.733249 duration_in_ms=640.589
2019-05-02 05:49:39,733 [salt.state       :1780][INFO    ][14625] Running state [maas_machines_storage_cmp001_lvm] at time 05:49:39.733856
2019-05-02 05:49:39,734 [salt.state       :1813][INFO    ][14625] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-02 05:49:40,448 [salt.state       :300 ][INFO    ][14625] Machine cmp001 is not in Ready state.
2019-05-02 05:49:40,449 [salt.state       :1951][INFO    ][14625] Completed state [maas_machines_storage_cmp001_lvm] at time 05:49:40.448996 duration_in_ms=715.14
2019-05-02 05:49:40,453 [salt.minion      :1711][INFO    ][14625] Returning information for job: 20190502054933752618
2019-05-02 05:49:41,090 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190502054941076844
2019-05-02 05:49:41,112 [salt.minion      :1432][INFO    ][14658] Starting a new job with PID 14658
2019-05-02 05:49:41,857 [salt.state       :915 ][INFO    ][14658] Loading fresh modules for state activity
2019-05-02 05:49:41,950 [salt.state       :1780][INFO    ][14658] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:49:41.950101
2019-05-02 05:49:41,950 [salt.state       :1813][INFO    ][14658] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-02 05:49:41,953 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14658] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-02 05:49:43,515 [salt.state       :300 ][INFO    ][14658] {'pid': 14665, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-02 05:49:43,515 [salt.state       :1951][INFO    ][14658] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:49:43.515432 duration_in_ms=1565.332
2019-05-02 05:49:43,516 [salt.state       :1780][INFO    ][14658] Running state [maas.deploy_machines] at time 05:49:43.516542
2019-05-02 05:49:43,516 [salt.state       :1813][INFO    ][14658] Executing state module.run for [maas.deploy_machines]
2019-05-02 05:49:43,517 [salt.utils.decorators:613 ][WARNING ][14658] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-02 05:49:44,314 [salt.state       :300 ][INFO    ][14658] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-02 05:49:44,315 [salt.state       :1951][INFO    ][14658] Completed state [maas.deploy_machines] at time 05:49:44.315164 duration_in_ms=798.619
2019-05-02 05:49:44,319 [salt.minion      :1711][INFO    ][14658] Returning information for job: 20190502054941076844
2019-05-02 05:49:44,811 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190502054944798937
2019-05-02 05:49:44,832 [salt.minion      :1432][INFO    ][14808] Starting a new job with PID 14808
2019-05-02 05:49:45,537 [salt.state       :915 ][INFO    ][14808] Loading fresh modules for state activity
2019-05-02 05:49:45,627 [salt.state       :1780][INFO    ][14808] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:49:45.627784
2019-05-02 05:49:45,628 [salt.state       :1813][INFO    ][14808] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-02 05:49:45,630 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14808] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-02 05:49:47,207 [salt.state       :300 ][INFO    ][14808] {'pid': 14828, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-02 05:49:47,208 [salt.state       :1951][INFO    ][14808] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:49:47.208280 duration_in_ms=1580.498
2019-05-02 05:49:47,209 [salt.state       :1780][INFO    ][14808] Running state [maas.wait_for_machine_status] at time 05:49:47.209963
2019-05-02 05:49:47,210 [salt.state       :1813][INFO    ][14808] Executing state module.run for [maas.wait_for_machine_status]
2019-05-02 05:49:47,210 [salt.utils.decorators:613 ][WARNING ][14808] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-02 05:49:50,760 [salt.state       :300 ][INFO    ][14808] {'ret': True}
2019-05-02 05:49:50,761 [salt.state       :1951][INFO    ][14808] Completed state [maas.wait_for_machine_status] at time 05:49:50.761328 duration_in_ms=3551.361
2019-05-02 05:49:50,765 [salt.minion      :1711][INFO    ][14808] Returning information for job: 20190502054944798937
2019-05-02 06:19:58,391 [salt.utils.schedule:1377][INFO    ][6702] Running scheduled job: __mine_interval
2019-05-02 07:14:47,531 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command cp.push_dir with jid 20190502071447520000
2019-05-02 07:14:47,553 [salt.minion      :1432][INFO    ][20819] Starting a new job with PID 20819
