openstack-lanch an instance and nova compute log analysis
1. how to launch an instance:# 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 |
+----+-----------+-----------+------+-----------+------+-------+-------------+-----------+
# glance image-list
+--------------------------------------+--------+
| ID | Name |
+--------------------------------------+--------+
| 3efcdaf6-778a-4a81-ac9a-b76ebb21bffd | cirros |
+--------------------------------------+--------+
# 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 Auditing locally available compute resources for node localhost
2016-12-04 05:59:17.545 1916 INFO nova.compute.resource_tracker Total usable vcpus: 1, total allocated vcpus: 0
2016-12-04 05:59:17.547 1916 INFO nova.compute.resource_tracker 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 Compute_service record updated for localhost:localhost
2016-12-04 05:59:18.666 1916 WARNING nova.image.glance No protocol specified in for api_server 'http://172.125.1.10:9292', please update api_servers with fully qualified url including scheme (http / https)
2016-12-04 05:59:18.921 1916 INFO nova.compute.claims Attempting claim: memory 2048 MB, disk 20 GB, vcpus 1 CPU
2016-12-04 05:59:18.922 1916 INFO nova.compute.claims Total memory: 4703 MB, used: 512.00 MB
2016-12-04 05:59:18.923 1916 INFO nova.compute.claims memory limit: 7054.50 MB, free: 6542.50 MB
2016-12-04 05:59:18.924 1916 INFO nova.compute.claims Total disk: 49 GB, used: 0.00 GB
2016-12-04 05:59:18.926 1916 INFO nova.compute.claims disk limit: 49.00 GB, free: 49.00 GB
2016-12-04 05:59:18.927 1916 INFO nova.compute.claims Total vcpu: 1 VCPU, used: 0.00 VCPU
2016-12-04 05:59:18.928 1916 INFO nova.compute.claims vcpu limit: 16.00 VCPU, free: 16.00 VCPU
2016-12-04 05:59:18.948 1916 INFO nova.compute.claims Claim successful
2016-12-04 05:59:20.910 1916 WARNING nova.virt.osinfo 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 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 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 Creating image
2016-12-04 05:59:39.829 1916 WARNING nova.virt.osinfo Cannot find OS information - Reason: (No configuration information found for operating system Empty)
2016-12-04 05:59:41.480 1916 INFO os_vif 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 VM Started (Lifecycle Event)
2016-12-04 05:59:52.953 1916 INFO nova.compute.manager VM Paused (Lifecycle Event)
2016-12-04 05:59:54.772 1916 INFO nova.compute.manager During sync_power_state the instance has a pending task (spawning). Skip.
2016-12-04 05:59:55.116 1916 INFO nova.compute.manager VM Resumed (Lifecycle Event)
2016-12-04 05:59:55.230 1916 INFO nova.virt.libvirt.driver [-] Instance spawned successfully.
2016-12-04 05:59:55.231 1916 INFO nova.compute.manager Took 31.19 seconds to spawn the instance on the hypervisor.
2016-12-04 05:59:57.907 1916 INFO nova.compute.manager During sync_power_state the instance has a pending task (spawning). Skip.
2016-12-04 05:59:57.908 1916 INFO nova.compute.manager VM Resumed (Lifecycle Event)
2016-12-04 06:00:00.482 1916 WARNING nova.image.glance No protocol specified in for api_server 'http://172.125.1.10:9292', please update api_servers with fully qualified url including scheme (http / https)
2016-12-04 06:00:00.593 1916 INFO nova.compute.manager Took 41.93 seconds to build instance.
2016-12-04 06:00:13.006 1916 INFO nova.compute.manager 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 No protocol specified in for api_server 'http://172.125.1.10:9292', please update api_servers with fully qualified url including scheme (http / https)
2016-12-04 06:00:13.507 1916 WARNING nova.image.glance No protocol specified in for api_server 'http://172.125.1.10:9292', please update api_servers with fully qualified url including scheme (http / https)
2016-12-04 06:00:13.751 1916 WARNING nova.image.glance No protocol specified in for api_server 'http://172.125.1.10:9292', please update api_servers with fully qualified url including scheme (http / https)
2016-12-04 06:00:16.088 1916 INFO nova.compute.resource_tracker Auditing locally available compute resources for node localhost
2016-12-04 06:00:16.171 1916 WARNING nova.virt.libvirt.driver 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 Total usable vcpus: 1, total allocated vcpus: 1
2016-12-04 06:00:20.246 1916 INFO nova.compute.resource_tracker 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 Compute_service record updated for localhost:localhost
2016-12-04 06:01:16.044 1916 INFO nova.compute.resource_tracker Auditing locally available compute resources for node localhost
2016-12-04 06:01:16.200 1916 WARNING nova.virt.libvirt.driver 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 Total usable vcpus: 1, total allocated vcpus: 1
2016-12-04 06:01:23.002 1916 INFO nova.compute.resource_tracker 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 Compute_service record updated for localhost:localhost
2016-12-04 06:02:16.167 1916 INFO nova.compute.resource_tracker Auditing locally available compute resources for node localhost
2016-12-04 06:02:16.264 1916 WARNING nova.virt.libvirt.driver 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 Total usable vcpus: 1, total allocated vcpus: 1
2016-12-04 06:02:21.225 1916 INFO nova.compute.resource_tracker 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 Compute_service record updated for localhost:localhost
1. audit
鉴权是第一步
2016-12-04 05:59:15.188 1916 INFO nova.compute.resource_tracker 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 Total usable vcpus: 1, total allocated vcpus: 0
2016-12-04 05:59:17.547 1916 INFO nova.compute.resource_tracker 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 Compute_service record updated for localhost:localhost
2016-12-0405:59:18.666 1916 WARNING nova.image.glance No protocol specified in for api_server 'http://172.125.1.10:9292', please update api_servers with fully qualified url including scheme (http / https)
2016-12-04 05:59:18.921 1916 INFO nova.compute.claims Attempting claim: memory 2048 MB, disk 20 GB, vcpus 1 CPU
2016-12-04 05:59:18.922 1916 INFO nova.compute.claims Total memory: 4703 MB,used: 512.00 MB
2016-12-04 05:59:18.923 1916 INFO nova.compute.claims memory limit: 7054.50 MB, free: 6542.50 MB
2016-12-04 05:59:18.924 1916 INFO nova.compute.claims Total disk: 49 GB, used: 0.00 GB
2016-12-04 05:59:18.926 1916 INFO nova.compute.claims disk limit: 49.00 GB, free: 49.00 GB
2016-12-04 05:59:18.927 1916 INFO nova.compute.claims Total vcpu: 1 VCPU, used: 0.00 VCPU
2016-12-04 05:59:18.928 1916 INFO nova.compute.claims vcpu limit: 16.00 VCPU, free: 16.00 VCPU
2016-12-04 05:59:18.948 1916 INFO nova.compute.claims Claim successful
3.os：image
2016-12-04 05:59:24.041 1916 INFO nova.virt.libvirt.driver Creating image
backend image path: /var/lib/nova/instances/_base
4. network:
2016-12-04 05:59:41.480 1916 INFO os_vif 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 VM Started (Lifecycle Event)
2016-12-0405:59:52.953 1916 INFO nova.compute.manager VM Paused (Lifecycle Event)
2016-12-0405:59:54.772 1916 INFO nova.compute.manager During sync_power_state the instance has a pending task (spawning). Skip.
2016-12-04 05:59:55.1161916 INFO nova.compute.manager VM Resumed (Lifecycle Event)
2016-12-0405:59:55.230 1916 INFO nova.virt.libvirt.driver [-] Instance spawned successfully.
页:
[1]