openstack-lanch an instance and nova compute log analysis

崬城衞

　　1. how to launch an instance:

[iyunv@localhost ~(keystone_admin)]# nova flavor-list
+----+-----------+-----------+------+-----------+------+-------+-------------+-----------+
| ID | Name      | Memory_MB | Disk | Ephemeral | Swap | VCPUs | RXTX_Factor | Is_Public |
+----+-----------+-----------+------+-----------+------+-------+-------------+-----------+
| 1  | m1.tiny   | 512       | 1    | 0         |      | 1     | 1.0         | True      |
| 2  | m1.small  | 2048      | 20   | 0         |      | 1     | 1.0         | True      |
| 3  | m1.medium | 4096      | 40   | 0         |      | 2     | 1.0         | True      |
| 4  | m1.large  | 8192      | 80   | 0         |      | 4     | 1.0         | True      |
| 5  | m1.xlarge | 16384     | 160  | 0         |      | 8     | 1.0         | True      |
+----+-----------+-----------+------+-----------+------+-------+-------------+-----------+

[iyunv@localhost ~(keystone_admin)]# glance image-list
+--------------------------------------+--------+
| ID                                   | Name   |
+--------------------------------------+--------+
| 3efcdaf6-778a-4a81-ac9a-b76ebb21bffd | cirros |
+--------------------------------------+--------+

[iyunv@localhost ~(keystone_admin)]# nova boot --flavor m1.small --image cirros test
+--------------------------------------+-----------------------------------------------+
| Property                             | Value                                         |
+--------------------------------------+-----------------------------------------------+
| OS-DCF:diskConfig                    | MANUAL                                        |
| OS-EXT-AZ:availability_zone          |                                               |
| OS-EXT-SRV-ATTR:host                 | -                                             |
| OS-EXT-SRV-ATTR:hostname             | test                                          |
| OS-EXT-SRV-ATTR:hypervisor_hostname  | -                                             |
| OS-EXT-SRV-ATTR:instance_name        |                                               |
| OS-EXT-SRV-ATTR:kernel_id            |                                               |
| OS-EXT-SRV-ATTR:launch_index         | 0                                             |
| OS-EXT-SRV-ATTR:ramdisk_id           |                                               |
| OS-EXT-SRV-ATTR:reservation_id       | r-wsqhc0un                                    |
| OS-EXT-SRV-ATTR:root_device_name     | -                                             |
| OS-EXT-SRV-ATTR:user_data            | -                                             |
| OS-EXT-STS:power_state               | 0                                             |
| OS-EXT-STS:task_state                | scheduling                                    |
| OS-EXT-STS:vm_state                  | building                                      |
| OS-SRV-USG:launched_at               | -                                             |
| OS-SRV-USG:terminated_at             | -                                             |
| accessIPv4                           |                                               |
| accessIPv6                           |                                               |
| adminPass                            | dtyW5McRcsd8                                  |
| config_drive                         |                                               |
| created                              | 2016-12-04T10:59:13Z                          |
| description                          | -                                             |
| flavor                               | m1.small (2)                                  |
| hostId                               |                                               |
| host_status                          |                                               |
| id                                   | ceb0bec9-40e9-4426-832a-3271eb8b62ab          |
| image                                | cirros (3efcdaf6-778a-4a81-ac9a-b76ebb21bffd) |
| key_name                             | -                                             |
| locked                               | False                                         |
| metadata                             | {}                                            |
| name                                 | test                                          |
| os-extended-volumes:volumes_attached | []                                            |
| progress                             | 0                                             |
| security_groups                      | default                                       |
| status                               | BUILD                                         |
| tags                                 | []                                            |
| tenant_id                            | 850808305d454ea7939724853a7c8844              |
| updated                              | 2016-12-04T10:59:14Z                          |
| user_id                              | ef23903614b84305bf5251915fa05d69              |
+--------------------------------------+-----------------------------------------------+

　　nova compute log:
　　path:/var/log/nova/nova_compute.log
　　1. 完整log
　　2016-12-04 05:59:15.188 1916 INFO nova.compute.resource_tracker [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] Auditing locally available compute resources for node localhost
2016-12-04 05:59:17.545 1916 INFO nova.compute.resource_tracker [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] Total usable vcpus: 1, total allocated vcpus: 0
2016-12-04 05:59:17.547 1916 INFO nova.compute.resource_tracker [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] Final resource view: name=localhost phys_ram=4703MB used_ram=512MB phys_disk=49GB used_disk=0GB total_vcpus=1 used_vcpus=0 pci_stats=[]
2016-12-04 05:59:17.714 1916 INFO nova.compute.resource_tracker [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] Compute_service record updated for localhost:localhost
2016-12-04 05:59:18.666 1916 WARNING nova.image.glance [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -] No protocol specified in for api_server 'http://172.125.1.10:9292', please update [glance] api_servers with fully qualified url including scheme (http / https)
2016-12-04 05:59:18.921 1916 INFO nova.compute.claims [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] Attempting claim: memory 2048 MB, disk 20 GB, vcpus 1 CPU
2016-12-04 05:59:18.922 1916 INFO nova.compute.claims [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] Total memory: 4703 MB, used: 512.00 MB
2016-12-04 05:59:18.923 1916 INFO nova.compute.claims [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] memory limit: 7054.50 MB, free: 6542.50 MB
2016-12-04 05:59:18.924 1916 INFO nova.compute.claims [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] Total disk: 49 GB, used: 0.00 GB
2016-12-04 05:59:18.926 1916 INFO nova.compute.claims [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] disk limit: 49.00 GB, free: 49.00 GB
2016-12-04 05:59:18.927 1916 INFO nova.compute.claims [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] Total vcpu: 1 VCPU, used: 0.00 VCPU
2016-12-04 05:59:18.928 1916 INFO nova.compute.claims [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] vcpu limit: 16.00 VCPU, free: 16.00 VCPU
2016-12-04 05:59:18.948 1916 INFO nova.compute.claims [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] Claim successful
2016-12-04 05:59:20.910 1916 WARNING nova.virt.osinfo [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -] Cannot find OS information - Reason: (No configuration information found for operating system Empty)
2016-12-04 05:59:21.246 1916 WARNING nova.virt.osinfo [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -] Cannot find OS information - Reason: (No configuration information found for operating system Empty)
2016-12-04 05:59:24.038 1916 WARNING nova.virt.osinfo [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -] Cannot find OS information - Reason: (No configuration information found for operating system Empty)
2016-12-04 05:59:24.041 1916 INFO nova.virt.libvirt.driver [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] Creating image
2016-12-04 05:59:39.829 1916 WARNING nova.virt.osinfo [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -] Cannot find OS information - Reason: (No configuration information found for operating system Empty)
2016-12-04 05:59:41.480 1916 INFO os_vif [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -] Successfully plugged vif VIFBridge(active=False,address=fa:16:3e:ae:2c:2a,bridge_name='qbrbea6ecd2-c5',has_traffic_filtering=True,id=bea6ecd2-c599-405b-9871-a6dcaa27ca2d,network=Network(da515db3-8dbf-4e67-833e-10c476871fa6),plugin='ovs',port_profile=VIFPortProfileBase,preserve_on_delete=False,vif_name='tapbea6ecd2-c5')
2016-12-04 05:59:51.979 1916 INFO nova.compute.manager [req-824292d4-400b-4e5f-9844-85528e330b2a - - - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] VM Started (Lifecycle Event)
2016-12-04 05:59:52.953 1916 INFO nova.compute.manager [req-824292d4-400b-4e5f-9844-85528e330b2a - - - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] VM Paused (Lifecycle Event)
2016-12-04 05:59:54.772 1916 INFO nova.compute.manager [req-824292d4-400b-4e5f-9844-85528e330b2a - - - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] During sync_power_state the instance has a pending task (spawning). Skip.
2016-12-04 05:59:55.116 1916 INFO nova.compute.manager [req-824292d4-400b-4e5f-9844-85528e330b2a - - - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] VM Resumed (Lifecycle Event)
2016-12-04 05:59:55.230 1916 INFO nova.virt.libvirt.driver [-] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] Instance spawned successfully.
2016-12-04 05:59:55.231 1916 INFO nova.compute.manager [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] Took 31.19 seconds to spawn the instance on the hypervisor.
2016-12-04 05:59:57.907 1916 INFO nova.compute.manager [req-824292d4-400b-4e5f-9844-85528e330b2a - - - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] During sync_power_state the instance has a pending task (spawning). Skip.
2016-12-04 05:59:57.908 1916 INFO nova.compute.manager [req-824292d4-400b-4e5f-9844-85528e330b2a - - - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] VM Resumed (Lifecycle Event)
2016-12-04 06:00:00.482 1916 WARNING nova.image.glance [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -] No protocol specified in for api_server 'http://172.125.1.10:9292', please update [glance] api_servers with fully qualified url including scheme (http / https)
2016-12-04 06:00:00.593 1916 INFO nova.compute.manager [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] Took 41.93 seconds to build instance.
2016-12-04 06:00:13.006 1916 INFO nova.compute.manager [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] Running instance usage audit for host localhost from 2016-12-04 10:00:00 to 2016-12-04 11:00:00. 3 instances.
2016-12-04 06:00:13.313 1916 WARNING nova.image.glance [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] No protocol specified in for api_server 'http://172.125.1.10:9292', please update [glance] api_servers with fully qualified url including scheme (http / https)
2016-12-04 06:00:13.507 1916 WARNING nova.image.glance [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] No protocol specified in for api_server 'http://172.125.1.10:9292', please update [glance] api_servers with fully qualified url including scheme (http / https)
2016-12-04 06:00:13.751 1916 WARNING nova.image.glance [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] No protocol specified in for api_server 'http://172.125.1.10:9292', please update [glance] api_servers with fully qualified url including scheme (http / https)
2016-12-04 06:00:16.088 1916 INFO nova.compute.resource_tracker [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] Auditing locally available compute resources for node localhost
2016-12-04 06:00:16.171 1916 WARNING nova.virt.libvirt.driver [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] couldn't obtain the vcpu count from domain id: ceb0bec9-40e9-4426-832a-3271eb8b62ab, exception: Requested operation is not valid: cpu affinity is not supported
2016-12-04 06:00:20.245 1916 INFO nova.compute.resource_tracker [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] Total usable vcpus: 1, total allocated vcpus: 1
2016-12-04 06:00:20.246 1916 INFO nova.compute.resource_tracker [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] Final resource view: name=localhost phys_ram=4703MB used_ram=2560MB phys_disk=49GB used_disk=20GB total_vcpus=1 used_vcpus=1 pci_stats=[]
2016-12-04 06:00:20.499 1916 INFO nova.compute.resource_tracker [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] Compute_service record updated for localhost:localhost
2016-12-04 06:01:16.044 1916 INFO nova.compute.resource_tracker [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] Auditing locally available compute resources for node localhost
2016-12-04 06:01:16.200 1916 WARNING nova.virt.libvirt.driver [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] couldn't obtain the vcpu count from domain id: ceb0bec9-40e9-4426-832a-3271eb8b62ab, exception: Requested operation is not valid: cpu affinity is not supported
2016-12-04 06:01:23.000 1916 INFO nova.compute.resource_tracker [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] Total usable vcpus: 1, total allocated vcpus: 1
2016-12-04 06:01:23.002 1916 INFO nova.compute.resource_tracker [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] Final resource view: name=localhost phys_ram=4703MB used_ram=2560MB phys_disk=49GB used_disk=20GB total_vcpus=1 used_vcpus=1 pci_stats=[]
2016-12-04 06:01:23.543 1916 INFO nova.compute.resource_tracker [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] Compute_service record updated for localhost:localhost
2016-12-04 06:02:16.167 1916 INFO nova.compute.resource_tracker [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] Auditing locally available compute resources for node localhost
2016-12-04 06:02:16.264 1916 WARNING nova.virt.libvirt.driver [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] couldn't obtain the vcpu count from domain id: ceb0bec9-40e9-4426-832a-3271eb8b62ab, exception: Requested operation is not valid: cpu affinity is not supported
2016-12-04 06:02:21.224 1916 INFO nova.compute.resource_tracker [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] Total usable vcpus: 1, total allocated vcpus: 1
2016-12-04 06:02:21.225 1916 INFO nova.compute.resource_tracker [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] Final resource view: name=localhost phys_ram=4703MB used_ram=2560MB phys_disk=49GB used_disk=20GB total_vcpus=1 used_vcpus=1 pci_stats=[]
2016-12-04 06:02:21.433 1916 INFO nova.compute.resource_tracker [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] Compute_service record updated for localhost:localhost


　　1. audit
　　鉴权是第一步
　　2016-12-04 05:59:15.188 1916 INFO nova.compute.resource_tracker [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] Auditing locally available compute resources for node localhost
　　2. flavor条件判断： disk/memory/vCPU
　　2016-12-04 05:59:17.545 1916 INFO nova.compute.resource_tracker [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 - - - - -] Total usable vcpus: 1, total allocated vcpus: 0
2016-12-04 05:59:17.547 1916 INFO nova.compute.resource_tracker [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 -- - - -] Final resource view: name=localhost phys_ram=4703MB used_ram=512MB phys_disk=49GB used_disk=0GB total_vcpus=1 used_vcpus=0 pci_stats=[]
2016-12-04 05:59:17.714 1916 INFO nova.compute.resource_tracker [req-aca1bd33-ce85-4f3d-ad92-d0f6030a35f0 -- - - -] Compute_service record updated for localhost:localhost
2016-12-0405:59:18.666 1916 WARNING nova.image.glance [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -]No protocol specified in for api_server 'http://172.125.1.10:9292', please update [glance] api_servers with fully qualified url including scheme (http / https)
2016-12-04 05:59:18.921 1916 INFO nova.compute.claims [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -][instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] Attempting claim: memory 2048 MB, disk 20 GB, vcpus 1 CPU
2016-12-04 05:59:18.922 1916 INFO nova.compute.claims [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -][instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] Total memory: 4703 MB,used: 512.00 MB
2016-12-04 05:59:18.923 1916 INFO nova.compute.claims [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -][instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] memory limit: 7054.50 MB, free: 6542.50 MB
2016-12-04 05:59:18.924 1916 INFO nova.compute.claims [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -][instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] Total disk: 49 GB, used: 0.00 GB
2016-12-04 05:59:18.926 1916 INFO nova.compute.claims [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -][instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] disk limit: 49.00 GB, free: 49.00 GB
2016-12-04 05:59:18.927 1916 INFO nova.compute.claims [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -][instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] Total vcpu: 1 VCPU, used: 0.00 VCPU
2016-12-04 05:59:18.928 1916 INFO nova.compute.claims[req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -][instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] vcpu limit: 16.00 VCPU, free: 16.00 VCPU
2016-12-04 05:59:18.948 1916 INFO nova.compute.claims [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -][instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] Claim successful
　　3.os：image
　　2016-12-04 05:59:24.041 1916 INFO nova.virt.libvirt.driver [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] Creating image
　　backend image path: /var/lib/nova/instances/_base
　　4. network:
　　2016-12-04 05:59:41.480 1916 INFO os_vif [req-876df2cc-e1f3-48ee-94f2-b794af6d25ea ef23903614b84305bf5251915fa05d69 850808305d454ea7939724853a7c8844 - - -] Successfully plugged vif VIFBridge(active=False,address=fa:16:3e:ae:2c:2a,bridge_name='qbrbea6ecd2-c5',has_traffic_filtering=True,id=bea6ecd2-c599-405b-9871-a
　　6dcaa27ca2d,network=Network(da515db3-8dbf-4e67-833e-10c476871fa6),plugin='ovs',port_profile=VIFPortProfileBase,preserve_on_delete=False,vif_name='tapbea6ecd2-c5')
　　5.vm life cycle manage:
　　2016-12-04 05:59:51.979 1916 INFO nova.compute.manager [req-824292d4-400b-4e5f-9844-85528e330b2a - - - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] VM Started (Lifecycle Event)
2016-12-0405:59:52.953 1916 INFO nova.compute.manager [req-824292d4-400b-4e5f-9844-85528e330b2a - - - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] VM Paused (Lifecycle Event)
2016-12-0405:59:54.772 1916 INFO nova.compute.manager [req-824292d4-400b-4e5f-9844-85528e330b2a - - - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] During sync_power_state the instance has a pending task (spawning). Skip.
2016-12-04 05:59:55.1161916 INFO nova.compute.manager [req-824292d4-400b-4e5f-9844-85528e330b2a - - - - -] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] VM Resumed (Lifecycle Event)
2016-12-0405:59:55.230 1916 INFO nova.virt.libvirt.driver [-] [instance: ceb0bec9-40e9-4426-832a-3271eb8b62ab] Instance spawned successfully.