2019-06-20 05:08:30,343 [salt.minion      :870 ][ERROR   ][360] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-20 05:09:20,382 [salt.minion      :870 ][ERROR   ][360] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-20 05:10:10,428 [salt.minion      :870 ][ERROR   ][360] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-20 05:11:00,471 [salt.minion      :870 ][ERROR   ][360] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-20 05:11:50,520 [salt.minion      :870 ][ERROR   ][360] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-20 05:14:00,492 [salt.utils.decorators:613 ][WARNING ][2735] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 05:14:01,113 [salt.utils.decorators:613 ][WARNING ][2735] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 05:14:03,232 [salt.loaded.int.states.file:2298][WARNING ][2879] 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-06-20 05:14:08,669 [salt.state       :2022][WARNING ][2985] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-20 05:14:11,267 [salt.utils.decorators:613 ][WARNING ][2985] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 05:14:25,497 [salt.utils.decorators:613 ][WARNING ][2985] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 05:14:57,063 [salt.utils.decorators:613 ][WARNING ][2985] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 05:14:57,980 [salt.utils.decorators:613 ][WARNING ][2985] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 05:15:01,846 [salt.loaded.ext.module.maasng:1008][WARNING ][2985] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-06-20 05:15:01,846 [salt.loaded.ext.module.maasng:1011][WARNING ][2985] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-06-20 05:15:02,779 [salt.loaded.ext.module.maasng:1235][WARNING ][2985] Ignoring parameter vlan:0
2019-06-20 05:15:03,890 [salt.utils.decorators:613 ][WARNING ][2985] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 05:15:09,476 [salt.utils.decorators:613 ][WARNING ][5841] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 05:15:09,541 [salt.loaded.ext.module.maas:412 ][WARNING ][5841] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-20 05:15:10,985 [salt.loaded.ext.module.maas:412 ][WARNING ][5841] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-20 05:15:12,285 [salt.loaded.ext.module.maas:412 ][WARNING ][5841] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-20 05:15:13,302 [salt.loaded.ext.module.maas:412 ][WARNING ][5841] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-20 05:15:14,451 [salt.loaded.ext.module.maas:412 ][WARNING ][5841] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-20 05:15:18,115 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6454] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-20 05:15:18,147 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6454] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-20 05:15:18,170 [salt.utils.parsers:1051][WARNING ][360] Minion received a SIGTERM. Exiting.
2019-06-20 05:15:19,169 [salt.cli.daemons :293 ][INFO    ][6508] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-06-20 05:15:19,250 [salt.cli.daemons :82  ][INFO    ][6508] Starting up the Salt Minion
2019-06-20 05:15:19,250 [salt.utils.event :1017][INFO    ][6508] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-06-20 05:15:20,107 [salt.minion      :976 ][INFO    ][6508] Creating minion process manager
2019-06-20 05:15:21,565 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6508] Executing command ['date', '+%z'] in directory '/root'
2019-06-20 05:15:21,584 [salt.utils.schedule:568 ][INFO    ][6508] Updating job settings for scheduled job: __mine_interval
2019-06-20 05:15:21,585 [salt.minion      :1108][INFO    ][6508] Added mine.update to scheduler
2019-06-20 05:15:21,589 [salt.minion      :1975][INFO    ][6508] Minion is starting as user 'root'
2019-06-20 05:15:21,601 [salt.minion      :2336][INFO    ][6508] Minion is ready to receive requests!
2019-06-20 05:15:46,789 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command state.apply with jid 20190620051546779949
2019-06-20 05:15:46,811 [salt.minion      :1432][INFO    ][6601] Starting a new job with PID 6601
2019-06-20 05:15:50,571 [salt.state       :915 ][INFO    ][6601] Loading fresh modules for state activity
2019-06-20 05:15:50,622 [salt.fileclient  :1219][INFO    ][6601] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-20 05:15:50,666 [salt.state       :1780][INFO    ][6601] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:50.666264
2019-06-20 05:15:50,666 [salt.state       :1813][INFO    ][6601] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-20 05:15:50,668 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6601] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-20 05:15:52,133 [salt.state       :300 ][INFO    ][6601] {'pid': 6609, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-20 05:15:52,134 [salt.state       :1951][INFO    ][6601] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:52.134241 duration_in_ms=1467.978
2019-06-20 05:15:52,135 [salt.state       :1780][INFO    ][6601] Running state [maas.wait_for_machine_status] at time 05:15:52.135745
2019-06-20 05:15:52,135 [salt.state       :1813][INFO    ][6601] Executing state module.run for [maas.wait_for_machine_status]
2019-06-20 05:15:52,136 [salt.utils.decorators:613 ][WARNING ][6601] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 05:15:53,312 [salt.loaded.ext.module.maas:1023][INFO    ][6601] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.82815695s left)
2019-06-20 05:16:01,862 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620051601849690
2019-06-20 05:16:01,885 [salt.minion      :1432][INFO    ][6621] Starting a new job with PID 6621
2019-06-20 05:16:01,908 [salt.minion      :1711][INFO    ][6621] Returning information for job: 20190620051601849690
2019-06-20 05:16:24,253 [salt.loaded.ext.module.maas:1023][INFO    ][6601] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.88758802s left)
2019-06-20 05:16:31,925 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620051631909584
2019-06-20 05:16:31,947 [salt.minion      :1432][INFO    ][6675] Starting a new job with PID 6675
2019-06-20 05:16:31,972 [salt.minion      :1711][INFO    ][6675] Returning information for job: 20190620051631909584
2019-06-20 05:16:55,388 [salt.loaded.ext.module.maas:1023][INFO    ][6601] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.75236297s left)
2019-06-20 05:17:01,961 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620051701948853
2019-06-20 05:17:01,982 [salt.minion      :1432][INFO    ][6816] Starting a new job with PID 6816
2019-06-20 05:17:02,004 [salt.minion      :1711][INFO    ][6816] Returning information for job: 20190620051701948853
2019-06-20 05:17:26,633 [salt.loaded.ext.module.maas:1023][INFO    ][6601] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.50727105s left)
2019-06-20 05:17:32,014 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620051731996693
2019-06-20 05:17:32,036 [salt.minion      :1432][INFO    ][6961] Starting a new job with PID 6961
2019-06-20 05:17:32,059 [salt.minion      :1711][INFO    ][6961] Returning information for job: 20190620051731996693
2019-06-20 05:17:58,424 [salt.loaded.ext.module.maas:1023][INFO    ][6601] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.71649504s left)
2019-06-20 05:18:02,078 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620051802065653
2019-06-20 05:18:02,100 [salt.minion      :1432][INFO    ][7127] Starting a new job with PID 7127
2019-06-20 05:18:02,125 [salt.minion      :1711][INFO    ][7127] Returning information for job: 20190620051802065653
2019-06-20 05:18:30,141 [salt.loaded.ext.module.maas:1023][INFO    ][6601] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.99962783s left)
2019-06-20 05:18:32,136 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620051832120945
2019-06-20 05:18:32,158 [salt.minion      :1432][INFO    ][7732] Starting a new job with PID 7732
2019-06-20 05:18:32,182 [salt.minion      :1711][INFO    ][7732] Returning information for job: 20190620051832120945
2019-06-20 05:19:02,200 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620051902187825
2019-06-20 05:19:02,218 [salt.minion      :1432][INFO    ][7903] Starting a new job with PID 7903
2019-06-20 05:19:02,239 [salt.minion      :1711][INFO    ][7903] Returning information for job: 20190620051902187825
2019-06-20 05:19:03,671 [salt.state       :300 ][INFO    ][6601] {'ret': True}
2019-06-20 05:19:03,672 [salt.state       :1951][INFO    ][6601] Completed state [maas.wait_for_machine_status] at time 05:19:03.672016 duration_in_ms=191536.268
2019-06-20 05:19:03,676 [salt.minion      :1711][INFO    ][6601] Returning information for job: 20190620051546779949
2019-06-20 05:19:04,294 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command state.apply with jid 20190620051904279016
2019-06-20 05:19:04,316 [salt.minion      :1432][INFO    ][7912] Starting a new job with PID 7912
2019-06-20 05:19:08,252 [salt.state       :915 ][INFO    ][7912] Loading fresh modules for state activity
2019-06-20 05:19:08,302 [salt.fileclient  :1219][INFO    ][7912] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-20 05:19:08,391 [salt.state       :1780][INFO    ][7912] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:08.391806
2019-06-20 05:19:08,392 [salt.state       :1813][INFO    ][7912] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-20 05:19:08,393 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7912] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-20 05:19:09,855 [salt.state       :300 ][INFO    ][7912] {'pid': 7936, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-20 05:19:09,856 [salt.state       :1951][INFO    ][7912] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:09.856027 duration_in_ms=1464.222
2019-06-20 05:19:09,857 [salt.state       :1780][INFO    ][7912] Running state [maas_machines_storage_cmp002_lvm] at time 05:19:09.857491
2019-06-20 05:19:09,857 [salt.state       :1813][INFO    ][7912] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-20 05:19:11,279 [salt.loaded.ext.module.maasng:610 ][INFO    ][7912] ycwp37
2019-06-20 05:19:11,280 [salt.loaded.ext.module.maasng:626 ][INFO    ][7912] sda
2019-06-20 05:19:11,832 [salt.loaded.ext.module.maasng:361 ][INFO    ][7912] ycwp37
2019-06-20 05:19:11,963 [salt.loaded.ext.module.maasng:367 ][INFO    ][7912] [{u'resource_uri': u'/MAAS/api/2.0/nodes/ycwp37/blockdevices/5/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'b3ca1bb0-ef32-4d36-8c24-e945e4d82c57', u'resource_uri': u'/MAAS/api/2.0/nodes/ycwp37/blockdevices/5/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ycwp37', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'uuid': u'11cd69d3-62c6-49e7-b1cd-306d04abb683'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'device_id': 5}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ycwp37', 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'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, {u'resource_uri': u'/MAAS/api/2.0/nodes/ycwp37/blockdevices/11/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'uuid': u'4ff45917-33e6-45d2-b372-a7c1a29ba621', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ycwp37', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'uuid': u'3f098297-6f2a-478e-89ea-a60ad0af15e5'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'serial': None, u'size': 2397988454400}]
2019-06-20 05:19:11,963 [salt.loaded.ext.module.maasng:632 ][INFO    ][7912] vgroot
2019-06-20 05:19:11,964 [salt.loaded.ext.module.maasng:635 ][INFO    ][7912] lvroot
2019-06-20 05:19:11,964 [salt.loaded.ext.module.maasng:639 ][INFO    ][7912] 107374182400
2019-06-20 05:19:12,701 [salt.loaded.ext.module.maasng:645 ][INFO    ][7912] {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'testing_status_name': u'Passed', u'memory_test_status': -1, 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'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 5, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ycwp37', 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'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'321eb01c-c40e-43b6-bb72-2f298bfe395c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ycwp37', u'device_id': 5, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'c9c90725-199f-4f24-abc0-4b006f151890', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/ycwp37/blockdevices/5/partition/7'}], u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/ycwp37/blockdevices/5/'}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'owner_data': {}, u'system_id': u'ycwp37', 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"From 'Testing' to 'Ready'", u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 12, u'uuid': u'7f35468a-5082-4068-acd9-4f7747b337db', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ycwp37', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'7beebfb9-b70c-49bd-a074-257f2a4af796', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'partitions': [], u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ycwp37/blockdevices/12/'}], u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 5, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ycwp37', 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'uuid': u'321eb01c-c40e-43b6-bb72-2f298bfe395c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ycwp37', u'device_id': 5, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'c9c90725-199f-4f24-abc0-4b006f151890', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/ycwp37/blockdevices/5/partition/7'}], u'type': u'physical', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/ycwp37/blockdevices/5/'}, {u'size': 107374182400, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 12, u'uuid': u'7f35468a-5082-4068-acd9-4f7747b337db', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ycwp37', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'7beebfb9-b70c-49bd-a074-257f2a4af796', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ycwp37/blockdevices/12/'}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 5, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ycwp37', 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'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'321eb01c-c40e-43b6-bb72-2f298bfe395c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ycwp37', u'device_id': 5, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'c9c90725-199f-4f24-abc0-4b006f151890', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/ycwp37/blockdevices/5/partition/7'}], u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/ycwp37/blockdevices/5/'}], u'ip_addresses': [u'192.168.11.42'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ycwp37', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6c7seh', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 39, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6c7seh', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'parents': [], u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6c7seh', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'effective_mtu': 1500, u'system_id': u'ycwp37', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ycwp37/interfaces/4/'}, u'interface_set': [{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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6c7seh', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 39, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6c7seh', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'parents': [], u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6c7seh', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'effective_mtu': 1500, u'system_id': u'ycwp37', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ycwp37/interfaces/4/'}, {u'name': u'enp8s0', u'links': [{u'id': 40, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6c', u'params': u'', u'effective_mtu': 1500, u'system_id': u'ycwp37', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/ycwp37/interfaces/21/'}, {u'name': u'enp9s0', u'links': [{u'id': 41, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6d', u'params': u'', u'effective_mtu': 1500, u'system_id': u'ycwp37', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/ycwp37/interfaces/22/'}, {u'name': u'enp7s0', u'links': [{u'id': 42, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6b', u'params': u'', u'effective_mtu': 1500, u'system_id': u'ycwp37', u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/ycwp37/interfaces/23/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/ycwp37/'}
2019-06-20 05:19:12,703 [salt.state       :300 ][INFO    ][7912] {'new': {'storage_layout': 'lvm'}}
2019-06-20 05:19:12,703 [salt.state       :1951][INFO    ][7912] Completed state [maas_machines_storage_cmp002_lvm] at time 05:19:12.703752 duration_in_ms=2846.259
2019-06-20 05:19:12,704 [salt.state       :1780][INFO    ][7912] Running state [maas_machines_storage_cmp001_lvm] at time 05:19:12.704261
2019-06-20 05:19:12,704 [salt.state       :1813][INFO    ][7912] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-20 05:19:14,095 [salt.loaded.ext.module.maasng:610 ][INFO    ][7912] aabbtx
2019-06-20 05:19:14,095 [salt.loaded.ext.module.maasng:626 ][INFO    ][7912] sda
2019-06-20 05:19:14,584 [salt.loaded.ext.module.maasng:361 ][INFO    ][7912] aabbtx
2019-06-20 05:19:14,675 [salt.loaded.ext.module.maasng:367 ][INFO    ][7912] [{u'resource_uri': u'/MAAS/api/2.0/nodes/aabbtx/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'98a193d2-cbb4-4833-a157-be42216ca9a1', u'resource_uri': u'/MAAS/api/2.0/nodes/aabbtx/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'aabbtx', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'uuid': u'7217ca73-e922-42b4-846e-88efdb75eb0e'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 1, u'device_id': 1}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'aabbtx', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'resource_uri': u'/MAAS/api/2.0/nodes/aabbtx/blockdevices/6/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'uuid': u'16875250-ea89-492e-9731-0f1d4fc10302', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'aabbtx', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'uuid': u'f7c4620f-a24e-46c3-a668-db1609911618'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 6, u'serial': None, u'size': 2397988454400}]
2019-06-20 05:19:14,676 [salt.loaded.ext.module.maasng:632 ][INFO    ][7912] vgroot
2019-06-20 05:19:14,676 [salt.loaded.ext.module.maasng:635 ][INFO    ][7912] lvroot
2019-06-20 05:19:14,677 [salt.loaded.ext.module.maasng:639 ][INFO    ][7912] 107374182400
2019-06-20 05:19:15,402 [salt.loaded.ext.module.maasng:645 ][INFO    ][7912] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'6c7seh', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'6c7seh', 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': 16, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5a', u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'6c7seh', 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'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'aabbtx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/aabbtx/interfaces/5/'}, u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'aabbtx', u'raids': [], 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'blockdevice_set': [{u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/aabbtx/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'aabbtx', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'1e2bb6d3-5c51-4ff8-b481-800d591126a9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'aabbtx', u'device_id': 1, u'filesystem': {u'uuid': u'942f16d5-be58-4583-8106-367e02e9d6a8', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/aabbtx/blockdevices/1/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}]}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/aabbtx/blockdevices/13/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'1ed4de3b-e402-4ed4-9835-3b5465ef71c8', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'aabbtx', u'partition_table_type': None, u'filesystem': {u'uuid': u'fb21ad50-8e6d-4371-b0fa-ff4b515bf73b', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 13, u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 2397998940160, u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/aabbtx/blockdevices/1/', u'used_size': 2397998940160, u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'aabbtx', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'1e2bb6d3-5c51-4ff8-b481-800d591126a9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'aabbtx', u'device_id': 1, u'filesystem': {u'uuid': u'942f16d5-be58-4583-8106-367e02e9d6a8', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/aabbtx/blockdevices/1/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'aabbtx', u'id': 8}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/aabbtx/blockdevices/13/', u'used_size': 107374182400, u'tags': [], u'uuid': u'1ed4de3b-e402-4ed4-9835-3b5465ef71c8', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'aabbtx', u'partition_table_type': None, u'filesystem': {u'uuid': u'fb21ad50-8e6d-4371-b0fa-ff4b515bf73b', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 13, u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_disk': {u'size': 2397998940160, u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/aabbtx/blockdevices/1/', u'used_size': 2397998940160, u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'aabbtx', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'1e2bb6d3-5c51-4ff8-b481-800d591126a9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'aabbtx', u'device_id': 1, u'filesystem': {u'uuid': u'942f16d5-be58-4583-8106-367e02e9d6a8', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/aabbtx/blockdevices/1/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}]}, u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'6c7seh', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'6c7seh', 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': 16, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5a', u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'6c7seh', 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'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'aabbtx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/aabbtx/interfaces/5/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp8s0', u'links': [{u'id': 17, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5c', u'enabled': True, u'id': 9, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'aabbtx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/aabbtx/interfaces/9/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp7s0', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5b', u'enabled': True, u'id': 10, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'aabbtx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/aabbtx/interfaces/10/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp9s0', u'links': [{u'id': 22, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5d', u'enabled': True, u'id': 11, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'aabbtx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/aabbtx/interfaces/11/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/aabbtx/', u'distro_series': u'', u'memory_test_status': -1}
2019-06-20 05:19:15,404 [salt.state       :300 ][INFO    ][7912] {'new': {'storage_layout': 'lvm'}}
2019-06-20 05:19:15,405 [salt.state       :1951][INFO    ][7912] Completed state [maas_machines_storage_cmp001_lvm] at time 05:19:15.404952 duration_in_ms=2700.691
2019-06-20 05:19:15,408 [salt.minion      :1711][INFO    ][7912] Returning information for job: 20190620051904279016
2019-06-20 05:19:16,042 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command state.apply with jid 20190620051916025615
2019-06-20 05:19:16,064 [salt.minion      :1432][INFO    ][8020] Starting a new job with PID 8020
2019-06-20 05:19:16,770 [salt.state       :915 ][INFO    ][8020] Loading fresh modules for state activity
2019-06-20 05:19:16,821 [salt.fileclient  :1219][INFO    ][8020] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-20 05:19:16,865 [salt.state       :1780][INFO    ][8020] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:16.864994
2019-06-20 05:19:16,865 [salt.state       :1813][INFO    ][8020] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-20 05:19:16,867 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8020] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-20 05:19:18,311 [salt.state       :300 ][INFO    ][8020] {'pid': 8034, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-20 05:19:18,312 [salt.state       :1951][INFO    ][8020] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:18.312201 duration_in_ms=1447.207
2019-06-20 05:19:18,314 [salt.state       :1780][INFO    ][8020] Running state [maas.deploy_machines] at time 05:19:18.314849
2019-06-20 05:19:18,315 [salt.state       :1813][INFO    ][8020] Executing state module.run for [maas.deploy_machines]
2019-06-20 05:19:18,316 [salt.utils.decorators:613 ][WARNING ][8020] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 05:19:19,055 [salt.loaded.ext.module.maas:684 ][INFO    ][8020] deploymachines hwe_kernel=hwe-16.04 system_id=ycwp37 distro_series=xenial
2019-06-20 05:19:21,384 [salt.loaded.ext.module.maas:684 ][INFO    ][8020] deploymachines hwe_kernel=hwe-16.04 system_id=aabbtx distro_series=xenial
2019-06-20 05:19:23,978 [salt.loaded.ext.module.maas:684 ][INFO    ][8020] deploymachines hwe_kernel=hwe-16.04 system_id=y3sg6a distro_series=xenial
2019-06-20 05:19:26,566 [salt.loaded.ext.module.maas:684 ][INFO    ][8020] deploymachines hwe_kernel=hwe-16.04 system_id=xkchct distro_series=xenial
2019-06-20 05:19:29,094 [salt.loaded.ext.module.maas:684 ][INFO    ][8020] deploymachines hwe_kernel=hwe-16.04 system_id=dq8hnn distro_series=xenial
2019-06-20 05:19:31,154 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620051931141588
2019-06-20 05:19:31,175 [salt.minion      :1432][INFO    ][8286] Starting a new job with PID 8286
2019-06-20 05:19:31,199 [salt.minion      :1711][INFO    ][8286] Returning information for job: 20190620051931141588
2019-06-20 05:19:31,466 [salt.state       :300 ][INFO    ][8020] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-20 05:19:31,466 [salt.state       :1951][INFO    ][8020] Completed state [maas.deploy_machines] at time 05:19:31.466591 duration_in_ms=13151.741
2019-06-20 05:19:31,470 [salt.minion      :1711][INFO    ][8020] Returning information for job: 20190620051916025615
2019-06-20 05:19:32,128 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command state.apply with jid 20190620051932112461
2019-06-20 05:19:32,151 [salt.minion      :1432][INFO    ][8300] Starting a new job with PID 8300
2019-06-20 05:19:35,949 [salt.state       :915 ][INFO    ][8300] Loading fresh modules for state activity
2019-06-20 05:19:36,001 [salt.fileclient  :1219][INFO    ][8300] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-20 05:19:36,042 [salt.state       :1780][INFO    ][8300] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:36.042579
2019-06-20 05:19:36,042 [salt.state       :1813][INFO    ][8300] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-20 05:19:36,044 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8300] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-20 05:19:37,480 [salt.state       :300 ][INFO    ][8300] {'pid': 8319, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-20 05:19:37,480 [salt.state       :1951][INFO    ][8300] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:37.480459 duration_in_ms=1437.881
2019-06-20 05:19:37,482 [salt.state       :1780][INFO    ][8300] Running state [maas.wait_for_machine_status] at time 05:19:37.482017
2019-06-20 05:19:37,482 [salt.state       :1813][INFO    ][8300] Executing state module.run for [maas.wait_for_machine_status]
2019-06-20 05:19:37,482 [salt.utils.decorators:613 ][WARNING ][8300] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 05:19:40,848 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.63925505s left)
2019-06-20 05:19:47,247 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620051947226211
2019-06-20 05:19:47,270 [salt.minion      :1432][INFO    ][8330] Starting a new job with PID 8330
2019-06-20 05:19:47,294 [salt.minion      :1711][INFO    ][8330] Returning information for job: 20190620051947226211
2019-06-20 05:20:14,446 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.04052401s left)
2019-06-20 05:20:17,305 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620052017291231
2019-06-20 05:20:17,327 [salt.minion      :1432][INFO    ][8385] Starting a new job with PID 8385
2019-06-20 05:20:17,352 [salt.minion      :1711][INFO    ][8385] Returning information for job: 20190620052017291231
2019-06-20 05:20:47,349 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620052047337048
2019-06-20 05:20:47,370 [salt.minion      :1432][INFO    ][8404] Starting a new job with PID 8404
2019-06-20 05:20:47,387 [salt.minion      :1711][INFO    ][8404] Returning information for job: 20190620052047337048
2019-06-20 05:20:47,749 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.73821521s left)
2019-06-20 05:21:17,385 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620052117376417
2019-06-20 05:21:17,405 [salt.minion      :1432][INFO    ][8575] Starting a new job with PID 8575
2019-06-20 05:21:17,427 [salt.minion      :1711][INFO    ][8575] Returning information for job: 20190620052117376417
2019-06-20 05:21:21,062 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.42487001s left)
2019-06-20 05:21:47,433 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620052147420647
2019-06-20 05:21:47,455 [salt.minion      :1432][INFO    ][8720] Starting a new job with PID 8720
2019-06-20 05:21:47,478 [salt.minion      :1711][INFO    ][8720] Returning information for job: 20190620052147420647
2019-06-20 05:21:54,219 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.26798916s left)
2019-06-20 05:22:17,484 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620052217470447
2019-06-20 05:22:17,505 [salt.minion      :1432][INFO    ][9536] Starting a new job with PID 9536
2019-06-20 05:22:17,530 [salt.minion      :1711][INFO    ][9536] Returning information for job: 20190620052217470447
2019-06-20 05:22:27,569 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.91743517s left)
2019-06-20 05:22:47,540 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620052247525834
2019-06-20 05:22:47,562 [salt.minion      :1432][INFO    ][9557] Starting a new job with PID 9557
2019-06-20 05:22:47,587 [salt.minion      :1711][INFO    ][9557] Returning information for job: 20190620052247525834
2019-06-20 05:23:01,200 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.28689909s left)
2019-06-20 05:23:17,593 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620052317581119
2019-06-20 05:23:17,615 [salt.minion      :1432][INFO    ][9698] Starting a new job with PID 9698
2019-06-20 05:23:17,637 [salt.minion      :1711][INFO    ][9698] Returning information for job: 20190620052317581119
2019-06-20 05:23:34,456 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.0310092s left)
2019-06-20 05:23:47,655 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620052347640362
2019-06-20 05:23:47,678 [salt.minion      :1432][INFO    ][9723] Starting a new job with PID 9723
2019-06-20 05:23:47,700 [salt.minion      :1711][INFO    ][9723] Returning information for job: 20190620052347640362
2019-06-20 05:24:07,446 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.04108214s left)
2019-06-20 05:24:17,725 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620052417709258
2019-06-20 05:24:17,747 [salt.minion      :1432][INFO    ][10331] Starting a new job with PID 10331
2019-06-20 05:24:17,769 [salt.minion      :1711][INFO    ][10331] Returning information for job: 20190620052417709258
2019-06-20 05:24:40,973 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.5142312s left)
2019-06-20 05:24:47,794 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620052447781274
2019-06-20 05:24:47,816 [salt.minion      :1432][INFO    ][10385] Starting a new job with PID 10385
2019-06-20 05:24:47,840 [salt.minion      :1711][INFO    ][10385] Returning information for job: 20190620052447781274
2019-06-20 05:25:13,142 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1914.34475303s left)
2019-06-20 05:25:17,864 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620052517851351
2019-06-20 05:25:17,886 [salt.minion      :1432][INFO    ][10743] Starting a new job with PID 10743
2019-06-20 05:25:17,911 [salt.minion      :1711][INFO    ][10743] Returning information for job: 20190620052517851351
2019-06-20 05:25:46,383 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1881.10439801s left)
2019-06-20 05:25:47,942 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620052547930416
2019-06-20 05:25:47,964 [salt.minion      :1432][INFO    ][10763] Starting a new job with PID 10763
2019-06-20 05:25:47,988 [salt.minion      :1711][INFO    ][10763] Returning information for job: 20190620052547930416
2019-06-20 05:26:18,024 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620052618012643
2019-06-20 05:26:18,043 [salt.minion      :1432][INFO    ][10842] Starting a new job with PID 10842
2019-06-20 05:26:18,064 [salt.minion      :1711][INFO    ][10842] Returning information for job: 20190620052618012643
2019-06-20 05:26:19,754 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1847.7332921s left)
2019-06-20 05:26:48,230 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620052648217876
2019-06-20 05:26:48,253 [salt.minion      :1432][INFO    ][10988] Starting a new job with PID 10988
2019-06-20 05:26:48,277 [salt.minion      :1711][INFO    ][10988] Returning information for job: 20190620052648217876
2019-06-20 05:26:53,400 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1814.08649516s left)
2019-06-20 05:27:18,256 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620052718243298
2019-06-20 05:27:18,278 [salt.minion      :1432][INFO    ][11509] Starting a new job with PID 11509
2019-06-20 05:27:18,303 [salt.minion      :1711][INFO    ][11509] Returning information for job: 20190620052718243298
2019-06-20 05:27:26,989 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1780.4981792s left)
2019-06-20 05:27:48,359 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620052748346654
2019-06-20 05:27:48,382 [salt.minion      :1432][INFO    ][11528] Starting a new job with PID 11528
2019-06-20 05:27:48,405 [salt.minion      :1711][INFO    ][11528] Returning information for job: 20190620052748346654
2019-06-20 05:28:00,672 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1746.8146162s left)
2019-06-20 05:28:18,505 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620052818493172
2019-06-20 05:28:18,527 [salt.minion      :1432][INFO    ][11645] Starting a new job with PID 11645
2019-06-20 05:28:18,551 [salt.minion      :1711][INFO    ][11645] Returning information for job: 20190620052818493172
2019-06-20 05:28:34,242 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1713.24524713s left)
2019-06-20 05:28:48,613 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620052848600627
2019-06-20 05:28:48,636 [salt.minion      :1432][INFO    ][11664] Starting a new job with PID 11664
2019-06-20 05:28:48,660 [salt.minion      :1711][INFO    ][11664] Returning information for job: 20190620052848600627
2019-06-20 05:29:07,589 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1679.89769506s left)
2019-06-20 05:29:18,743 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620052918729973
2019-06-20 05:29:18,766 [salt.minion      :1432][INFO    ][11720] Starting a new job with PID 11720
2019-06-20 05:29:18,791 [salt.minion      :1711][INFO    ][11720] Returning information for job: 20190620052918729973
2019-06-20 05:29:41,050 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1646.43698215s left)
2019-06-20 05:29:48,864 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620052948852183
2019-06-20 05:29:48,887 [salt.minion      :1432][INFO    ][11740] Starting a new job with PID 11740
2019-06-20 05:29:48,911 [salt.minion      :1711][INFO    ][11740] Returning information for job: 20190620052948852183
2019-06-20 05:30:14,464 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1613.02287102s left)
2019-06-20 05:30:19,010 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620053018996954
2019-06-20 05:30:19,033 [salt.minion      :1432][INFO    ][11795] Starting a new job with PID 11795
2019-06-20 05:30:19,058 [salt.minion      :1711][INFO    ][11795] Returning information for job: 20190620053018996954
2019-06-20 05:30:48,237 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1579.24990821s left)
2019-06-20 05:30:49,171 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620053049154813
2019-06-20 05:30:49,193 [salt.minion      :1432][INFO    ][11815] Starting a new job with PID 11815
2019-06-20 05:30:49,217 [salt.minion      :1711][INFO    ][11815] Returning information for job: 20190620053049154813
2019-06-20 05:31:19,327 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620053119314703
2019-06-20 05:31:19,350 [salt.minion      :1432][INFO    ][11871] Starting a new job with PID 11871
2019-06-20 05:31:19,373 [salt.minion      :1711][INFO    ][11871] Returning information for job: 20190620053119314703
2019-06-20 05:31:21,745 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1545.74166012s left)
2019-06-20 05:31:49,489 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620053149473795
2019-06-20 05:31:49,512 [salt.minion      :1432][INFO    ][11899] Starting a new job with PID 11899
2019-06-20 05:31:49,535 [salt.minion      :1711][INFO    ][11899] Returning information for job: 20190620053149473795
2019-06-20 05:31:55,237 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1512.2494812s left)
2019-06-20 05:32:19,668 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620053219656011
2019-06-20 05:32:19,691 [salt.minion      :1432][INFO    ][11956] Starting a new job with PID 11956
2019-06-20 05:32:19,715 [salt.minion      :1711][INFO    ][11956] Returning information for job: 20190620053219656011
2019-06-20 05:32:28,824 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1478.66296101s left)
2019-06-20 05:32:49,853 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620053249840117
2019-06-20 05:32:49,875 [salt.minion      :1432][INFO    ][11976] Starting a new job with PID 11976
2019-06-20 05:32:49,900 [salt.minion      :1711][INFO    ][11976] Returning information for job: 20190620053249840117
2019-06-20 05:33:02,309 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1445.17765903s left)
2019-06-20 05:33:20,050 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620053320036160
2019-06-20 05:33:20,072 [salt.minion      :1432][INFO    ][12032] Starting a new job with PID 12032
2019-06-20 05:33:20,096 [salt.minion      :1711][INFO    ][12032] Returning information for job: 20190620053320036160
2019-06-20 05:33:35,801 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1411.68621612s left)
2019-06-20 05:33:50,258 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620053350246368
2019-06-20 05:33:50,281 [salt.minion      :1432][INFO    ][12052] Starting a new job with PID 12052
2019-06-20 05:33:50,304 [salt.minion      :1711][INFO    ][12052] Returning information for job: 20190620053350246368
2019-06-20 05:34:09,079 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1378.40838218s left)
2019-06-20 05:34:20,479 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620053420466459
2019-06-20 05:34:20,502 [salt.minion      :1432][INFO    ][12250] Starting a new job with PID 12250
2019-06-20 05:34:20,527 [salt.minion      :1711][INFO    ][12250] Returning information for job: 20190620053420466459
2019-06-20 05:34:42,697 [salt.loaded.ext.module.maas:1023][INFO    ][8300] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1344.78990006s left)
2019-06-20 05:34:50,503 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620053450490463
2019-06-20 05:34:50,522 [salt.minion      :1432][INFO    ][12282] Starting a new job with PID 12282
2019-06-20 05:34:50,544 [salt.minion      :1711][INFO    ][12282] Returning information for job: 20190620053450490463
2019-06-20 05:35:15,381 [salt.loaded.ext.module.maas:993 ][INFO    ][8300] Machine xkchct mark broken
2019-06-20 05:35:16,138 [salt.loaded.ext.module.maas:996 ][INFO    ][8300] Machine xkchct mark fixed
2019-06-20 05:35:17,383 [salt.loaded.ext.module.maas:684 ][INFO    ][8300] deploymachines hwe_kernel=hwe-16.04 system_id=xkchct distro_series=xenial
2019-06-20 05:35:20,071 [salt.loaded.ext.module.maas:160 ][ERROR   ][8300] Failed for object kvm03 reason Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node.
2019-06-20 05:35:20,073 [salt.state       :302 ][ERROR   ][8300] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm02'], 'errors': {'kvm03': "Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node."}, 'success': []}
2019-06-20 05:35:20,074 [salt.state       :1951][INFO    ][8300] Completed state [maas.wait_for_machine_status] at time 05:35:20.074215 duration_in_ms=942592.193
2019-06-20 05:35:20,082 [salt.minion      :1711][INFO    ][8300] Returning information for job: 20190620051932112461
2019-06-20 05:35:30,881 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command pillar.get with jid 20190620053530869959
2019-06-20 05:35:30,903 [salt.minion      :1432][INFO    ][12409] Starting a new job with PID 12409
2019-06-20 05:35:30,910 [salt.minion      :1711][INFO    ][12409] Returning information for job: 20190620053530869959
2019-06-20 05:35:31,545 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command service.status with jid 20190620053531532113
2019-06-20 05:35:31,566 [salt.minion      :1432][INFO    ][12421] Starting a new job with PID 12421
2019-06-20 05:35:31,945 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12421] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-20 05:35:31,976 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12421] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-20 05:35:31,990 [salt.minion      :1711][INFO    ][12421] Returning information for job: 20190620053531532113
2019-06-20 05:35:32,482 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command state.apply with jid 20190620053532469904
2019-06-20 05:35:32,503 [salt.minion      :1432][INFO    ][12432] Starting a new job with PID 12432
2019-06-20 05:35:36,267 [salt.state       :915 ][INFO    ][12432] Loading fresh modules for state activity
2019-06-20 05:35:36,671 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12432] Executing command 'salt-minion --version' in directory '/root'
2019-06-20 05:35:37,048 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12432] Executing command 'salt-minion --version' in directory '/root'
2019-06-20 05:35:37,890 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12432] Executing command 'salt-minion --version' in directory '/root'
2019-06-20 05:35:38,250 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12432] Executing command 'salt-minion --version' in directory '/root'
2019-06-20 05:35:39,585 [salt.state       :1780][INFO    ][12432] Running state [salt-minion] at time 05:35:39.585233
2019-06-20 05:35:39,585 [salt.state       :1813][INFO    ][12432] Executing state pkg.installed for [salt-minion]
2019-06-20 05:35:39,586 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12432] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-20 05:35:39,680 [salt.state       :300 ][INFO    ][12432] All specified packages are already installed
2019-06-20 05:35:39,681 [salt.state       :1951][INFO    ][12432] Completed state [salt-minion] at time 05:35:39.681361 duration_in_ms=96.128
2019-06-20 05:35:39,681 [salt.state       :1780][INFO    ][12432] Running state [salt_minion_dependency_packages] at time 05:35:39.681774
2019-06-20 05:35:39,682 [salt.state       :1813][INFO    ][12432] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-20 05:35:39,689 [salt.state       :300 ][INFO    ][12432] All specified packages are already installed
2019-06-20 05:35:39,690 [salt.state       :1951][INFO    ][12432] Completed state [salt_minion_dependency_packages] at time 05:35:39.689987 duration_in_ms=8.213
2019-06-20 05:35:39,693 [salt.state       :1780][INFO    ][12432] Running state [/etc/salt/minion.d/minion.conf] at time 05:35:39.693563
2019-06-20 05:35:39,693 [salt.state       :1813][INFO    ][12432] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-20 05:35:39,888 [salt.state       :300 ][INFO    ][12432] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-20 05:35:39,888 [salt.state       :1951][INFO    ][12432] Completed state [/etc/salt/minion.d/minion.conf] at time 05:35:39.888345 duration_in_ms=194.783
2019-06-20 05:35:39,888 [salt.state       :1780][INFO    ][12432] Running state [python-netaddr] at time 05:35:39.888544
2019-06-20 05:35:39,888 [salt.state       :1813][INFO    ][12432] Executing state pkg.installed for [python-netaddr]
2019-06-20 05:35:39,893 [salt.state       :300 ][INFO    ][12432] All specified packages are already installed
2019-06-20 05:35:39,893 [salt.state       :1951][INFO    ][12432] Completed state [python-netaddr] at time 05:35:39.893207 duration_in_ms=4.663
2019-06-20 05:35:39,895 [salt.state       :1780][INFO    ][12432] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:35:39.895236
2019-06-20 05:35:39,895 [salt.state       :1813][INFO    ][12432] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-20 05:35:39,905 [salt.state       :300 ][INFO    ][12432] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-20 05:35:39,905 [salt.state       :1951][INFO    ][12432] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:35:39.905310 duration_in_ms=10.074
2019-06-20 05:35:39,905 [salt.state       :1780][INFO    ][12432] Running state [salt-minion] at time 05:35:39.905949
2019-06-20 05:35:39,906 [salt.state       :1813][INFO    ][12432] Executing state service.running for [salt-minion]
2019-06-20 05:35:39,906 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12432] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-20 05:35:39,940 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12432] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-20 05:35:39,955 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12432] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-20 05:35:39,971 [salt.state       :300 ][INFO    ][12432] The service salt-minion is already running
2019-06-20 05:35:39,971 [salt.state       :1951][INFO    ][12432] Completed state [salt-minion] at time 05:35:39.971855 duration_in_ms=65.906
2019-06-20 05:35:39,973 [salt.state       :1780][INFO    ][12432] Running state [/etc/salt/grains.d] at time 05:35:39.973117
2019-06-20 05:35:39,973 [salt.state       :1813][INFO    ][12432] Executing state file.directory for [/etc/salt/grains.d]
2019-06-20 05:35:39,974 [salt.state       :300 ][INFO    ][12432] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-20 05:35:39,974 [salt.state       :1951][INFO    ][12432] Completed state [/etc/salt/grains.d] at time 05:35:39.974412 duration_in_ms=1.295
2019-06-20 05:35:39,975 [salt.state       :1780][INFO    ][12432] Running state [/etc/salt/grains] at time 05:35:39.974986
2019-06-20 05:35:39,975 [salt.state       :1813][INFO    ][12432] Executing state file.managed for [/etc/salt/grains]
2019-06-20 05:35:39,975 [salt.state       :300 ][INFO    ][12432] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-20 05:35:39,975 [salt.state       :1951][INFO    ][12432] Completed state [/etc/salt/grains] at time 05:35:39.975878 duration_in_ms=0.892
2019-06-20 05:35:39,976 [salt.state       :1780][INFO    ][12432] Running state [/etc/salt/grains.d/placeholder] at time 05:35:39.976261
2019-06-20 05:35:39,976 [salt.state       :1813][INFO    ][12432] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-20 05:35:39,976 [salt.state       :300 ][INFO    ][12432] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-20 05:35:39,977 [salt.state       :1951][INFO    ][12432] Completed state [/etc/salt/grains.d/placeholder] at time 05:35:39.977116 duration_in_ms=0.855
2019-06-20 05:35:39,977 [salt.state       :1780][INFO    ][12432] Running state [/etc/salt/grains.d/sphinx] at time 05:35:39.977500
2019-06-20 05:35:39,977 [salt.state       :1813][INFO    ][12432] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-20 05:35:39,989 [salt.state       :300 ][INFO    ][12432] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-20 05:35:39,990 [salt.state       :1951][INFO    ][12432] Completed state [/etc/salt/grains.d/sphinx] at time 05:35:39.990047 duration_in_ms=12.547
2019-06-20 05:35:39,991 [salt.state       :1780][INFO    ][12432] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:35:39.991897
2019-06-20 05:35:39,992 [salt.state       :1813][INFO    ][12432] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-20 05:35:39,992 [salt.state       :300 ][INFO    ][12432] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-20 05:35:39,992 [salt.state       :1951][INFO    ][12432] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:35:39.992629 duration_in_ms=0.732
2019-06-20 05:35:39,993 [salt.state       :1780][INFO    ][12432] Running state [/etc/salt/grains.d/dns_records] at time 05:35:39.993014
2019-06-20 05:35:39,993 [salt.state       :1813][INFO    ][12432] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-20 05:35:40,007 [salt.state       :300 ][INFO    ][12432] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-20 05:35:40,007 [salt.state       :1951][INFO    ][12432] Completed state [/etc/salt/grains.d/dns_records] at time 05:35:40.007849 duration_in_ms=14.835
2019-06-20 05:35:40,008 [salt.state       :1780][INFO    ][12432] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:35:40.008621
2019-06-20 05:35:40,008 [salt.state       :1813][INFO    ][12432] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-20 05:35:40,009 [salt.state       :300 ][INFO    ][12432] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-20 05:35:40,009 [salt.state       :1951][INFO    ][12432] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:35:40.009335 duration_in_ms=0.714
2019-06-20 05:35:40,009 [salt.state       :1780][INFO    ][12432] Running state [/etc/salt/grains.d/salt] at time 05:35:40.009742
2019-06-20 05:35:40,010 [salt.state       :1813][INFO    ][12432] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-20 05:35:40,019 [salt.state       :300 ][INFO    ][12432] File /etc/salt/grains.d/salt is in the correct state
2019-06-20 05:35:40,019 [salt.state       :1951][INFO    ][12432] Completed state [/etc/salt/grains.d/salt] at time 05:35:40.019837 duration_in_ms=10.094
2019-06-20 05:35:40,020 [salt.state       :1780][INFO    ][12432] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:35:40.020568
2019-06-20 05:35:40,020 [salt.state       :1813][INFO    ][12432] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-20 05:35:40,021 [salt.state       :300 ][INFO    ][12432] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-20 05:35:40,021 [salt.state       :1951][INFO    ][12432] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:35:40.021284 duration_in_ms=0.716
2019-06-20 05:35:40,022 [salt.state       :1780][INFO    ][12432] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:35:40.022823
2019-06-20 05:35:40,023 [salt.state       :1813][INFO    ][12432] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-20 05:35:40,023 [salt.state       :300 ][INFO    ][12432] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-20 05:35:40,023 [salt.state       :1951][INFO    ][12432] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:35:40.023570 duration_in_ms=0.747
2019-06-20 05:35:40,024 [salt.state       :1780][INFO    ][12432] Running state [mine.update] at time 05:35:40.024133
2019-06-20 05:35:40,024 [salt.state       :1813][INFO    ][12432] Executing state module.wait for [mine.update]
2019-06-20 05:35:40,024 [salt.state       :300 ][INFO    ][12432] No changes made for mine.update
2019-06-20 05:35:40,024 [salt.state       :1951][INFO    ][12432] Completed state [mine.update] at time 05:35:40.024814 duration_in_ms=0.681
2019-06-20 05:35:40,025 [salt.state       :1780][INFO    ][12432] Running state [ca-certificates] at time 05:35:40.025021
2019-06-20 05:35:40,025 [salt.state       :1813][INFO    ][12432] Executing state pkg.installed for [ca-certificates]
2019-06-20 05:35:40,031 [salt.state       :300 ][INFO    ][12432] All specified packages are already installed
2019-06-20 05:35:40,031 [salt.state       :1951][INFO    ][12432] Completed state [ca-certificates] at time 05:35:40.031521 duration_in_ms=6.5
2019-06-20 05:35:40,032 [salt.state       :1780][INFO    ][12432] Running state [update-ca-certificates] at time 05:35:40.032094
2019-06-20 05:35:40,032 [salt.state       :1813][INFO    ][12432] Executing state cmd.wait for [update-ca-certificates]
2019-06-20 05:35:40,032 [salt.state       :300 ][INFO    ][12432] No changes made for update-ca-certificates
2019-06-20 05:35:40,032 [salt.state       :1951][INFO    ][12432] Completed state [update-ca-certificates] at time 05:35:40.032785 duration_in_ms=0.691
2019-06-20 05:35:40,033 [salt.state       :1780][INFO    ][12432] Running state [iptables] at time 05:35:40.032991
2019-06-20 05:35:40,033 [salt.state       :1813][INFO    ][12432] Executing state pkg.installed for [iptables]
2019-06-20 05:35:40,038 [salt.state       :300 ][INFO    ][12432] All specified packages are already installed
2019-06-20 05:35:40,038 [salt.state       :1951][INFO    ][12432] Completed state [iptables] at time 05:35:40.038836 duration_in_ms=5.845
2019-06-20 05:35:40,039 [salt.state       :1780][INFO    ][12432] Running state [iptables-persistent] at time 05:35:40.039042
2019-06-20 05:35:40,039 [salt.state       :1813][INFO    ][12432] Executing state pkg.installed for [iptables-persistent]
2019-06-20 05:35:40,044 [salt.state       :300 ][INFO    ][12432] All specified packages are already installed
2019-06-20 05:35:40,044 [salt.state       :1951][INFO    ][12432] Completed state [iptables-persistent] at time 05:35:40.044807 duration_in_ms=5.765
2019-06-20 05:35:40,045 [salt.state       :1780][INFO    ][12432] Running state [iptables_modules_v4_load] at time 05:35:40.045610
2019-06-20 05:35:40,045 [salt.state       :1813][INFO    ][12432] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-20 05:35:40,046 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12432] Executing command 'lsmod' in directory '/root'
2019-06-20 05:35:40,067 [salt.state       :300 ][INFO    ][12432] Kernel modules iptable_filter, ip_tables are already present
2019-06-20 05:35:40,067 [salt.state       :1951][INFO    ][12432] Completed state [iptables_modules_v4_load] at time 05:35:40.067856 duration_in_ms=22.247
2019-06-20 05:35:40,068 [salt.state       :1780][INFO    ][12432] Running state [/etc/iptables/rules.v4] at time 05:35:40.068360
2019-06-20 05:35:40,068 [salt.state       :1813][INFO    ][12432] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-20 05:35:40,145 [salt.state       :300 ][INFO    ][12432] File /etc/iptables/rules.v4 is in the correct state
2019-06-20 05:35:40,145 [salt.state       :1951][INFO    ][12432] Completed state [/etc/iptables/rules.v4] at time 05:35:40.145647 duration_in_ms=77.286
2019-06-20 05:35:40,146 [salt.state       :1780][INFO    ][12432] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:35:40.146381
2019-06-20 05:35:40,146 [salt.state       :1813][INFO    ][12432] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-20 05:35:40,147 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12432] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-20 05:35:40,164 [salt.state       :300 ][INFO    ][12432] onlyif execution failed
2019-06-20 05:35:40,164 [salt.state       :1951][INFO    ][12432] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:35:40.164654 duration_in_ms=18.273
2019-06-20 05:35:40,165 [salt.state       :1780][INFO    ][12432] Running state [netfilter-persistent] at time 05:35:40.165353
2019-06-20 05:35:40,165 [salt.state       :1813][INFO    ][12432] Executing state service.running for [netfilter-persistent]
2019-06-20 05:35:40,166 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12432] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-20 05:35:40,184 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12432] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-20 05:35:40,201 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12432] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-20 05:35:40,217 [salt.state       :300 ][INFO    ][12432] The service netfilter-persistent is already running
2019-06-20 05:35:40,218 [salt.state       :1951][INFO    ][12432] Completed state [netfilter-persistent] at time 05:35:40.218042 duration_in_ms=52.688
2019-06-20 05:35:40,218 [salt.state       :1780][INFO    ][12432] Running state [iptables_extra.remove_stale_tables] at time 05:35:40.218746
2019-06-20 05:35:40,219 [salt.state       :1813][INFO    ][12432] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-20 05:35:40,219 [salt.state       :300 ][INFO    ][12432] No changes made for iptables_extra.remove_stale_tables
2019-06-20 05:35:40,219 [salt.state       :1951][INFO    ][12432] Completed state [iptables_extra.remove_stale_tables] at time 05:35:40.219525 duration_in_ms=0.779
2019-06-20 05:35:40,219 [salt.state       :1780][INFO    ][12432] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:35:40.219747
2019-06-20 05:35:40,219 [salt.state       :1813][INFO    ][12432] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-20 05:35:40,220 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12432] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-20 05:35:40,234 [salt.state       :300 ][INFO    ][12432] onlyif execution failed
2019-06-20 05:35:40,234 [salt.state       :1951][INFO    ][12432] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:35:40.234607 duration_in_ms=14.86
2019-06-20 05:35:40,235 [salt.state       :1780][INFO    ][12432] Running state [/etc/iptables/rules.v6] at time 05:35:40.235421
2019-06-20 05:35:40,235 [salt.state       :1813][INFO    ][12432] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-20 05:35:40,236 [salt.state       :300 ][INFO    ][12432] File /etc/iptables/rules.v6 is not present
2019-06-20 05:35:40,236 [salt.state       :1951][INFO    ][12432] Completed state [/etc/iptables/rules.v6] at time 05:35:40.236313 duration_in_ms=0.892
2019-06-20 05:35:40,236 [salt.state       :1780][INFO    ][12432] Running state [iptables_extra.flush_all] at time 05:35:40.236886
2019-06-20 05:35:40,237 [salt.state       :1813][INFO    ][12432] Executing state module.wait for [iptables_extra.flush_all]
2019-06-20 05:35:40,237 [salt.state       :300 ][INFO    ][12432] No changes made for iptables_extra.flush_all
2019-06-20 05:35:40,237 [salt.state       :1951][INFO    ][12432] Completed state [iptables_extra.flush_all] at time 05:35:40.237591 duration_in_ms=0.705
2019-06-20 05:35:40,277 [salt.minion      :1711][INFO    ][12432] Returning information for job: 20190620053532469904
2019-06-20 05:35:40,863 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command state.apply with jid 20190620053540854227
2019-06-20 05:35:40,885 [salt.minion      :1432][INFO    ][12514] Starting a new job with PID 12514
2019-06-20 05:35:41,568 [salt.state       :915 ][INFO    ][12514] Loading fresh modules for state activity
2019-06-20 05:35:42,109 [salt.state       :1780][INFO    ][12514] Running state [maas-rack-controller] at time 05:35:42.109701
2019-06-20 05:35:42,110 [salt.state       :1813][INFO    ][12514] Executing state pkg.installed for [maas-rack-controller]
2019-06-20 05:35:42,110 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12514] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-20 05:35:42,192 [salt.state       :300 ][INFO    ][12514] All specified packages are already installed
2019-06-20 05:35:42,192 [salt.state       :1951][INFO    ][12514] Completed state [maas-rack-controller] at time 05:35:42.192416 duration_in_ms=82.714
2019-06-20 05:35:42,192 [salt.state       :1780][INFO    ][12514] Running state [ipmitool] at time 05:35:42.192724
2019-06-20 05:35:42,192 [salt.state       :1813][INFO    ][12514] Executing state pkg.installed for [ipmitool]
2019-06-20 05:35:42,198 [salt.state       :300 ][INFO    ][12514] All specified packages are already installed
2019-06-20 05:35:42,198 [salt.state       :1951][INFO    ][12514] Completed state [ipmitool] at time 05:35:42.198192 duration_in_ms=5.468
2019-06-20 05:35:42,200 [salt.state       :1780][INFO    ][12514] Running state [/etc/maas/rackd.conf] at time 05:35:42.200664
2019-06-20 05:35:42,200 [salt.state       :1813][INFO    ][12514] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-20 05:35:42,201 [salt.state       :300 ][INFO    ][12514] No changes needed to be made
2019-06-20 05:35:42,201 [salt.state       :1951][INFO    ][12514] Completed state [/etc/maas/rackd.conf] at time 05:35:42.201924 duration_in_ms=1.259
2019-06-20 05:35:42,202 [salt.state       :1780][INFO    ][12514] Running state [/etc/maas/rackd.conf] at time 05:35:42.202118
2019-06-20 05:35:42,202 [salt.state       :1813][INFO    ][12514] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-20 05:35:42,202 [salt.loaded.int.states.file:2298][WARNING ][12514] 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-06-20 05:35:42,203 [salt.state       :300 ][INFO    ][12514] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-20 05:35:42,203 [salt.state       :1951][INFO    ][12514] Completed state [/etc/maas/rackd.conf] at time 05:35:42.203129 duration_in_ms=1.011
2019-06-20 05:35:42,203 [salt.state       :1780][INFO    ][12514] Running state [maas-rackd] at time 05:35:42.203851
2019-06-20 05:35:42,204 [salt.state       :1813][INFO    ][12514] Executing state service.running for [maas-rackd]
2019-06-20 05:35:42,204 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12514] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-20 05:35:42,238 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12514] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-20 05:35:42,256 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12514] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-20 05:35:42,271 [salt.state       :300 ][INFO    ][12514] The service maas-rackd is already running
2019-06-20 05:35:42,271 [salt.state       :1951][INFO    ][12514] Completed state [maas-rackd] at time 05:35:42.271883 duration_in_ms=68.032
2019-06-20 05:35:42,273 [salt.minion      :1711][INFO    ][12514] Returning information for job: 20190620053540854227
2019-06-20 05:35:42,811 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command state.apply with jid 20190620053542804028
2019-06-20 05:35:42,832 [salt.minion      :1432][INFO    ][12538] Starting a new job with PID 12538
2019-06-20 05:35:43,611 [salt.state       :915 ][INFO    ][12538] Loading fresh modules for state activity
2019-06-20 05:35:44,388 [salt.state       :1780][INFO    ][12538] Running state [maas-region-controller] at time 05:35:44.388669
2019-06-20 05:35:44,389 [salt.state       :1813][INFO    ][12538] Executing state pkg.installed for [maas-region-controller]
2019-06-20 05:35:44,389 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12538] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-20 05:35:44,495 [salt.state       :300 ][INFO    ][12538] All specified packages are already installed
2019-06-20 05:35:44,496 [salt.state       :1951][INFO    ][12538] Completed state [maas-region-controller] at time 05:35:44.496121 duration_in_ms=107.452
2019-06-20 05:35:44,496 [salt.state       :1780][INFO    ][12538] Running state [python-oauth] at time 05:35:44.496534
2019-06-20 05:35:44,496 [salt.state       :1813][INFO    ][12538] Executing state pkg.installed for [python-oauth]
2019-06-20 05:35:44,504 [salt.state       :300 ][INFO    ][12538] All specified packages are already installed
2019-06-20 05:35:44,505 [salt.state       :1951][INFO    ][12538] Completed state [python-oauth] at time 05:35:44.505193 duration_in_ms=8.66
2019-06-20 05:35:44,508 [salt.state       :1780][INFO    ][12538] Running state [/etc/maas/regiond.conf] at time 05:35:44.508872
2019-06-20 05:35:44,509 [salt.state       :1813][INFO    ][12538] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-20 05:35:44,557 [salt.state       :300 ][INFO    ][12538] No changes needed to be made
2019-06-20 05:35:44,559 [salt.state       :1951][INFO    ][12538] Completed state [/etc/maas/regiond.conf] at time 05:35:44.559368 duration_in_ms=50.494
2019-06-20 05:35:44,561 [salt.state       :1780][INFO    ][12538] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:35:44.561405
2019-06-20 05:35:44,562 [salt.state       :1813][INFO    ][12538] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-20 05:35:44,649 [salt.state       :300 ][INFO    ][12538] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-20 05:35:44,649 [salt.state       :1951][INFO    ][12538] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:35:44.649464 duration_in_ms=88.058
2019-06-20 05:35:44,650 [salt.state       :1780][INFO    ][12538] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:35:44.650141
2019-06-20 05:35:44,650 [salt.state       :1813][INFO    ][12538] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-20 05:35:44,668 [salt.state       :300 ][INFO    ][12538] No changes needed to be made
2019-06-20 05:35:44,669 [salt.state       :1951][INFO    ][12538] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:35:44.669025 duration_in_ms=18.885
2019-06-20 05:35:44,669 [salt.state       :1780][INFO    ][12538] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:35:44.669643
2019-06-20 05:35:44,670 [salt.state       :1813][INFO    ][12538] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-20 05:35:44,692 [salt.state       :300 ][INFO    ][12538] No changes needed to be made
2019-06-20 05:35:44,692 [salt.state       :1951][INFO    ][12538] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:35:44.692897 duration_in_ms=23.253
2019-06-20 05:35:44,693 [salt.state       :1780][INFO    ][12538] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:35:44.693480
2019-06-20 05:35:44,693 [salt.state       :1813][INFO    ][12538] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-20 05:35:44,722 [salt.state       :300 ][INFO    ][12538] No changes needed to be made
2019-06-20 05:35:44,723 [salt.state       :1951][INFO    ][12538] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:35:44.723003 duration_in_ms=29.524
2019-06-20 05:35:44,723 [salt.state       :1780][INFO    ][12538] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:35:44.723539
2019-06-20 05:35:44,723 [salt.state       :1813][INFO    ][12538] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-20 05:35:44,736 [salt.state       :300 ][INFO    ][12538] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-20 05:35:44,736 [salt.state       :1951][INFO    ][12538] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:35:44.736231 duration_in_ms=12.692
2019-06-20 05:35:44,740 [salt.state       :1780][INFO    ][12538] Running state [a2enmod headers] at time 05:35:44.740259
2019-06-20 05:35:44,740 [salt.state       :1813][INFO    ][12538] Executing state cmd.run for [a2enmod headers]
2019-06-20 05:35:44,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12538] Executing command 'a2enmod headers' in directory '/root'
2019-06-20 05:35:44,817 [salt.state       :300 ][INFO    ][12538] {'pid': 12557, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-20 05:35:44,817 [salt.state       :1951][INFO    ][12538] Completed state [a2enmod headers] at time 05:35:44.817559 duration_in_ms=77.299
2019-06-20 05:35:44,818 [salt.state       :1780][INFO    ][12538] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:35:44.818151
2019-06-20 05:35:44,818 [salt.state       :1813][INFO    ][12538] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-20 05:35:44,837 [salt.state       :300 ][INFO    ][12538] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-20 05:35:44,838 [salt.state       :1951][INFO    ][12538] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:35:44.838031 duration_in_ms=19.88
2019-06-20 05:35:44,838 [salt.state       :1780][INFO    ][12538] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:35:44.838890
2019-06-20 05:35:44,839 [salt.state       :1813][INFO    ][12538] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-20 05:35:44,918 [salt.state       :300 ][INFO    ][12538] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-20 05:35:44,919 [salt.state       :1951][INFO    ][12538] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:35:44.919097 duration_in_ms=80.207
2019-06-20 05:35:44,919 [salt.state       :1780][INFO    ][12538] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:35:44.919764
2019-06-20 05:35:44,920 [salt.state       :1813][INFO    ][12538] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-20 05:35:44,984 [salt.state       :300 ][INFO    ][12538] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-20 05:35:44,984 [salt.state       :1951][INFO    ][12538] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:35:44.984891 duration_in_ms=65.128
2019-06-20 05:35:44,985 [salt.state       :1780][INFO    ][12538] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:35:44.985473
2019-06-20 05:35:44,985 [salt.state       :1813][INFO    ][12538] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-20 05:35:45,050 [salt.state       :300 ][INFO    ][12538] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-20 05:35:45,050 [salt.state       :1951][INFO    ][12538] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:35:45.050673 duration_in_ms=65.199
2019-06-20 05:35:45,051 [salt.state       :1780][INFO    ][12538] Running state [/root/.pgpass] at time 05:35:45.050984
2019-06-20 05:35:45,051 [salt.state       :1813][INFO    ][12538] Executing state file.managed for [/root/.pgpass]
2019-06-20 05:35:45,104 [salt.state       :300 ][INFO    ][12538] File /root/.pgpass is in the correct state
2019-06-20 05:35:45,104 [salt.state       :1951][INFO    ][12538] Completed state [/root/.pgpass] at time 05:35:45.104715 duration_in_ms=53.731
2019-06-20 05:35:45,111 [salt.state       :1780][INFO    ][12538] Running state [maas-region syncdb --noinput] at time 05:35:45.111065
2019-06-20 05:35:45,111 [salt.state       :1813][INFO    ][12538] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-20 05:35:45,112 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12538] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-20 05:35:47,142 [salt.state       :300 ][INFO    ][12538] {'pid': 12570, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: piston3, contenttypes, maasserver, auth, metadataserver, sessions, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-20 05:35:47,143 [salt.state       :1951][INFO    ][12538] Completed state [maas-region syncdb --noinput] at time 05:35:47.143347 duration_in_ms=2032.281
2019-06-20 05:35:47,143 [salt.state       :2022][WARNING ][12538] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-20 05:35:47,146 [salt.state       :1780][INFO    ][12538] Running state [maas-regiond] at time 05:35:47.146634
2019-06-20 05:35:47,147 [salt.state       :1813][INFO    ][12538] Executing state service.running for [maas-regiond]
2019-06-20 05:35:47,148 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12538] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-20 05:35:47,190 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12538] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-20 05:35:47,209 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12538] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-20 05:35:47,227 [salt.state       :300 ][INFO    ][12538] The service maas-regiond is already running
2019-06-20 05:35:47,228 [salt.state       :1951][INFO    ][12538] Completed state [maas-regiond] at time 05:35:47.227927 duration_in_ms=81.293
2019-06-20 05:35:47,230 [salt.state       :1780][INFO    ][12538] Running state [bind9] at time 05:35:47.230424
2019-06-20 05:35:47,230 [salt.state       :1813][INFO    ][12538] Executing state service.running for [bind9]
2019-06-20 05:35:47,232 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12538] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-20 05:35:47,251 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12538] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-20 05:35:47,270 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12538] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-20 05:35:47,288 [salt.state       :300 ][INFO    ][12538] The service bind9 is already running
2019-06-20 05:35:47,288 [salt.state       :1951][INFO    ][12538] Completed state [bind9] at time 05:35:47.288715 duration_in_ms=58.291
2019-06-20 05:35:47,291 [salt.state       :1780][INFO    ][12538] Running state [apache2] at time 05:35:47.291205
2019-06-20 05:35:47,291 [salt.state       :1813][INFO    ][12538] Executing state service.running for [apache2]
2019-06-20 05:35:47,292 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12538] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-20 05:35:47,312 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12538] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-20 05:35:47,330 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12538] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-20 05:35:47,353 [salt.state       :300 ][INFO    ][12538] The service apache2 is already running
2019-06-20 05:35:47,353 [salt.state       :1951][INFO    ][12538] Completed state [apache2] at time 05:35:47.353456 duration_in_ms=62.251
2019-06-20 05:35:47,355 [salt.state       :1780][INFO    ][12538] Running state [maasng.wait_for_http_code] at time 05:35:47.355845
2019-06-20 05:35:47,356 [salt.state       :1813][INFO    ][12538] Executing state module.run for [maasng.wait_for_http_code]
2019-06-20 05:35:47,357 [salt.utils.decorators:613 ][WARNING ][12538] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 05:35:47,369 [salt.state       :300 ][INFO    ][12538] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-20 05:35:47,369 [salt.state       :1951][INFO    ][12538] Completed state [maasng.wait_for_http_code] at time 05:35:47.369542 duration_in_ms=13.697
2019-06-20 05:35:47,370 [salt.state       :1780][INFO    ][12538] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:35:47.370853
2019-06-20 05:35:47,371 [salt.state       :1813][INFO    ][12538] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-20 05:35:47,372 [salt.state       :300 ][INFO    ][12538] /var/lib/maas/.setup_admin exists
2019-06-20 05:35:47,372 [salt.state       :1951][INFO    ][12538] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:35:47.372372 duration_in_ms=1.519
2019-06-20 05:35:47,373 [salt.state       :1780][INFO    ][12538] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:47.373460
2019-06-20 05:35:47,373 [salt.state       :1813][INFO    ][12538] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-20 05:35:47,374 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12538] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-20 05:35:48,811 [salt.state       :300 ][INFO    ][12538] {'pid': 12591, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-20 05:35:48,812 [salt.state       :1951][INFO    ][12538] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:48.812013 duration_in_ms=1438.552
2019-06-20 05:35:48,820 [salt.state       :1780][INFO    ][12538] Running state [maas_region_boot_source_resources_mirror] at time 05:35:48.820435
2019-06-20 05:35:48,821 [salt.state       :1813][INFO    ][12538] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-20 05:35:48,930 [salt.state       :300 ][INFO    ][12538] {'changes': {}}
2019-06-20 05:35:48,930 [salt.state       :1951][INFO    ][12538] Completed state [maas_region_boot_source_resources_mirror] at time 05:35:48.930746 duration_in_ms=110.311
2019-06-20 05:35:48,931 [salt.state       :1780][INFO    ][12538] Running state [maasng.boot_resources_import] at time 05:35:48.931908
2019-06-20 05:35:48,932 [salt.state       :1813][INFO    ][12538] Executing state module.run for [maasng.boot_resources_import]
2019-06-20 05:35:48,933 [salt.utils.decorators:613 ][WARNING ][12538] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 05:35:49,038 [salt.loaded.ext.module.maasng:1600][INFO    ][12538] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-20 05:35:54,105 [salt.loaded.ext.module.maasng:1600][INFO    ][12538] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-20 05:35:57,877 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620053557864948
2019-06-20 05:35:57,898 [salt.minion      :1432][INFO    ][12618] Starting a new job with PID 12618
2019-06-20 05:35:57,921 [salt.minion      :1711][INFO    ][12618] Returning information for job: 20190620053557864948
2019-06-20 05:35:59,170 [salt.loaded.ext.module.maasng:1600][INFO    ][12538] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-06-20 05:36:04,236 [salt.loaded.ext.module.maasng:1600][INFO    ][12538] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-06-20 05:36:09,351 [salt.state       :300 ][INFO    ][12538] {'ret': True}
2019-06-20 05:36:09,352 [salt.state       :1951][INFO    ][12538] Completed state [maasng.boot_resources_import] at time 05:36:09.352391 duration_in_ms=20420.482
2019-06-20 05:36:09,353 [salt.state       :1780][INFO    ][12538] Running state [maas_region_boot_sources_selection_xenial] at time 05:36:09.353623
2019-06-20 05:36:09,354 [salt.state       :1813][INFO    ][12538] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-20 05:36:09,567 [salt.state       :300 ][INFO    ][12538] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-20 05:36:09,567 [salt.state       :1951][INFO    ][12538] Completed state [maas_region_boot_sources_selection_xenial] at time 05:36:09.567492 duration_in_ms=213.854
2019-06-20 05:36:09,568 [salt.state       :1780][INFO    ][12538] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:36:09.568823
2019-06-20 05:36:09,569 [salt.state       :1813][INFO    ][12538] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-20 05:36:09,569 [salt.utils.decorators:613 ][WARNING ][12538] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 05:36:09,570 [salt.loaded.ext.module.maasng:1771][INFO    ][12538] boot-sources sync initiated for ALL Rack's
2019-06-20 05:36:10,584 [salt.state       :300 ][INFO    ][12538] {'ret': True}
2019-06-20 05:36:10,586 [salt.state       :1951][INFO    ][12538] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:36:10.586109 duration_in_ms=1017.285
2019-06-20 05:36:10,588 [salt.state       :1780][INFO    ][12538] Running state [maas.process_maas_config] at time 05:36:10.588287
2019-06-20 05:36:10,588 [salt.state       :1813][INFO    ][12538] Executing state module.run for [maas.process_maas_config]
2019-06-20 05:36:10,589 [salt.utils.decorators:613 ][WARNING ][12538] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 05:36:10,590 [salt.loaded.ext.module.maas:92  ][INFO    ][12538] maasconfig name=enable_http_proxy value=True
2019-06-20 05:36:10,653 [salt.loaded.ext.module.maas:92  ][INFO    ][12538] maasconfig name=upstream_dns value=8.8.8.8
2019-06-20 05:36:10,723 [salt.loaded.ext.module.maas:92  ][INFO    ][12538] maasconfig name=commissioning_distro_series value=xenial
2019-06-20 05:36:10,819 [salt.loaded.ext.module.maas:92  ][INFO    ][12538] maasconfig name=default_osystem value=ubuntu
2019-06-20 05:36:16,452 [salt.loaded.ext.module.maas:92  ][INFO    ][12538] maasconfig name=active_discovery_interval value=600
2019-06-20 05:36:16,514 [salt.loaded.ext.module.maas:92  ][INFO    ][12538] maasconfig name=dnssec_validation value=no
2019-06-20 05:36:16,580 [salt.loaded.ext.module.maas:92  ][INFO    ][12538] maasconfig name=maas_name value=mas01
2019-06-20 05:36:16,647 [salt.loaded.ext.module.maas:92  ][INFO    ][12538] maasconfig name=network_discovery value=enabled
2019-06-20 05:36:16,792 [salt.loaded.ext.module.maas:92  ][INFO    ][12538] maasconfig name=enable_third_party_drivers value=True
2019-06-20 05:36:16,857 [salt.loaded.ext.module.maas:92  ][INFO    ][12538] maasconfig name=default_storage_layout value=lvm
2019-06-20 05:36:16,927 [salt.loaded.ext.module.maas:92  ][INFO    ][12538] maasconfig name=ntp_external_only value=True
2019-06-20 05:36:16,981 [salt.loaded.ext.module.maas:92  ][INFO    ][12538] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-20 05:36:17,034 [salt.loaded.ext.module.maas:92  ][INFO    ][12538] maasconfig name=default_distro_series value=xenial
2019-06-20 05:36:17,100 [salt.loaded.ext.module.maas:92  ][INFO    ][12538] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-06-20 05:36:17,231 [salt.state       :300 ][INFO    ][12538] {'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-06-20 05:36:17,232 [salt.state       :1951][INFO    ][12538] Completed state [maas.process_maas_config] at time 05:36:17.232320 duration_in_ms=6644.031
2019-06-20 05:36:17,233 [salt.state       :1780][INFO    ][12538] Running state [pxe_admin] at time 05:36:17.233706
2019-06-20 05:36:17,234 [salt.state       :1813][INFO    ][12538] Executing state maasng.fabric_present for [pxe_admin]
2019-06-20 05:36:17,322 [salt.loaded.ext.module.maasng:945 ][INFO    ][12538] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'id': 1}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6c7seh', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'id': 2}]
2019-06-20 05:36:17,387 [salt.loaded.ext.module.maasng:1008][WARNING ][12538] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-20 05:36:17,387 [salt.loaded.ext.module.maasng:1011][WARNING ][12538] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-20 05:36:17,455 [salt.state       :300 ][INFO    ][12538] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-20 05:36:17,455 [salt.state       :1951][INFO    ][12538] Completed state [pxe_admin] at time 05:36:17.455424 duration_in_ms=221.718
2019-06-20 05:36:17,456 [salt.state       :1780][INFO    ][12538] Running state [vlan 0] at time 05:36:17.456004
2019-06-20 05:36:17,456 [salt.state       :1813][INFO    ][12538] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-20 05:36:17,540 [salt.loaded.ext.module.maasng:945 ][INFO    ][12538] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'id': 1}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6c7seh', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'id': 2}]
2019-06-20 05:36:17,664 [salt.loaded.ext.module.maasng:945 ][INFO    ][12538] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{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'6c7seh', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-20 05:36:17,933 [salt.loaded.ext.module.maasng:945 ][INFO    ][12538] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{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'6c7seh', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-20 05:36:18,030 [salt.state       :300 ][INFO    ][12538] {'new': 'Vlan untagged was updated'}
2019-06-20 05:36:18,030 [salt.state       :1951][INFO    ][12538] Completed state [vlan 0] at time 05:36:18.030628 duration_in_ms=574.622
2019-06-20 05:36:18,032 [salt.state       :1780][INFO    ][12538] Running state [192.168.11.0/24] at time 05:36:18.032621
2019-06-20 05:36:18,033 [salt.state       :1813][INFO    ][12538] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-20 05:36:18,249 [salt.loaded.ext.module.maasng:945 ][INFO    ][12538] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6c7seh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-20 05:36:18,250 [salt.loaded.ext.module.maasng:1235][WARNING ][12538] Ignoring parameter vlan:0
2019-06-20 05:36:18,322 [salt.state       :300 ][INFO    ][12538] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-20 05:36:18,323 [salt.state       :1951][INFO    ][12538] Completed state [192.168.11.0/24] at time 05:36:18.323081 duration_in_ms=290.459
2019-06-20 05:36:18,324 [salt.state       :1780][INFO    ][12538] Running state [maas_create_iprange_1] at time 05:36:18.324451
2019-06-20 05:36:18,324 [salt.state       :1813][INFO    ][12538] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-20 05:36:18,388 [salt.state       :300 ][INFO    ][12538] Iprange maas_create_iprange_1 already exist.
2019-06-20 05:36:18,388 [salt.state       :1951][INFO    ][12538] Completed state [maas_create_iprange_1] at time 05:36:18.388746 duration_in_ms=64.295
2019-06-20 05:36:18,389 [salt.state       :1780][INFO    ][12538] Running state [vlan 0] at time 05:36:18.389183
2019-06-20 05:36:18,389 [salt.state       :1813][INFO    ][12538] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-20 05:36:18,447 [salt.loaded.ext.module.maasng:945 ][INFO    ][12538] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6c7seh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-20 05:36:18,556 [salt.loaded.ext.module.maasng:945 ][INFO    ][12538] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6c7seh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-20 05:36:18,777 [salt.loaded.ext.module.maasng:945 ][INFO    ][12538] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'6c7seh', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-20 05:36:18,856 [salt.state       :300 ][INFO    ][12538] {'new': 'Vlan untagged was updated'}
2019-06-20 05:36:18,856 [salt.state       :1951][INFO    ][12538] Completed state [vlan 0] at time 05:36:18.856449 duration_in_ms=467.266
2019-06-20 05:36:18,857 [salt.state       :1780][INFO    ][12538] Running state [opnfv] at time 05:36:18.857370
2019-06-20 05:36:18,857 [salt.state       :1813][INFO    ][12538] Executing state maasng.sshkey_present for [opnfv]
2019-06-20 05:36:18,891 [salt.loaded.ext.module.maasng:1903][INFO    ][12538] [{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-06-20 05:36:18,891 [salt.state       :300 ][INFO    ][12538] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-06-20 05:36:18,892 [salt.state       :1951][INFO    ][12538] Completed state [opnfv] at time 05:36:18.891980 duration_in_ms=34.61
2019-06-20 05:36:18,892 [salt.state       :1780][INFO    ][12538] Running state [maas.process_tags] at time 05:36:18.892840
2019-06-20 05:36:18,893 [salt.state       :1813][INFO    ][12538] Executing state module.run for [maas.process_tags]
2019-06-20 05:36:18,893 [salt.utils.decorators:613 ][WARNING ][12538] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 05:36:18,944 [salt.loaded.ext.module.maas:92  ][INFO    ][12538] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-06-20 05:36:19,041 [salt.state       :300 ][INFO    ][12538] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-06-20 05:36:19,041 [salt.state       :1951][INFO    ][12538] Completed state [maas.process_tags] at time 05:36:19.041465 duration_in_ms=148.625
2019-06-20 05:36:19,045 [salt.minion      :1711][INFO    ][12538] Returning information for job: 20190620053542804028
2019-06-20 05:36:19,615 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command state.apply with jid 20190620053619604120
2019-06-20 05:36:19,633 [salt.minion      :1432][INFO    ][13025] Starting a new job with PID 13025
2019-06-20 05:36:23,412 [salt.state       :915 ][INFO    ][13025] Loading fresh modules for state activity
2019-06-20 05:36:23,506 [salt.state       :1780][INFO    ][13025] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:23.506419
2019-06-20 05:36:23,506 [salt.state       :1813][INFO    ][13025] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-20 05:36:23,508 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13025] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-20 05:36:24,991 [salt.state       :300 ][INFO    ][13025] {'pid': 13047, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-20 05:36:24,992 [salt.state       :1951][INFO    ][13025] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:24.992452 duration_in_ms=1486.032
2019-06-20 05:36:24,995 [salt.state       :1780][INFO    ][13025] Running state [maas.process_machines] at time 05:36:24.995129
2019-06-20 05:36:24,995 [salt.state       :1813][INFO    ][13025] Executing state module.run for [maas.process_machines]
2019-06-20 05:36:24,997 [salt.utils.decorators:613 ][WARNING ][13025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 05:36:25,711 [salt.loaded.ext.module.maas:412 ][WARNING ][13025] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-20 05:36:25,712 [salt.loaded.ext.module.maas:92  ][INFO    ][13025] 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=ycwp37 architecture=amd64/generic power_parameters_power_user=admin
2019-06-20 05:36:26,891 [salt.loaded.ext.module.maas:412 ][WARNING ][13025] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-20 05:36:26,892 [salt.loaded.ext.module.maas:92  ][INFO    ][13025] 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=aabbtx architecture=amd64/generic power_parameters_power_user=admin
2019-06-20 05:36:28,155 [salt.loaded.ext.module.maas:412 ][WARNING ][13025] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-20 05:36:28,156 [salt.loaded.ext.module.maas:92  ][INFO    ][13025] 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=y3sg6a architecture=amd64/generic power_parameters_power_user=admin
2019-06-20 05:36:29,364 [salt.loaded.ext.module.maas:412 ][WARNING ][13025] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-20 05:36:29,365 [salt.loaded.ext.module.maas:92  ][INFO    ][13025] 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=xkchct architecture=amd64/generic power_parameters_power_user=admin
2019-06-20 05:36:30,640 [salt.loaded.ext.module.maas:412 ][WARNING ][13025] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-20 05:36:30,640 [salt.loaded.ext.module.maas:92  ][INFO    ][13025] 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=dq8hnn architecture=amd64/generic power_parameters_power_user=admin
2019-06-20 05:36:31,868 [salt.state       :300 ][INFO    ][13025] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-20 05:36:31,869 [salt.state       :1951][INFO    ][13025] Completed state [maas.process_machines] at time 05:36:31.869208 duration_in_ms=6874.078
2019-06-20 05:36:31,873 [salt.minion      :1711][INFO    ][13025] Returning information for job: 20190620053619604120
2019-06-20 05:37:05,196 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command state.apply with jid 20190620053705183226
2019-06-20 05:37:05,219 [salt.minion      :1432][INFO    ][13288] Starting a new job with PID 13288
2019-06-20 05:37:08,932 [salt.state       :915 ][INFO    ][13288] Loading fresh modules for state activity
2019-06-20 05:37:09,021 [salt.state       :1780][INFO    ][13288] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:37:09.021709
2019-06-20 05:37:09,022 [salt.state       :1813][INFO    ][13288] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-20 05:37:09,024 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13288] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-20 05:37:10,335 [salt.state       :300 ][INFO    ][13288] {'pid': 13295, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-20 05:37:10,336 [salt.state       :1951][INFO    ][13288] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:37:10.336710 duration_in_ms=1315.002
2019-06-20 05:37:10,339 [salt.state       :1780][INFO    ][13288] Running state [maas.wait_for_machine_status] at time 05:37:10.339642
2019-06-20 05:37:10,340 [salt.state       :1813][INFO    ][13288] Executing state module.run for [maas.wait_for_machine_status]
2019-06-20 05:37:10,340 [salt.utils.decorators:613 ][WARNING ][13288] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 05:37:12,913 [salt.loaded.ext.module.maas:993 ][INFO    ][13288] Machine xkchct mark broken
2019-06-20 05:37:13,567 [salt.loaded.ext.module.maas:996 ][INFO    ][13288] Machine xkchct mark fixed
2019-06-20 05:37:14,837 [salt.loaded.ext.module.maas:684 ][INFO    ][13288] deploymachines hwe_kernel=hwe-16.04 system_id=xkchct distro_series=xenial
2019-06-20 05:37:17,982 [salt.loaded.ext.module.maas:1023][INFO    ][13288] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1492.36787796s left)
2019-06-20 05:37:20,215 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620053720202703
2019-06-20 05:37:20,238 [salt.minion      :1432][INFO    ][13391] Starting a new job with PID 13391
2019-06-20 05:37:20,262 [salt.minion      :1711][INFO    ][13391] Returning information for job: 20190620053720202703
2019-06-20 05:37:50,266 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620053750256341
2019-06-20 05:37:50,288 [salt.minion      :1432][INFO    ][13414] Starting a new job with PID 13414
2019-06-20 05:37:50,312 [salt.minion      :1711][INFO    ][13414] Returning information for job: 20190620053750256341
2019-06-20 05:37:51,604 [salt.loaded.ext.module.maas:1023][INFO    ][13288] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1458.745754s left)
2019-06-20 05:38:20,328 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620053820278065
2019-06-20 05:38:20,350 [salt.minion      :1432][INFO    ][13469] Starting a new job with PID 13469
2019-06-20 05:38:20,375 [salt.minion      :1711][INFO    ][13469] Returning information for job: 20190620053820278065
2019-06-20 05:38:24,772 [salt.loaded.ext.module.maas:1023][INFO    ][13288] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1425.57807708s left)
2019-06-20 05:38:50,373 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620053850360173
2019-06-20 05:38:50,396 [salt.minion      :1432][INFO    ][13492] Starting a new job with PID 13492
2019-06-20 05:38:50,423 [salt.minion      :1711][INFO    ][13492] Returning information for job: 20190620053850360173
2019-06-20 05:38:58,239 [salt.loaded.ext.module.maas:1023][INFO    ][13288] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1392.11114788s left)
2019-06-20 05:39:20,430 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620053920416217
2019-06-20 05:39:20,452 [salt.minion      :1432][INFO    ][13630] Starting a new job with PID 13630
2019-06-20 05:39:20,480 [salt.minion      :1711][INFO    ][13630] Returning information for job: 20190620053920416217
2019-06-20 05:39:31,767 [salt.loaded.ext.module.maas:1023][INFO    ][13288] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1358.583179s left)
2019-06-20 05:39:50,487 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620053950474316
2019-06-20 05:39:50,510 [salt.minion      :1432][INFO    ][13659] Starting a new job with PID 13659
2019-06-20 05:39:50,536 [salt.minion      :1711][INFO    ][13659] Returning information for job: 20190620053950474316
2019-06-20 05:40:04,995 [salt.loaded.ext.module.maas:1023][INFO    ][13288] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1325.35476589s left)
2019-06-20 05:40:20,543 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620054020530141
2019-06-20 05:40:20,565 [salt.minion      :1432][INFO    ][13830] Starting a new job with PID 13830
2019-06-20 05:40:20,589 [salt.minion      :1711][INFO    ][13830] Returning information for job: 20190620054020530141
2019-06-20 05:40:38,660 [salt.loaded.ext.module.maas:1023][INFO    ][13288] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1291.68959498s left)
2019-06-20 05:40:50,606 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620054050593880
2019-06-20 05:40:50,629 [salt.minion      :1432][INFO    ][13850] Starting a new job with PID 13850
2019-06-20 05:40:50,654 [salt.minion      :1711][INFO    ][13850] Returning information for job: 20190620054050593880
2019-06-20 05:41:12,468 [salt.loaded.ext.module.maas:1023][INFO    ][13288] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1257.88143897s left)
2019-06-20 05:41:20,674 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620054120661551
2019-06-20 05:41:20,697 [salt.minion      :1432][INFO    ][13934] Starting a new job with PID 13934
2019-06-20 05:41:20,723 [salt.minion      :1711][INFO    ][13934] Returning information for job: 20190620054120661551
2019-06-20 05:41:45,905 [salt.loaded.ext.module.maas:1023][INFO    ][13288] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1224.44449687s left)
2019-06-20 05:41:50,746 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620054150733160
2019-06-20 05:41:50,768 [salt.minion      :1432][INFO    ][13955] Starting a new job with PID 13955
2019-06-20 05:41:50,793 [salt.minion      :1711][INFO    ][13955] Returning information for job: 20190620054150733160
2019-06-20 05:42:19,396 [salt.loaded.ext.module.maas:1023][INFO    ][13288] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1190.95395398s left)
2019-06-20 05:42:20,817 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620054220805057
2019-06-20 05:42:20,834 [salt.minion      :1432][INFO    ][14115] Starting a new job with PID 14115
2019-06-20 05:42:20,860 [salt.minion      :1711][INFO    ][14115] Returning information for job: 20190620054220805057
2019-06-20 05:42:50,889 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620054250876676
2019-06-20 05:42:50,910 [salt.minion      :1432][INFO    ][14139] Starting a new job with PID 14139
2019-06-20 05:42:50,932 [salt.minion      :1711][INFO    ][14139] Returning information for job: 20190620054250876676
2019-06-20 05:42:53,096 [salt.loaded.ext.module.maas:1023][INFO    ][13288] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1157.2540791s left)
2019-06-20 05:43:20,967 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620054320955166
2019-06-20 05:43:20,988 [salt.minion      :1432][INFO    ][14262] Starting a new job with PID 14262
2019-06-20 05:43:21,011 [salt.minion      :1711][INFO    ][14262] Returning information for job: 20190620054320955166
2019-06-20 05:43:26,541 [salt.loaded.ext.module.maas:1023][INFO    ][13288] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1123.8086319s left)
2019-06-20 05:43:51,051 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620054351037724
2019-06-20 05:43:51,073 [salt.minion      :1432][INFO    ][14284] Starting a new job with PID 14284
2019-06-20 05:43:51,098 [salt.minion      :1711][INFO    ][14284] Returning information for job: 20190620054351037724
2019-06-20 05:43:59,841 [salt.loaded.ext.module.maas:1023][INFO    ][13288] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1090.50903201s left)
2019-06-20 05:44:21,144 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620054421131274
2019-06-20 05:44:21,167 [salt.minion      :1432][INFO    ][14379] Starting a new job with PID 14379
2019-06-20 05:44:21,193 [salt.minion      :1711][INFO    ][14379] Returning information for job: 20190620054421131274
2019-06-20 05:44:33,111 [salt.state       :300 ][INFO    ][13288] {'ret': True}
2019-06-20 05:44:33,112 [salt.state       :1951][INFO    ][13288] Completed state [maas.wait_for_machine_status] at time 05:44:33.112073 duration_in_ms=442772.43
2019-06-20 05:44:33,115 [salt.minion      :1711][INFO    ][13288] Returning information for job: 20190620053705183226
2019-06-20 05:44:33,761 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command state.apply with jid 20190620054433748980
2019-06-20 05:44:33,782 [salt.minion      :1432][INFO    ][14416] Starting a new job with PID 14416
2019-06-20 05:44:37,615 [salt.state       :915 ][INFO    ][14416] Loading fresh modules for state activity
2019-06-20 05:44:37,747 [salt.state       :1780][INFO    ][14416] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:37.747240
2019-06-20 05:44:37,747 [salt.state       :1813][INFO    ][14416] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-20 05:44:37,749 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14416] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-20 05:44:39,289 [salt.state       :300 ][INFO    ][14416] {'pid': 14432, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-20 05:44:39,290 [salt.state       :1951][INFO    ][14416] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:39.290210 duration_in_ms=1542.971
2019-06-20 05:44:39,291 [salt.state       :1780][INFO    ][14416] Running state [maas_machines_storage_cmp002_lvm] at time 05:44:39.291692
2019-06-20 05:44:39,291 [salt.state       :1813][INFO    ][14416] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-20 05:44:39,929 [salt.state       :300 ][INFO    ][14416] Machine cmp002 is not in Ready state.
2019-06-20 05:44:39,930 [salt.state       :1951][INFO    ][14416] Completed state [maas_machines_storage_cmp002_lvm] at time 05:44:39.930331 duration_in_ms=638.638
2019-06-20 05:44:39,930 [salt.state       :1780][INFO    ][14416] Running state [maas_machines_storage_cmp001_lvm] at time 05:44:39.930776
2019-06-20 05:44:39,931 [salt.state       :1813][INFO    ][14416] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-20 05:44:40,852 [salt.state       :300 ][INFO    ][14416] Machine cmp001 is not in Ready state.
2019-06-20 05:44:40,853 [salt.state       :1951][INFO    ][14416] Completed state [maas_machines_storage_cmp001_lvm] at time 05:44:40.853288 duration_in_ms=922.511
2019-06-20 05:44:40,857 [salt.minion      :1711][INFO    ][14416] Returning information for job: 20190620054433748980
2019-06-20 05:44:41,495 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command state.apply with jid 20190620054441482645
2019-06-20 05:44:41,518 [salt.minion      :1432][INFO    ][14443] Starting a new job with PID 14443
2019-06-20 05:44:42,326 [salt.state       :915 ][INFO    ][14443] Loading fresh modules for state activity
2019-06-20 05:44:42,417 [salt.state       :1780][INFO    ][14443] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:42.417425
2019-06-20 05:44:42,417 [salt.state       :1813][INFO    ][14443] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-20 05:44:42,420 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14443] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-20 05:44:43,866 [salt.state       :300 ][INFO    ][14443] {'pid': 14450, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-20 05:44:43,867 [salt.state       :1951][INFO    ][14443] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:43.867034 duration_in_ms=1449.61
2019-06-20 05:44:43,868 [salt.state       :1780][INFO    ][14443] Running state [maas.deploy_machines] at time 05:44:43.868147
2019-06-20 05:44:43,868 [salt.state       :1813][INFO    ][14443] Executing state module.run for [maas.deploy_machines]
2019-06-20 05:44:43,868 [salt.utils.decorators:613 ][WARNING ][14443] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 05:44:44,437 [salt.state       :300 ][INFO    ][14443] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-20 05:44:44,437 [salt.state       :1951][INFO    ][14443] Completed state [maas.deploy_machines] at time 05:44:44.437402 duration_in_ms=569.253
2019-06-20 05:44:44,439 [salt.minion      :1711][INFO    ][14443] Returning information for job: 20190620054441482645
2019-06-20 05:44:45,047 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command state.apply with jid 20190620054445032991
2019-06-20 05:44:45,070 [salt.minion      :1432][INFO    ][14459] Starting a new job with PID 14459
2019-06-20 05:44:45,827 [salt.state       :915 ][INFO    ][14459] Loading fresh modules for state activity
2019-06-20 05:44:45,919 [salt.state       :1780][INFO    ][14459] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:45.919492
2019-06-20 05:44:45,919 [salt.state       :1813][INFO    ][14459] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-20 05:44:45,921 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14459] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-20 05:44:47,448 [salt.state       :300 ][INFO    ][14459] {'pid': 14468, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-20 05:44:47,449 [salt.state       :1951][INFO    ][14459] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:47.449554 duration_in_ms=1530.061
2019-06-20 05:44:47,453 [salt.state       :1780][INFO    ][14459] Running state [maas.wait_for_machine_status] at time 05:44:47.453029
2019-06-20 05:44:47,453 [salt.state       :1813][INFO    ][14459] Executing state module.run for [maas.wait_for_machine_status]
2019-06-20 05:44:47,454 [salt.utils.decorators:613 ][WARNING ][14459] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 05:44:50,626 [salt.state       :300 ][INFO    ][14459] {'ret': True}
2019-06-20 05:44:50,627 [salt.state       :1951][INFO    ][14459] Completed state [maas.wait_for_machine_status] at time 05:44:50.626948 duration_in_ms=3173.918
2019-06-20 05:44:50,630 [salt.minion      :1711][INFO    ][14459] Returning information for job: 20190620054445032991
2019-06-20 06:15:22,603 [salt.utils.schedule:1377][INFO    ][6508] Running scheduled job: __mine_interval
2019-06-20 07:15:09,136 [salt.minion      :1308][INFO    ][6508] User sudo_ubuntu Executing command cp.push_dir with jid 20190620071509124664
2019-06-20 07:15:09,159 [salt.minion      :1432][INFO    ][21069] Starting a new job with PID 21069
