Here is the log when I tried to boot from a volume created from an image. 694dab66-924a-456b-b81f-b32e319e54d6 is the volume id.
Sorry, there is Chinese in the log.


========================================Log og n-cpu==============================
2013-03-25 14:05:02.835 DEBUG nova.openstack.common.rpc.amqp [-] received {u'_context_roles': [u'admin'], u'_context_request_id': u'req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71', u'_context_quota_class': None, u'_context_project_name': u'admin', u'_context_service_catalog': [{u'endpoints': [{u'adminURL': u'http://9.119.148.37:8776/v1/74a588cd2b2247ccb84a2c81c038e4a7', u'region': u'RegionOne', u'internalURL': u'http://9.119.148.37:8776/v1/74a588cd2b2247ccb84a2c81c038e4a7', u'serviceName': u'cinder', u'id': u'09ee724452d54a6dadc109f88638f6f5', u'publicURL': u'http://9.119.148.37:8776/v1/74a588cd2b2247ccb84a2c81c038e4a7'}], u'endpoints_links': [], u'type': u'volume', u'name': u'cinder'}], u'_context_user_name': u'admin', u'_context_auth_token': '<SANITIZED>', u'args': {u'node': u'vincent-ThinkPad-T410', u'request_spec': {u'block_device_mapping': [{u'volume_id': u'694dab66-924a-456b-b81f-b32e319e54d6', u'device_name': u'vda'}], u'image': {}, u'instance_type': {u'disabled': False, u'root_gb': 0, u'name': u'm1.tiny', u'flavorid': u'1', u'deleted': 0, u'created_at': None, u'ephemeral_gb': 0, u'updated_at': None, u'memory_mb': 512, u'vcpus': 1, u'extra_specs': {}, u'swap': 0, u'rxtx_factor': 1.0, u'is_public': True, u'deleted_at': None, u'vcpu_weight': None, u'id': 2}, u'instance_properties': {u'vm_state': u'building', u'availability_zone': None, u'launch_index': 0, u'ephemeral_gb': 0, u'instance_type_id': 2, u'user_data': None, u'vm_mode': None, u'reservation_id': u'r-ov9t16k2', u'config_drive_id': u'', u'root_device_name': None, u'user_id': u'c396313a5b7b4cf89ac180ee1dc918d7', u'display_description': u'VM_test', u'key_data': None, u'power_state': 0, u'progress': 0, u'project_id': u'74a588cd2b2247ccb84a2c81c038e4a7', u'config_drive': u'', u'ramdisk_id': u'', u'access_ip_v6': None, u'access_ip_v4': None, u'kernel_id': u'', u'key_name': None, u'display_name': u'VM_test', u'system_metadata': {u'instance_type_memory_mb': 512, u'instance_type_swap': 0, u'instance_type_vcpu_weight': None, u'instance_type_root_gb': 0, u'instance_type_name': u'm1.tiny', u'instance_type_id': 2, u'instance_type_ephemeral_gb': 0, u'instance_type_rxtx_factor': 1.0, u'instance_type_flavorid': u'1', u'instance_type_vcpus': 1, u'image_base_image_ref': u''}, u'root_gb': 0, u'locked': False, u'launch_time': u'2013-03-25T06:05:02Z', u'memory_mb': 512, u'vcpus': 1, u'image_ref': u'', u'architecture': None, u'auto_disk_config': None, u'os_type': None, u'metadata': {}}, u'security_group': [u'default'], u'instance_uuids': [u'61ce878b-1836-4bf7-b220-134e24aa6100']}, u'requested_networks': None, u'filter_properties': {u'config_options': {}, u'limits': {u'memory_mb': 5724.0}, u'request_spec': {u'block_device_mapping': [{u'volume_id': u'694dab66-924a-456b-b81f-b32e319e54d6', u'device_name': u'vda'}], u'image': {}, u'instance_type': {u'disabled': False, u'root_gb': 0, u'name': u'm1.tiny', u'flavorid': u'1', u'deleted': 0, u'created_at': None, u'ephemeral_gb': 0, u'updated_at': None, u'memory_mb': 512, u'vcpus': 1, u'extra_specs': {}, u'swap': 0, u'rxtx_factor': 1.0, u'is_public': True, u'deleted_at': None, u'vcpu_weight': None, u'id': 2}, u'instance_properties': {u'vm_state': u'building', u'availability_zone': None, u'launch_index': 0, u'ephemeral_gb': 0, u'instance_type_id': 2, u'user_data': None, u'vm_mode': None, u'reservation_id': u'r-ov9t16k2', u'config_drive_id': u'', u'root_device_name': None, u'user_id': u'c396313a5b7b4cf89ac180ee1dc918d7', u'display_description': u'VM_test', u'key_data': None, u'power_state': 0, u'progress': 0, u'project_id': u'74a588cd2b2247ccb84a2c81c038e4a7', u'config_drive': u'', u'ramdisk_id': u'', u'access_ip_v6': None, u'access_ip_v4': None, u'kernel_id': u'', u'key_name': None, u'display_name': u'VM_test', u'system_metadata': {u'instance_type_memory_mb': 512, u'instance_type_swap': 0, u'instance_type_vcpu_weight': None, u'instance_type_root_gb': 0, u'instance_type_name': u'm1.tiny', u'instance_type_id': 2, u'instance_type_ephemeral_gb': 0, u'instance_type_rxtx_factor': 1.0, u'instance_type_flavorid': u'1', u'instance_type_vcpus': 1, u'image_base_image_ref': u''}, u'root_gb': 0, u'locked': False, u'launch_time': u'2013-03-25T06:05:02Z', u'memory_mb': 512, u'vcpus': 1, u'image_ref': u'', u'architecture': None, u'auto_disk_config': None, u'os_type': None, u'metadata': {}}, u'security_group': [u'default'], u'instance_uuids': [u'61ce878b-1836-4bf7-b220-134e24aa6100']}, u'instance_type': {u'disabled': False, u'root_gb': 0, u'name': u'm1.tiny', u'flavorid': u'1', u'deleted': 0, u'created_at': None, u'ephemeral_gb': 0, u'updated_at': None, u'memory_mb': 512, u'vcpus': 1, u'extra_specs': {}, u'swap': 0, u'rxtx_factor': 1.0, u'is_public': True, u'deleted_at': None, u'vcpu_weight': None, u'id': 2}, u'retry': {u'num_attempts': 1, u'hosts': [[u'vincent-ThinkPad-T410', u'vincent-ThinkPad-T410']]}, u'os_type': None, u'project_id': u'74a588cd2b2247ccb84a2c81c038e4a7', u'scheduler_hints': {}}, u'instance': {u'vm_state': u'building', u'availability_zone': None, u'terminated_at': None, u'ephemeral_gb': 0, u'instance_type_id': 2, u'user_data': None, u'vm_mode': None, u'deleted_at': None, u'reservation_id': u'r-ov9t16k2', u'id': 6, u'security_groups': [{u'deleted_at': None, u'user_id': u'c396313a5b7b4cf89ac180ee1dc918d7', u'name': u'default', u'deleted': 0, u'created_at': u'2013-03-25T03:40:27.000000', u'updated_at': None, u'rules': [], u'project_id': u'74a588cd2b2247ccb84a2c81c038e4a7', u'id': 1, u'description': u'default'}], u'disable_terminate': False, u'user_id': u'c396313a5b7b4cf89ac180ee1dc918d7', u'uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'default_swap_device': None, u'info_cache': {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'network_info': u'[]', u'deleted_at': None, u'id': 6}, u'hostname': u'vm-test', u'launched_on': None, u'display_description': u'VM_test', u'key_data': None, u'kernel_id': u'', u'power_state': 0, u'default_ephemeral_device': None, u'progress': 0, u'project_id': u'74a588cd2b2247ccb84a2c81c038e4a7', u'launched_at': None, u'scheduled_at': u'2013-03-25T06:05:02.635066', u'node': None, u'ramdisk_id': u'', u'access_ip_v6': None, u'access_ip_v4': None, u'deleted': 0, u'key_name': None, u'updated_at': u'2013-03-25T06:05:02.669657', u'host': None, u'architecture': None, u'display_name': u'VM_test', u'system_metadata': [{u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'512', u'key': u'instance_type_memory_mb', u'deleted_at': None, u'id': 58}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'0', u'key': u'instance_type_swap', u'deleted_at': None, u'id': 59}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': None, u'key': u'instance_type_vcpu_weight', u'deleted_at': None, u'id': 60}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'0', u'key': u'instance_type_root_gb', u'deleted_at': None, u'id': 61}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'2', u'key': u'instance_type_id', u'deleted_at': None, u'id': 62}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'm1.tiny', u'key': u'instance_type_name', u'deleted_at': None, u'id': 63}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'0', u'key': u'instance_type_ephemeral_gb', u'deleted_at': None, u'id': 64}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'1', u'key': u'instance_type_rxtx_factor', u'deleted_at': None, u'id': 65}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'1', u'key': u'instance_type_flavorid', u'deleted_at': None, u'id': 66}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'1', u'key': u'instance_type_vcpus', u'deleted_at': None, u'id': 67}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'', u'key': u'image_base_image_ref', u'deleted_at': None, u'id': 68}], u'task_state': u'scheduling', u'shutdown_terminate': False, u'cell_name': None, u'root_gb': 0, u'locked': False, u'name': u'instance-00000006', u'created_at': u'2013-03-25T06:05:02.000000', u'launch_index': 0, u'metadata': [], u'memory_mb': 512, u'vcpus': 1, u'image_ref': u'', u'root_device_name': None, u'auto_disk_config': None, u'os_type': None, u'config_drive': u''}, u'admin_password': '<SANITIZED>', u'injected_files': [], u'is_first_time': True}, u'_context_tenant': u'74a588cd2b2247ccb84a2c81c038e4a7', u'_context_instance_lock_checked': False, u'_context_timestamp': u'2013-03-25T06:05:01.991644', u'_context_is_admin': True, u'version': u'2.19', u'_context_project_id': u'74a588cd2b2247ccb84a2c81c038e4a7', u'_context_user': u'c396313a5b7b4cf89ac180ee1dc918d7', u'_unique_id': u'ec629ef3bba5487b843711ea5b9d88f1', u'_context_read_deleted': u'no', u'_context_user_id': u'c396313a5b7b4cf89ac180ee1dc918d7', u'method': u'run_instance', u'_context_remote_address': u'9.119.148.37'} from (pid=17829) _safe_log /opt/stack/nova/nova/openstack/common/rpc/common.py:276
2013-03-25 14:05:02.836 DEBUG nova.openstack.common.rpc.amqp [-] unpacked context: {'read_deleted': u'no', 'project_name': u'admin', 'user_id': u'c396313a5b7b4cf89ac180ee1dc918d7', 'roles': [u'admin'], 'timestamp': u'2013-03-25T06:05:01.991644', 'auth_token': '<SANITIZED>', 'remote_address': u'9.119.148.37', 'quota_class': None, 'is_admin': True, 'user': u'c396313a5b7b4cf89ac180ee1dc918d7', 'service_catalog': [{u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://9.119.148.37:8776/v1/74a588cd2b2247ccb84a2c81c038e4a7', u'region': u'RegionOne', u'id': u'09ee724452d54a6dadc109f88638f6f5', u'serviceName': u'cinder', u'internalURL': u'http://9.119.148.37:8776/v1/74a588cd2b2247ccb84a2c81c038e4a7', u'publicURL': u'http://9.119.148.37:8776/v1/74a588cd2b2247ccb84a2c81c038e4a7'}], u'type': u'volume', u'name': u'cinder'}], 'request_id': u'req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71', 'instance_lock_checked': False, 'project_id': u'74a588cd2b2247ccb84a2c81c038e4a7', 'user_name': u'admin', 'tenant': u'74a588cd2b2247ccb84a2c81c038e4a7'} from (pid=17829) _safe_log /opt/stack/nova/nova/openstack/common/rpc/common.py:276
2013-03-25 14:05:02.838 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:02.838 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is 47d01b12c08748b68ddd83984c4a613a from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:02.839 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is 993de216371d4c108f48e7456a9ff13c. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:03.012 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Got semaphore "61ce878b-1836-4bf7-b220-134e24aa6100" for method "do_run_instance"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:185
libvir: QEMU Driver error : Domain not found: no domain with matching name 'instance-00000006'
2013-03-25 14:05:03.017 AUDIT nova.compute.manager [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] 正在启动虚拟机
2013-03-25 14:05:03.017 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:03.018 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is 2836bb3a22c143f2bfbe10f14390f11d from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:03.019 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is 05b4cf7ef4754182bb8f00aeac3bde54. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:03.109 DEBUG nova.openstack.common.rpc.amqp [-] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:03.109 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is 3c6f3a1e702840e58bdcc170b39bd8b8 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:03.109 DEBUG nova.openstack.common.rpc.amqp [-] UNIQUE_ID is 3826454825d14d4bac216463022dead5. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:03.253 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:03.254 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is e9e15451160f40e888d3b5e2f2568271 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:03.254 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is 8fff5159a6624850b9600e2cfa62ae2d. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:03.399 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Got semaphore "compute_resources" for method "instance_claim"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:185
2013-03-25 14:05:03.406 AUDIT nova.compute.claims [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] Attempting claim: memory 512 MB, disk 0 GB, VCPUs 1
2013-03-25 14:05:03.406 AUDIT nova.compute.claims [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] Total Memory: 3816 MB, used: 512 MB
2013-03-25 14:05:03.407 AUDIT nova.compute.claims [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] Memory limit: 5724 MB, free: 5212 MB
2013-03-25 14:05:03.407 AUDIT nova.compute.claims [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] Total Disk: 36 GB, used: 0 GB
2013-03-25 14:05:03.407 AUDIT nova.compute.claims [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] Disk limit not specified, defaulting to unlimited
2013-03-25 14:05:03.408 AUDIT nova.compute.claims [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] Total CPU: 4 VCPUs, used: 0 VCPUs
2013-03-25 14:05:03.408 AUDIT nova.compute.claims [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] CPU limit not specified, defaulting to unlimited
2013-03-25 14:05:03.408 AUDIT nova.compute.claims [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] Claim successful
2013-03-25 14:05:03.409 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:03.409 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is f4e2e56b639a4eb28a40789e83f738cd from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:03.410 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is 16285fbae17240458175c52750f16c0e. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:03.618 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:03.619 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is 8557a3f3e0484f94ad395f71b3d180c6 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:03.619 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is af42003b82774fd6a5624e2d6b68fe75. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:03.844 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:03.845 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is 4175cf6bc98a43ad8dfcfb74082695b4 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:03.845 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is 5869658083e3448cb555067f702043d2. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:04.077 DEBUG nova.virt.libvirt.driver [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在更新主机状态 from (pid=17829) update_status /opt/stack/nova/nova/virt/libvirt/driver.py:3690
2013-03-25 14:05:04.099 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Got semaphore "compute_resources" for method "update_usage"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:185
2013-03-25 14:05:04.101 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:04.102 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is 78900b397b5648a68a52dbfd6334c253 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:04.103 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is 4c75a10ef4554785bd1546cf4f68a530. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:04.333 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on network.vincent-ThinkPad-T410 ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:04.334 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is 5407241812684856ad527cd5e7abf79d from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:04.334 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is 00416660dcd64a4b8255a8ef63231446. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:07.940 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:07.941 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is e4186fc11a254727b6b9c8d637f2add3 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:07.942 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is a90f9a04ab864ab29d796ce4af50b951. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:08.111 DEBUG nova.compute.manager [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] 实例的network_info：|[VIF({'ovs_interfaceid': None, 'network': Network({'bridge': u'br100', 'subnets': [Subnet({'ips': [FixedIP({'meta': {}, 'version': 4, 'type': u'fixed', 'floating_ips': [], 'address': u'10.0.0.2'})], 'version': 4, 'meta': {u'dhcp_server': u'10.0.0.3'}, 'dns': [IP({'meta': {}, 'version': 4, 'type': u'dns', 'address': u'8.8.4.4'})], 'routes': [], 'cidr': u'10.0.0.0/24', 'gateway': IP({'meta': {}, 'version': 4, 'type': u'gateway', 'address': u'10.0.0.1'})}), Subnet({'ips': [], 'version': None, 'meta': {u'dhcp_server': u'10.0.0.3'}, 'dns': [], 'routes': [], 'cidr': None, 'gateway': IP({'meta': {}, 'version': None, 'type': u'gateway', 'address': None})})], 'meta': {u'tenant_id': None, u'multi_host': True, u'should_create_bridge': True, u'bridge_interface': u'br100'}, 'id': u'e486213d-e3f6-49e1-ba0f-aa8f9ccc3f4b', 'label': u'private'}), 'devname': None, 'qbh_params': None, 'meta': {}, 'address': u'fa:16:3e:ec:3d:3b', 'type': u'bridge', 'id': u'e1d4a1c2-8738-4db2-82c9-2e07e94bbb1a', 'qbg_params': None})]| from (pid=17829) _allocate_network /opt/stack/nova/nova/compute/manager.py:1036
2013-03-25 14:05:08.112 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:08.112 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is 4398729e0e8b4092a36e5891654b2127 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:08.113 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is fb016ecece42496abf6a5a95f01252f0. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:08.315 DEBUG nova.virt.libvirt.driver [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在更新主机状态 from (pid=17829) update_status /opt/stack/nova/nova/virt/libvirt/driver.py:3690
2013-03-25 14:05:08.330 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Got semaphore "compute_resources" for method "update_usage"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:185
2013-03-25 14:05:08.331 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:08.331 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is fcb2948135ad43f598be97b9d5e856de from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:08.331 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is 9678202e5c3a471f877ad5c49216fa15. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:08.539 DEBUG nova.compute.manager [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] 正在设置 bdm {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'virtual_name': None, u'no_device': None, u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'volume_id': u'694dab66-924a-456b-b81f-b32e319e54d6', u'updated_at': None, u'device_name': u'vda', u'connection_info': None, u'volume_size': None, u'snapshot_id': None, u'id': 8, u'deleted_at': None, u'delete_on_termination': False} from (pid=17829) _setup_block_device_mapping /opt/stack/nova/nova/compute/manager.py:674
2013-03-25 14:05:08.540 DEBUG nova.volume.cinder [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Cinderclient connection created using URL: http://9.119.148.37:8776/v1/74a588cd2b2247ccb84a2c81c038e4a7 from (pid=17829) cinderclient /opt/stack/nova/nova/volume/cinder.py:94
2013-03-25 14:05:08.628 AUDIT nova.compute.manager [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] 卷 694dab66-924a-456b-b81f-b32e319e54d6 正在 vda 上启动
2013-03-25 14:05:08.629 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在运行cmd (subprocess)：sudo nova-rootwrap /etc/nova/rootwrap.conf systool -c fc_host -v from (pid=17829) execute /opt/stack/nova/nova/utils.py:208
2013-03-25 14:05:08.792 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 运行结果为 1 from (pid=17829) execute /opt/stack/nova/nova/utils.py:232
2013-03-25 14:05:08.793 DEBUG nova.virt.libvirt.driver [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] Could not determine fibre channel world wide node names from (pid=17829) get_volume_connector /opt/stack/nova/nova/virt/libvirt/driver.py:902
2013-03-25 14:05:08.794 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在运行cmd (subprocess)：sudo nova-rootwrap /etc/nova/rootwrap.conf systool -c fc_host -v from (pid=17829) execute /opt/stack/nova/nova/utils.py:208
2013-03-25 14:05:08.969 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 运行结果为 1 from (pid=17829) execute /opt/stack/nova/nova/utils.py:232
2013-03-25 14:05:08.970 DEBUG nova.virt.libvirt.driver [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] Could not determine fibre channel world wide port names from (pid=17829) get_volume_connector /opt/stack/nova/nova/virt/libvirt/driver.py:909
2013-03-25 14:05:08.971 DEBUG nova.volume.cinder [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Cinderclient connection created using URL: http://9.119.148.37:8776/v1/74a588cd2b2247ccb84a2c81c038e4a7 from (pid=17829) cinderclient /opt/stack/nova/nova/volume/cinder.py:94
2013-03-25 14:05:09.167 DEBUG nova.volume.cinder [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Cinderclient connection created using URL: http://9.119.148.37:8776/v1/74a588cd2b2247ccb84a2c81c038e4a7 from (pid=17829) cinderclient /opt/stack/nova/nova/volume/cinder.py:94
2013-03-25 14:05:09.539 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:09.540 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is 645fe6d3c3e44e70b456da1edd9b0645 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:09.541 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is cda81211701448a48d094689d2c0a503. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:09.713 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:09.713 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is cb0187c9d12b4998b478f4b627e8c943 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:09.714 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is 4515887c92124246803a9e87089fbee1. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:09.988 DEBUG nova.virt.libvirt.driver [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在更新主机状态 from (pid=17829) update_status /opt/stack/nova/nova/virt/libvirt/driver.py:3690
2013-03-25 14:05:10.011 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Got semaphore "compute_resources" for method "update_usage"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:185
2013-03-25 14:05:10.013 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:10.014 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is 3210cac44ee64e6fb85abe11e8b45f2e from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:10.014 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is 7a3f638c8c0b44218c7e7f8672020303. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:10.232 DEBUG nova.block_device [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 块设备列表 block_device_list [u'vda'] from (pid=17829) volume_in_mapping /opt/stack/nova/nova/block_device.py:167
2013-03-25 14:05:10.233 DEBUG nova.block_device [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 块设备列表 block_device_list [u'vda'] from (pid=17829) volume_in_mapping /opt/stack/nova/nova/block_device.py:167
2013-03-25 14:05:10.234 DEBUG nova.virt.libvirt.driver [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Start to_xml instance={u'vm_state': u'building', u'availability_zone': None, u'terminated_at': None, u'ephemeral_gb': 0, u'instance_type_id': 2, u'user_data': None, u'vm_mode': None, u'deleted_at': None, u'reservation_id': u'r-ov9t16k2', u'id': 6, u'security_groups': [{u'project_id': u'74a588cd2b2247ccb84a2c81c038e4a7', u'user_id': u'c396313a5b7b4cf89ac180ee1dc918d7', u'name': u'default', u'deleted': 0, u'created_at': u'2013-03-25T03:40:27.000000', u'updated_at': None, u'rules': [], u'deleted_at': None, u'id': 1, u'description': u'default'}], u'disable_terminate': False, u'root_device_name': None, u'user_id': u'c396313a5b7b4cf89ac180ee1dc918d7', u'uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'default_swap_device': None, u'info_cache': {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': u'2013-03-25T06:05:07.000000', u'network_info': u'[{"ovs_interfaceid": null, "network": {"bridge": "br100", "subnets": [{"ips": [{"meta": {}, "version": 4, "type": "fixed", "floating_ips": [], "address": "10.0.0.2"}], "version": 4, "meta": {"dhcp_server": "10.0.0.3"}, "dns": [{"meta": {}, "version": 4, "type": "dns", "address": "8.8.4.4"}], "routes": [], "cidr": "10.0.0.0/24", "gateway": {"meta": {}, "version": 4, "type": "gateway", "address": "10.0.0.1"}}, {"ips": [], "version": null, "meta": {"dhcp_server": "10.0.0.3"}, "dns": [], "routes": [], "cidr": null, "gateway": {"meta": {}, "version": null, "type": "gateway", "address": null}}], "meta": {"tenant_id": null, "multi_host": true, "should_create_bridge": true, "bridge_interface": "br100"}, "id": "e486213d-e3f6-49e1-ba0f-aa8f9ccc3f4b", "label": "private"}, "devname": null, "qbh_params": null, "meta": {}, "address": "fa:16:3e:ec:3d:3b", "type": "bridge", "id": "e1d4a1c2-8738-4db2-82c9-2e07e94bbb1a", "qbg_params": null}]', u'deleted_at': None, u'id': 6}, u'hostname': u'vm-test', u'launched_on': u'vincent-ThinkPad-T410', u'display_description': u'VM_test', u'key_data': None, u'deleted': 0, u'scheduled_at': u'2013-03-25T06:05:02.000000', u'power_state': 0, u'default_ephemeral_device': None, u'progress': 0, u'project_id': u'74a588cd2b2247ccb84a2c81c038e4a7', u'launched_at': None, u'config_drive': u'', u'node': u'vincent-ThinkPad-T410', u'ramdisk_id': u'', u'access_ip_v6': None, u'access_ip_v4': None, u'kernel_id': u'', u'key_name': None, u'updated_at': u'2013-03-25T06:05:09.861700', u'host': u'vincent-ThinkPad-T410', u'display_name': u'VM_test', u'system_metadata': [{u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'512', u'key': u'instance_type_memory_mb', u'deleted_at': None, u'id': 58}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'0', u'key': u'instance_type_swap', u'deleted_at': None, u'id': 59}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': None, u'key': u'instance_type_vcpu_weight', u'deleted_at': None, u'id': 60}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'0', u'key': u'instance_type_root_gb', u'deleted_at': None, u'id': 61}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'2', u'key': u'instance_type_id', u'deleted_at': None, u'id': 62}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'm1.tiny', u'key': u'instance_type_name', u'deleted_at': None, u'id': 63}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'0', u'key': u'instance_type_ephemeral_gb', u'deleted_at': None, u'id': 64}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'1', u'key': u'instance_type_rxtx_factor', u'deleted_at': None, u'id': 65}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'1', u'key': u'instance_type_flavorid', u'deleted_at': None, u'id': 66}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'1', u'key': u'instance_type_vcpus', u'deleted_at': None, u'id': 67}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'', u'key': u'image_base_image_ref', u'deleted_at': None, u'id': 68}], u'task_state': u'spawning', u'shutdown_terminate': False, u'cell_name': None, u'root_gb': 0, u'locked': False, u'name': u'instance-00000006', u'created_at': u'2013-03-25T06:05:02.000000', u'launch_index': 0, u'memory_mb': 512, u'vcpus': 1, u'image_ref': u'', u'architecture': None, u'auto_disk_config': None, u'os_type': None, u'metadata': []} network_info=[({'injected': False, 'bridge': u'br100', 'multi_host': True, 'bridge_interface': u'br100', 'cidr_v6': None, 'cidr': u'10.0.0.0/24', 'vlan': None, 'id': u'e486213d-e3f6-49e1-ba0f-aa8f9ccc3f4b'}, {'vif_type': u'bridge', 'should_create_bridge': True, 'vif_devname': None, 'broadcast': '10.0.0.255', 'ips': [{'ip': u'10.0.0.2', 'netmask': '255.255.255.0', 'enabled': '1', 'gateway': u'10.0.0.1'}], 'mac': u'fa:16:3e:ec:3d:3b', 'should_create_vlan': False, 'gateway': u'10.0.0.1', 'ovs_interfaceid': None, 'rxtx_cap': 0, 'ip6s': [], 'label': u'private', 'qbh_params': None, 'vif_uuid': u'e1d4a1c2-8738-4db2-82c9-2e07e94bbb1a', 'dns': [u'8.8.4.4'], 'gateway_v6': None, 'dhcp_server': u'10.0.0.3', 'qbg_params': None})] disk_info={'disk_bus': 'virtio', 'cdrom_bus': 'ide', 'mapping': {u'vda': {'bus': 'virtio', 'type': 'disk', 'dev': u'vda'}, 'root': {'bus': 'virtio', 'type': 'disk', 'dev': 'vda'}}} image_meta={} rescue=Noneblock_device_info={'block_device_mapping': [{'connection_info': {u'driver_volume_type': u'iscsi', 'serial': u'694dab66-924a-456b-b81f-b32e319e54d6', u'data': {u'target_discovered': False, u'target_iqn': u'iqn.2010-10.org.openstack:volume-694dab66-924a-456b-b81f-b32e319e54d6', u'target_portal': u'9.119.148.37:3260', u'volume_id': u'694dab66-924a-456b-b81f-b32e319e54d6', u'target_lun': 1, u'auth_password': u'qa7ebAaJpfs3hj39YYSY', u'auth_username': u'QUUxBAE9wstH57oqL5FU', u'auth_method': u'CHAP'}}, 'mount_device': u'vda', 'delete_on_termination': False}], 'root_device_name': None, 'ephemerals': [], 'swap': None} from (pid=17829) to_xml /opt/stack/nova/nova/virt/libvirt/driver.py:2324
2013-03-25 14:05:10.235 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:10.235 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is 51dc950d8bd344b8a6a7483e3bc9e186 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:10.236 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is 8dd1e1e463514a2fb7aff9d27ec8e55d. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:10.388 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:10.389 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is 716785d7239a4167be77ff154d738660 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:10.390 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is 5f5259fb5b2f4db3bfe3be2710c3a2b3. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:10.616 DEBUG nova.virt.libvirt.driver [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在更新主机状态 from (pid=17829) update_status /opt/stack/nova/nova/virt/libvirt/driver.py:3690
2013-03-25 14:05:10.632 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Got semaphore "compute_resources" for method "update_usage"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:185
2013-03-25 14:05:10.633 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:10.634 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is aa18f18f957c43f492f242f2a9391536 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:10.634 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is 7355126bd2da4665ad24c4bda0dcf08a. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:10.830 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Got semaphore "connect_volume" for method "connect_volume"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:185
2013-03-25 14:05:10.831 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在运行cmd (subprocess)：sudo nova-rootwrap /etc/nova/rootwrap.conf iscsiadm -m node -T iqn.2010-10.org.openstack:volume-694dab66-924a-456b-b81f-b32e319e54d6 -p 9.119.148.37:3260 from (pid=17829) execute /opt/stack/nova/nova/utils.py:208
2013-03-25 14:05:11.009 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 运行结果为 255 from (pid=17829) execute /opt/stack/nova/nova/utils.py:232
2013-03-25 14:05:11.011 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在运行cmd (subprocess)：sudo nova-rootwrap /etc/nova/rootwrap.conf iscsiadm -m node -T iqn.2010-10.org.openstack:volume-694dab66-924a-456b-b81f-b32e319e54d6 -p 9.119.148.37:3260 --op new from (pid=17829) execute /opt/stack/nova/nova/utils.py:208
2013-03-25 14:05:11.199 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 运行结果为 0 from (pid=17829) execute /opt/stack/nova/nova/utils.py:232
2013-03-25 14:05:11.200 DEBUG nova.virt.libvirt.volume [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] iscsiadm ('--op', 'new'): stdout=New iSCSI node [tcp:[hw=,ip=,net_if=,iscsi_if=default] 9.119.148.37,3260,-1 iqn.2010-10.org.openstack:volume-694dab66-924a-456b-b81f-b32e319e54d6] added
 stderr= from (pid=17829) _run_iscsiadm /opt/stack/nova/nova/virt/libvirt/volume.py:181
2013-03-25 14:05:11.201 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在运行cmd (subprocess)：sudo nova-rootwrap /etc/nova/rootwrap.conf iscsiadm -m node -T iqn.2010-10.org.openstack:volume-694dab66-924a-456b-b81f-b32e319e54d6 -p 9.119.148.37:3260 --op update -n node.session.auth.authmethod -v CHAP from (pid=17829) execute /opt/stack/nova/nova/utils.py:208
2013-03-25 14:05:11.380 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 运行结果为 0 from (pid=17829) execute /opt/stack/nova/nova/utils.py:232
2013-03-25 14:05:11.380 DEBUG nova.virt.libvirt.volume [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] iscsiadm ('--op', 'update', '-n', 'node.session.auth.authmethod', '-v', u'CHAP'): stdout= stderr= from (pid=17829) _run_iscsiadm /opt/stack/nova/nova/virt/libvirt/volume.py:181
2013-03-25 14:05:11.381 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在运行cmd (subprocess)：sudo nova-rootwrap /etc/nova/rootwrap.conf iscsiadm -m node -T iqn.2010-10.org.openstack:volume-694dab66-924a-456b-b81f-b32e319e54d6 -p 9.119.148.37:3260 --op update -n node.session.auth.username -v QUUxBAE9wstH57oqL5FU from (pid=17829) execute /opt/stack/nova/nova/utils.py:208
2013-03-25 14:05:11.583 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 运行结果为 0 from (pid=17829) execute /opt/stack/nova/nova/utils.py:232
2013-03-25 14:05:11.584 DEBUG nova.virt.libvirt.volume [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] iscsiadm ('--op', 'update', '-n', 'node.session.auth.username', '-v', u'QUUxBAE9wstH57oqL5FU'): stdout= stderr= from (pid=17829) _run_iscsiadm /opt/stack/nova/nova/virt/libvirt/volume.py:181
2013-03-25 14:05:11.585 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在运行cmd (subprocess)：sudo nova-rootwrap /etc/nova/rootwrap.conf iscsiadm -m node -T iqn.2010-10.org.openstack:volume-694dab66-924a-456b-b81f-b32e319e54d6 -p 9.119.148.37:3260 --op update -n node.session.auth.password -v qa7ebAaJpfs3hj39YYSY from (pid=17829) execute /opt/stack/nova/nova/utils.py:208
2013-03-25 14:05:11.769 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 运行结果为 0 from (pid=17829) execute /opt/stack/nova/nova/utils.py:232
2013-03-25 14:05:11.770 DEBUG nova.virt.libvirt.volume [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] iscsiadm ('--op', 'update', '-n', 'node.session.auth.password', '-v', u'qa7ebAaJpfs3hj39YYSY'): stdout= stderr= from (pid=17829) _run_iscsiadm /opt/stack/nova/nova/virt/libvirt/volume.py:181
2013-03-25 14:05:11.770 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在运行cmd (subprocess)：sudo nova-rootwrap /etc/nova/rootwrap.conf iscsiadm -m session from (pid=17829) execute /opt/stack/nova/nova/utils.py:208
2013-03-25 14:05:11.972 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 运行结果为 0 from (pid=17829) execute /opt/stack/nova/nova/utils.py:232
2013-03-25 14:05:11.973 DEBUG nova.virt.libvirt.volume [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] iscsiadm ['-m', 'session']: stdout= stderr=iscsiadm: No active sessions.
 from (pid=17829) _run_iscsiadm_bare /opt/stack/nova/nova/virt/libvirt/volume.py:422
2013-03-25 14:05:11.973 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在运行cmd (subprocess)：sudo nova-rootwrap /etc/nova/rootwrap.conf iscsiadm -m node -T iqn.2010-10.org.openstack:volume-694dab66-924a-456b-b81f-b32e319e54d6 -p 9.119.148.37:3260 --login from (pid=17829) execute /opt/stack/nova/nova/utils.py:208
2013-03-25 14:05:12.923 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 运行结果为 0 from (pid=17829) execute /opt/stack/nova/nova/utils.py:232
2013-03-25 14:05:12.924 DEBUG nova.virt.libvirt.volume [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] iscsiadm ('--login',): stdout=Logging in to [iface: default, target: iqn.2010-10.org.openstack:volume-694dab66-924a-456b-b81f-b32e319e54d6, portal: 9.119.148.37,3260]
Login to [iface: default, target: iqn.2010-10.org.openstack:volume-694dab66-924a-456b-b81f-b32e319e54d6, portal: 9.119.148.37,3260]: successful
 stderr= from (pid=17829) _run_iscsiadm /opt/stack/nova/nova/virt/libvirt/volume.py:181
2013-03-25 14:05:12.924 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在运行cmd (subprocess)：sudo nova-rootwrap /etc/nova/rootwrap.conf iscsiadm -m node -T iqn.2010-10.org.openstack:volume-694dab66-924a-456b-b81f-b32e319e54d6 -p 9.119.148.37:3260 --op update -n node.startup -v automatic from (pid=17829) execute /opt/stack/nova/nova/utils.py:208
2013-03-25 14:05:13.109 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 运行结果为 0 from (pid=17829) execute /opt/stack/nova/nova/utils.py:232
2013-03-25 14:05:13.109 DEBUG nova.virt.libvirt.volume [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] iscsiadm ('--op', 'update', '-n', 'node.startup', '-v', 'automatic'): stdout= stderr= from (pid=17829) _run_iscsiadm /opt/stack/nova/nova/virt/libvirt/volume.py:181
2013-03-25 14:05:13.110 DEBUG nova.virt.libvirt.vif [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] vif_type=bridge instance={u'vm_state': u'building', u'availability_zone': None, u'terminated_at': None, u'ephemeral_gb': 0, u'instance_type_id': 2, u'user_data': None, u'vm_mode': None, u'deleted_at': None, u'reservation_id': u'r-ov9t16k2', u'id': 6, u'security_groups': [{u'project_id': u'74a588cd2b2247ccb84a2c81c038e4a7', u'user_id': u'c396313a5b7b4cf89ac180ee1dc918d7', u'name': u'default', u'deleted': 0, u'created_at': u'2013-03-25T03:40:27.000000', u'updated_at': None, u'rules': [], u'deleted_at': None, u'id': 1, u'description': u'default'}], u'disable_terminate': False, u'root_device_name': None, u'user_id': u'c396313a5b7b4cf89ac180ee1dc918d7', u'uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'default_swap_device': None, u'info_cache': {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': u'2013-03-25T06:05:07.000000', u'network_info': u'[{"ovs_interfaceid": null, "network": {"bridge": "br100", "subnets": [{"ips": [{"meta": {}, "version": 4, "type": "fixed", "floating_ips": [], "address": "10.0.0.2"}], "version": 4, "meta": {"dhcp_server": "10.0.0.3"}, "dns": [{"meta": {}, "version": 4, "type": "dns", "address": "8.8.4.4"}], "routes": [], "cidr": "10.0.0.0/24", "gateway": {"meta": {}, "version": 4, "type": "gateway", "address": "10.0.0.1"}}, {"ips": [], "version": null, "meta": {"dhcp_server": "10.0.0.3"}, "dns": [], "routes": [], "cidr": null, "gateway": {"meta": {}, "version": null, "type": "gateway", "address": null}}], "meta": {"tenant_id": null, "multi_host": true, "should_create_bridge": true, "bridge_interface": "br100"}, "id": "e486213d-e3f6-49e1-ba0f-aa8f9ccc3f4b", "label": "private"}, "devname": null, "qbh_params": null, "meta": {}, "address": "fa:16:3e:ec:3d:3b", "type": "bridge", "id": "e1d4a1c2-8738-4db2-82c9-2e07e94bbb1a", "qbg_params": null}]', u'deleted_at': None, u'id': 6}, u'hostname': u'vm-test', u'launched_on': u'vincent-ThinkPad-T410', u'display_description': u'VM_test', u'key_data': None, u'deleted': 0, u'scheduled_at': u'2013-03-25T06:05:02.000000', u'power_state': 0, u'default_ephemeral_device': None, u'progress': 0, u'project_id': u'74a588cd2b2247ccb84a2c81c038e4a7', u'launched_at': None, u'config_drive': u'', u'node': u'vincent-ThinkPad-T410', u'ramdisk_id': u'', u'access_ip_v6': None, u'access_ip_v4': None, u'kernel_id': u'', u'key_name': None, u'updated_at': u'2013-03-25T06:05:09.861700', u'host': u'vincent-ThinkPad-T410', u'display_name': u'VM_test', u'system_metadata': [{u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'512', u'key': u'instance_type_memory_mb', u'deleted_at': None, u'id': 58}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'0', u'key': u'instance_type_swap', u'deleted_at': None, u'id': 59}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': None, u'key': u'instance_type_vcpu_weight', u'deleted_at': None, u'id': 60}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'0', u'key': u'instance_type_root_gb', u'deleted_at': None, u'id': 61}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'2', u'key': u'instance_type_id', u'deleted_at': None, u'id': 62}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'm1.tiny', u'key': u'instance_type_name', u'deleted_at': None, u'id': 63}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'0', u'key': u'instance_type_ephemeral_gb', u'deleted_at': None, u'id': 64}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'1', u'key': u'instance_type_rxtx_factor', u'deleted_at': None, u'id': 65}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'1', u'key': u'instance_type_flavorid', u'deleted_at': None, u'id': 66}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'1', u'key': u'instance_type_vcpus', u'deleted_at': None, u'id': 67}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'', u'key': u'image_base_image_ref', u'deleted_at': None, u'id': 68}], u'task_state': u'spawning', u'shutdown_terminate': False, u'cell_name': None, u'root_gb': 0, u'locked': False, u'name': u'instance-00000006', u'created_at': u'2013-03-25T06:05:02.000000', u'launch_index': 0, u'memory_mb': 512, u'vcpus': 1, u'image_ref': u'', u'architecture': None, u'auto_disk_config': None, u'os_type': None, u'metadata': []} network={'injected': False, 'bridge': u'br100', 'multi_host': True, 'bridge_interface': u'br100', 'cidr_v6': None, 'cidr': u'10.0.0.0/24', 'vlan': None, 'id': u'e486213d-e3f6-49e1-ba0f-aa8f9ccc3f4b'} mapping={'vif_type': u'bridge', 'should_create_bridge': True, 'vif_devname': None, 'broadcast': '10.0.0.255', 'ips': [{'ip': u'10.0.0.2', 'netmask': '255.255.255.0', 'enabled': '1', 'gateway': u'10.0.0.1'}], 'mac': u'fa:16:3e:ec:3d:3b', 'should_create_vlan': False, 'gateway': u'10.0.0.1', 'ovs_interfaceid': None, 'rxtx_cap': 0, 'ip6s': [], 'label': u'private', 'qbh_params': None, 'vif_uuid': u'e1d4a1c2-8738-4db2-82c9-2e07e94bbb1a', 'dns': [u'8.8.4.4'], 'gateway_v6': None, 'dhcp_server': u'10.0.0.3', 'qbg_params': None} from (pid=17829) get_config /opt/stack/nova/nova/virt/libvirt/vif.py:266
2013-03-25 14:05:13.112 DEBUG nova.virt.libvirt.config [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Generated XML <domain type="kvm">
  <uuid>61ce878b-1836-4bf7-b220-134e24aa6100</uuid>
  <name>instance-00000006</name>
  <memory>524288</memory>
  <vcpu>1</vcpu>
  <sysinfo type="smbios">
    <system>
      <entry name="manufacturer">OpenStack Foundation</entry>
      <entry name="product">OpenStack Nova</entry>
      <entry name="version">2013.2</entry>
      <entry name="serial">560a3c01-5078-11cb-b988-f91b8e4f0d5b</entry>
      <entry name="uuid">61ce878b-1836-4bf7-b220-134e24aa6100</entry>
    </system>
  </sysinfo>
  <os>
    <type>hvm</type>
    <boot dev="hd"/>
    <smbios mode="sysinfo"/>
  </os>
  <features>
    <acpi/>
    <apic/>
  </features>
  <clock offset="utc">
    <timer name="pit" tickpolicy="delay"/>
    <timer name="rtc" tickpolicy="catchup"/>
  </clock>
  <devices>
    <disk type="block" device="disk">
      <driver name="qemu" type="raw" cache="none"/>
      <source dev="/dev/disk/by-path/ip-9.119.148.37:3260-iscsi-iqn.2010-10.org.openstack:volume-694dab66-924a-456b-b81f-b32e319e54d6-lun-1"/>
      <target bus="virtio" dev="vda"/>
      <serial>694dab66-924a-456b-b81f-b32e319e54d6</serial>
    </disk>
    <interface type="bridge">
      <mac address="fa:16:3e:ec:3d:3b"/>
      <model type="virtio"/>
      <source bridge="br100"/>
      <filterref filter="nova-instance-instance-00000006-fa163eec3d3b"/>
    </interface>
    <serial type="file">
      <source path="/opt/stack/data/nova/instances/61ce878b-1836-4bf7-b220-134e24aa6100/console.log"/>
    </serial>
    <serial type="pty"/>
    <input type="tablet" bus="usb"/>
    <graphics type="vnc" autoport="yes" keymap="en-us" listen="127.0.0.1"/>
  </devices>
</domain>
  from (pid=17829) to_xml /opt/stack/nova/nova/virt/libvirt/config.py:70
2013-03-25 14:05:13.112 DEBUG nova.virt.libvirt.driver [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] End to_xml instance={u'vm_state': u'building', u'availability_zone': None, u'terminated_at': None, u'ephemeral_gb': 0, u'instance_type_id': 2, u'user_data': None, u'vm_mode': None, u'deleted_at': None, u'reservation_id': u'r-ov9t16k2', u'id': 6, u'security_groups': [{u'project_id': u'74a588cd2b2247ccb84a2c81c038e4a7', u'user_id': u'c396313a5b7b4cf89ac180ee1dc918d7', u'name': u'default', u'deleted': 0, u'created_at': u'2013-03-25T03:40:27.000000', u'updated_at': None, u'rules': [], u'deleted_at': None, u'id': 1, u'description': u'default'}], u'disable_terminate': False, u'root_device_name': None, u'user_id': u'c396313a5b7b4cf89ac180ee1dc918d7', u'uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'default_swap_device': None, u'info_cache': {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': u'2013-03-25T06:05:07.000000', u'network_info': u'[{"ovs_interfaceid": null, "network": {"bridge": "br100", "subnets": [{"ips": [{"meta": {}, "version": 4, "type": "fixed", "floating_ips": [], "address": "10.0.0.2"}], "version": 4, "meta": {"dhcp_server": "10.0.0.3"}, "dns": [{"meta": {}, "version": 4, "type": "dns", "address": "8.8.4.4"}], "routes": [], "cidr": "10.0.0.0/24", "gateway": {"meta": {}, "version": 4, "type": "gateway", "address": "10.0.0.1"}}, {"ips": [], "version": null, "meta": {"dhcp_server": "10.0.0.3"}, "dns": [], "routes": [], "cidr": null, "gateway": {"meta": {}, "version": null, "type": "gateway", "address": null}}], "meta": {"tenant_id": null, "multi_host": true, "should_create_bridge": true, "bridge_interface": "br100"}, "id": "e486213d-e3f6-49e1-ba0f-aa8f9ccc3f4b", "label": "private"}, "devname": null, "qbh_params": null, "meta": {}, "address": "fa:16:3e:ec:3d:3b", "type": "bridge", "id": "e1d4a1c2-8738-4db2-82c9-2e07e94bbb1a", "qbg_params": null}]', u'deleted_at': None, u'id': 6}, u'hostname': u'vm-test', u'launched_on': u'vincent-ThinkPad-T410', u'display_description': u'VM_test', u'key_data': None, u'deleted': 0, u'scheduled_at': u'2013-03-25T06:05:02.000000', u'power_state': 0, u'default_ephemeral_device': None, u'progress': 0, u'project_id': u'74a588cd2b2247ccb84a2c81c038e4a7', u'launched_at': None, u'config_drive': u'', u'node': u'vincent-ThinkPad-T410', u'ramdisk_id': u'', u'access_ip_v6': None, u'access_ip_v4': None, u'kernel_id': u'', u'key_name': None, u'updated_at': u'2013-03-25T06:05:09.861700', u'host': u'vincent-ThinkPad-T410', u'display_name': u'VM_test', u'system_metadata': [{u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'512', u'key': u'instance_type_memory_mb', u'deleted_at': None, u'id': 58}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'0', u'key': u'instance_type_swap', u'deleted_at': None, u'id': 59}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': None, u'key': u'instance_type_vcpu_weight', u'deleted_at': None, u'id': 60}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'0', u'key': u'instance_type_root_gb', u'deleted_at': None, u'id': 61}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'2', u'key': u'instance_type_id', u'deleted_at': None, u'id': 62}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'm1.tiny', u'key': u'instance_type_name', u'deleted_at': None, u'id': 63}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'0', u'key': u'instance_type_ephemeral_gb', u'deleted_at': None, u'id': 64}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'1', u'key': u'instance_type_rxtx_factor', u'deleted_at': None, u'id': 65}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'1', u'key': u'instance_type_flavorid', u'deleted_at': None, u'id': 66}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'1', u'key': u'instance_type_vcpus', u'deleted_at': None, u'id': 67}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'', u'key': u'image_base_image_ref', u'deleted_at': None, u'id': 68}], u'task_state': u'spawning', u'shutdown_terminate': False, u'cell_name': None, u'root_gb': 0, u'locked': False, u'name': u'instance-00000006', u'created_at': u'2013-03-25T06:05:02.000000', u'launch_index': 0, u'memory_mb': 512, u'vcpus': 1, u'image_ref': u'', u'architecture': None, u'auto_disk_config': None, u'os_type': None, u'metadata': []} xml=<domain type="kvm">
  <uuid>61ce878b-1836-4bf7-b220-134e24aa6100</uuid>
  <name>instance-00000006</name>
  <memory>524288</memory>
  <vcpu>1</vcpu>
  <sysinfo type="smbios">
    <system>
      <entry name="manufacturer">OpenStack Foundation</entry>
      <entry name="product">OpenStack Nova</entry>
      <entry name="version">2013.2</entry>
      <entry name="serial">560a3c01-5078-11cb-b988-f91b8e4f0d5b</entry>
      <entry name="uuid">61ce878b-1836-4bf7-b220-134e24aa6100</entry>
    </system>
  </sysinfo>
  <os>
    <type>hvm</type>
    <boot dev="hd"/>
    <smbios mode="sysinfo"/>
  </os>
  <features>
    <acpi/>
    <apic/>
  </features>
  <clock offset="utc">
    <timer name="pit" tickpolicy="delay"/>
    <timer name="rtc" tickpolicy="catchup"/>
  </clock>
  <devices>
    <disk type="block" device="disk">
      <driver name="qemu" type="raw" cache="none"/>
      <source dev="/dev/disk/by-path/ip-9.119.148.37:3260-iscsi-iqn.2010-10.org.openstack:volume-694dab66-924a-456b-b81f-b32e319e54d6-lun-1"/>
      <target bus="virtio" dev="vda"/>
      <serial>694dab66-924a-456b-b81f-b32e319e54d6</serial>
    </disk>
    <interface type="bridge">
      <mac address="fa:16:3e:ec:3d:3b"/>
      <model type="virtio"/>
      <source bridge="br100"/>
      <filterref filter="nova-instance-instance-00000006-fa163eec3d3b"/>
    </interface>
    <serial type="file">
      <source path="/opt/stack/data/nova/instances/61ce878b-1836-4bf7-b220-134e24aa6100/console.log"/>
    </serial>
    <serial type="pty"/>
    <input type="tablet" bus="usb"/>
    <graphics type="vnc" autoport="yes" keymap="en-us" listen="127.0.0.1"/>
  </devices>
</domain>
 from (pid=17829) to_xml /opt/stack/nova/nova/virt/libvirt/driver.py:2334
2013-03-25 14:05:13.113 INFO nova.virt.libvirt.driver [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] 正在创建镜像
2013-03-25 14:05:13.114 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Got semaphore "connect_volume" for method "connect_volume"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:185
2013-03-25 14:05:13.114 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在运行cmd (subprocess)：sudo nova-rootwrap /etc/nova/rootwrap.conf iscsiadm -m node -T iqn.2010-10.org.openstack:volume-694dab66-924a-456b-b81f-b32e319e54d6 -p 9.119.148.37:3260 from (pid=17829) execute /opt/stack/nova/nova/utils.py:208
2013-03-25 14:05:13.281 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 运行结果为 0 from (pid=17829) execute /opt/stack/nova/nova/utils.py:232
2013-03-25 14:05:13.283 DEBUG nova.virt.libvirt.volume [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] iscsiadm (): stdout=# BEGIN RECORD 2.0-871
node.name = iqn.2010-10.org.openstack:volume-694dab66-924a-456b-b81f-b32e319e54d6
node.tpgt = -1
node.startup = automatic
iface.hwaddress = <empty>
iface.ipaddress = <empty>
iface.iscsi_ifacename = default
iface.net_ifacename = <empty>
iface.transport_name = tcp
iface.initiatorname = <empty>
node.discovery_address = <empty>
node.discovery_port = 0
node.discovery_type = static
node.session.initial_cmdsn = 0
node.session.initial_login_retry_max = 8
node.session.xmit_thread_priority = -20
node.session.cmds_max = 128
node.session.queue_depth = 32
node.session.auth.authmethod = CHAP
node.session.auth.username = QUUxBAE9wstH57oqL5FU
node.session.auth.password = ********
node.session.auth.username_in = <empty>
node.session.auth.password_in = <empty>
node.session.timeo.replacement_timeout = 120
node.session.err_timeo.abort_timeout = 15
node.session.err_timeo.lu_reset_timeout = 20
node.session.err_timeo.host_reset_timeout = 60
node.session.iscsi.FastAbort = Yes
node.session.iscsi.InitialR2T = No
node.session.iscsi.ImmediateData = Yes
node.session.iscsi.FirstBurstLength = 262144
node.session.iscsi.MaxBurstLength = 16776192
node.session.iscsi.DefaultTime2Retain = 0
node.session.iscsi.DefaultTime2Wait = 2
node.session.iscsi.MaxConnections = 1
node.session.iscsi.MaxOutstandingR2T = 1
node.session.iscsi.ERL = 0
node.conn[0].address = 9.119.148.37
node.conn[0].port = 3260
node.conn[0].startup = manual
node.conn[0].tcp.window_size = 524288
node.conn[0].tcp.type_of_service = 0
node.conn[0].timeo.logout_timeout = 15
node.conn[0].timeo.login_timeout = 15
node.conn[0].timeo.auth_timeout = 45
node.conn[0].timeo.noop_out_interval = 5
node.conn[0].timeo.noop_out_timeout = 5
node.conn[0].iscsi.MaxRecvDataSegmentLength = 262144
node.conn[0].iscsi.HeaderDigest = None
node.conn[0].iscsi.DataDigest = None
node.conn[0].iscsi.IFMarker = No
node.conn[0].iscsi.OFMarker = No
# END RECORD
 stderr= from (pid=17829) _run_iscsiadm /opt/stack/nova/nova/virt/libvirt/volume.py:181
2013-03-25 14:05:13.284 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在运行cmd (subprocess)：sudo nova-rootwrap /etc/nova/rootwrap.conf iscsiadm -m node -T iqn.2010-10.org.openstack:volume-694dab66-924a-456b-b81f-b32e319e54d6 -p 9.119.148.37:3260 --op update -n node.session.auth.authmethod -v CHAP from (pid=17829) execute /opt/stack/nova/nova/utils.py:208
2013-03-25 14:05:13.294 DEBUG nova.openstack.common.rpc.amqp [-] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:13.295 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is 8cb2a8dfca5b43848577351953116449 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:13.295 DEBUG nova.openstack.common.rpc.amqp [-] UNIQUE_ID is cd4ff8f6d1a343e386add78622732f07. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:13.481 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 运行结果为 0 from (pid=17829) execute /opt/stack/nova/nova/utils.py:232
2013-03-25 14:05:13.481 DEBUG nova.virt.libvirt.volume [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] iscsiadm ('--op', 'update', '-n', 'node.session.auth.authmethod', '-v', u'CHAP'): stdout= stderr= from (pid=17829) _run_iscsiadm /opt/stack/nova/nova/virt/libvirt/volume.py:181
2013-03-25 14:05:13.481 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在运行cmd (subprocess)：sudo nova-rootwrap /etc/nova/rootwrap.conf iscsiadm -m node -T iqn.2010-10.org.openstack:volume-694dab66-924a-456b-b81f-b32e319e54d6 -p 9.119.148.37:3260 --op update -n node.session.auth.username -v QUUxBAE9wstH57oqL5FU from (pid=17829) execute /opt/stack/nova/nova/utils.py:208
2013-03-25 14:05:13.655 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 运行结果为 0 from (pid=17829) execute /opt/stack/nova/nova/utils.py:232
2013-03-25 14:05:13.656 DEBUG nova.virt.libvirt.volume [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] iscsiadm ('--op', 'update', '-n', 'node.session.auth.username', '-v', u'QUUxBAE9wstH57oqL5FU'): stdout= stderr= from (pid=17829) _run_iscsiadm /opt/stack/nova/nova/virt/libvirt/volume.py:181
2013-03-25 14:05:13.656 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在运行cmd (subprocess)：sudo nova-rootwrap /etc/nova/rootwrap.conf iscsiadm -m node -T iqn.2010-10.org.openstack:volume-694dab66-924a-456b-b81f-b32e319e54d6 -p 9.119.148.37:3260 --op update -n node.session.auth.password -v qa7ebAaJpfs3hj39YYSY from (pid=17829) execute /opt/stack/nova/nova/utils.py:208
2013-03-25 14:05:13.842 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 运行结果为 0 from (pid=17829) execute /opt/stack/nova/nova/utils.py:232
2013-03-25 14:05:13.844 DEBUG nova.virt.libvirt.volume [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] iscsiadm ('--op', 'update', '-n', 'node.session.auth.password', '-v', u'qa7ebAaJpfs3hj39YYSY'): stdout= stderr= from (pid=17829) _run_iscsiadm /opt/stack/nova/nova/virt/libvirt/volume.py:181
2013-03-25 14:05:13.844 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在运行cmd (subprocess)：sudo nova-rootwrap /etc/nova/rootwrap.conf iscsiadm -m session from (pid=17829) execute /opt/stack/nova/nova/utils.py:208
2013-03-25 14:05:14.023 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 运行结果为 0 from (pid=17829) execute /opt/stack/nova/nova/utils.py:232
2013-03-25 14:05:14.024 DEBUG nova.virt.libvirt.volume [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] iscsiadm ['-m', 'session']: stdout=tcp: [8] 9.119.148.37:3260,1 iqn.2010-10.org.openstack:volume-694dab66-924a-456b-b81f-b32e319e54d6
 stderr= from (pid=17829) _run_iscsiadm_bare /opt/stack/nova/nova/virt/libvirt/volume.py:422
2013-03-25 14:05:14.025 DEBUG nova.virt.libvirt.vif [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] vif_type=bridge instance={u'vm_state': u'building', u'availability_zone': None, u'terminated_at': None, u'ephemeral_gb': 0, u'instance_type_id': 2, u'user_data': None, u'vm_mode': None, u'deleted_at': None, u'reservation_id': u'r-ov9t16k2', u'id': 6, u'security_groups': [{u'project_id': u'74a588cd2b2247ccb84a2c81c038e4a7', u'user_id': u'c396313a5b7b4cf89ac180ee1dc918d7', u'name': u'default', u'deleted': 0, u'created_at': u'2013-03-25T03:40:27.000000', u'updated_at': None, u'rules': [], u'deleted_at': None, u'id': 1, u'description': u'default'}], u'disable_terminate': False, u'root_device_name': None, u'user_id': u'c396313a5b7b4cf89ac180ee1dc918d7', u'uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'default_swap_device': None, u'info_cache': {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': u'2013-03-25T06:05:07.000000', u'network_info': u'[{"ovs_interfaceid": null, "network": {"bridge": "br100", "subnets": [{"ips": [{"meta": {}, "version": 4, "type": "fixed", "floating_ips": [], "address": "10.0.0.2"}], "version": 4, "meta": {"dhcp_server": "10.0.0.3"}, "dns": [{"meta": {}, "version": 4, "type": "dns", "address": "8.8.4.4"}], "routes": [], "cidr": "10.0.0.0/24", "gateway": {"meta": {}, "version": 4, "type": "gateway", "address": "10.0.0.1"}}, {"ips": [], "version": null, "meta": {"dhcp_server": "10.0.0.3"}, "dns": [], "routes": [], "cidr": null, "gateway": {"meta": {}, "version": null, "type": "gateway", "address": null}}], "meta": {"tenant_id": null, "multi_host": true, "should_create_bridge": true, "bridge_interface": "br100"}, "id": "e486213d-e3f6-49e1-ba0f-aa8f9ccc3f4b", "label": "private"}, "devname": null, "qbh_params": null, "meta": {}, "address": "fa:16:3e:ec:3d:3b", "type": "bridge", "id": "e1d4a1c2-8738-4db2-82c9-2e07e94bbb1a", "qbg_params": null}]', u'deleted_at': None, u'id': 6}, u'hostname': u'vm-test', u'launched_on': u'vincent-ThinkPad-T410', u'display_description': u'VM_test', u'key_data': None, u'deleted': 0, u'scheduled_at': u'2013-03-25T06:05:02.000000', u'power_state': 0, u'default_ephemeral_device': None, u'progress': 0, u'project_id': u'74a588cd2b2247ccb84a2c81c038e4a7', u'launched_at': None, u'config_drive': u'', u'node': u'vincent-ThinkPad-T410', u'ramdisk_id': u'', u'access_ip_v6': None, u'access_ip_v4': None, u'kernel_id': u'', u'key_name': None, u'updated_at': u'2013-03-25T06:05:09.861700', u'host': u'vincent-ThinkPad-T410', u'display_name': u'VM_test', u'system_metadata': [{u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'512', u'key': u'instance_type_memory_mb', u'deleted_at': None, u'id': 58}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'0', u'key': u'instance_type_swap', u'deleted_at': None, u'id': 59}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': None, u'key': u'instance_type_vcpu_weight', u'deleted_at': None, u'id': 60}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'0', u'key': u'instance_type_root_gb', u'deleted_at': None, u'id': 61}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'2', u'key': u'instance_type_id', u'deleted_at': None, u'id': 62}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'm1.tiny', u'key': u'instance_type_name', u'deleted_at': None, u'id': 63}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'0', u'key': u'instance_type_ephemeral_gb', u'deleted_at': None, u'id': 64}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'1', u'key': u'instance_type_rxtx_factor', u'deleted_at': None, u'id': 65}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'1', u'key': u'instance_type_flavorid', u'deleted_at': None, u'id': 66}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'1', u'key': u'instance_type_vcpus', u'deleted_at': None, u'id': 67}, {u'instance_uuid': u'61ce878b-1836-4bf7-b220-134e24aa6100', u'deleted': 0, u'created_at': u'2013-03-25T06:05:02.000000', u'updated_at': None, u'value': u'', u'key': u'image_base_image_ref', u'deleted_at': None, u'id': 68}], u'task_state': u'spawning', u'shutdown_terminate': False, u'cell_name': None, u'root_gb': 0, u'locked': False, u'name': u'instance-00000006', u'created_at': u'2013-03-25T06:05:02.000000', u'launch_index': 0, u'memory_mb': 512, u'vcpus': 1, u'image_ref': u'', u'architecture': None, u'auto_disk_config': None, u'os_type': None, u'metadata': []} network={'injected': False, 'bridge': u'br100', 'multi_host': True, 'bridge_interface': u'br100', 'cidr_v6': None, 'cidr': u'10.0.0.0/24', 'vlan': None, 'id': u'e486213d-e3f6-49e1-ba0f-aa8f9ccc3f4b'} mapping={'vif_type': u'bridge', 'should_create_bridge': True, 'vif_devname': None, 'broadcast': '10.0.0.255', 'ips': [{'ip': u'10.0.0.2', 'netmask': '255.255.255.0', 'enabled': '1', 'gateway': u'10.0.0.1'}], 'mac': u'fa:16:3e:ec:3d:3b', 'should_create_vlan': False, 'gateway': u'10.0.0.1', 'ovs_interfaceid': None, 'rxtx_cap': 0, 'ip6s': [], 'label': u'private', 'qbh_params': None, 'vif_uuid': u'e1d4a1c2-8738-4db2-82c9-2e07e94bbb1a', 'dns': [u'8.8.4.4'], 'gateway_v6': None, 'dhcp_server': u'10.0.0.3', 'qbg_params': None} from (pid=17829) plug /opt/stack/nova/nova/virt/libvirt/vif.py:387
2013-03-25 14:05:14.025 INFO nova.virt.libvirt.firewall [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] 在 nwfilter 里调用 setup_basic_filtering
2013-03-25 14:05:14.026 INFO nova.virt.libvirt.firewall [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] 正在确保静态过滤器
2013-03-25 14:05:14.171 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:14.171 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is 0ff169bae13d4f3ea6f07bd37eeb8f79 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:14.171 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is 06923e38fc8b4fe7a321741541e7fc9e. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:14.328 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:14.329 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is d393fc926d6c46b3b2c610aff578b08a from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:14.330 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is f4e295821bd14da49770ce9ebe5c23d3. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:14.483 DEBUG nova.virt.firewall [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] Filters added to instance from (pid=17829) prepare_instance_filter /opt/stack/nova/nova/virt/firewall.py:195
2013-03-25 14:05:14.484 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Got semaphore "iptables" for method "_do_refresh_provider_fw_rules"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:185
2013-03-25 14:05:14.484 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Attempting to grab file lock "iptables" for method "_do_refresh_provider_fw_rules"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:196
2013-03-25 14:05:14.485 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Got file lock "iptables" at /opt/stack/data/nova/nova-iptables for method "_do_refresh_provider_fw_rules"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:227
2013-03-25 14:05:14.486 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:14.486 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is 80e412a1c8b74841a81e3eac0355f110 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:14.487 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is 8c4855388578489aaeb4c0ef4ff5d85a. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:14.628 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Released file lock "iptables" at /opt/stack/data/nova/nova-iptables for method "_do_refresh_provider_fw_rules"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:234
2013-03-25 14:05:14.629 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Got semaphore "iptables" for method "_apply"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:185
2013-03-25 14:05:14.630 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Attempting to grab file lock "iptables" for method "_apply"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:196
2013-03-25 14:05:14.630 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Got file lock "iptables" at /opt/stack/data/nova/nova-iptables for method "_apply"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:227
2013-03-25 14:05:14.631 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在运行cmd (subprocess)：sudo nova-rootwrap /etc/nova/rootwrap.conf iptables-save -c from (pid=17829) execute /opt/stack/nova/nova/utils.py:208
2013-03-25 14:05:14.800 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 运行结果为 0 from (pid=17829) execute /opt/stack/nova/nova/utils.py:232
2013-03-25 14:05:14.803 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在运行cmd (subprocess)：sudo nova-rootwrap /etc/nova/rootwrap.conf iptables-restore -c from (pid=17829) execute /opt/stack/nova/nova/utils.py:208
2013-03-25 14:05:15.001 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 运行结果为 0 from (pid=17829) execute /opt/stack/nova/nova/utils.py:232
2013-03-25 14:05:15.002 DEBUG nova.network.linux_net [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] IPTablesManager.apply成功完成 from (pid=17829) _apply /opt/stack/nova/nova/network/linux_net.py:420
2013-03-25 14:05:15.003 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Released file lock "iptables" at /opt/stack/data/nova/nova-iptables for method "_apply"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:234
2013-03-25 14:05:15.003 DEBUG nova.virt.firewall [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] 提供者防火墙规则刷新 from (pid=17829) prepare_instance_filter /opt/stack/nova/nova/virt/firewall.py:197
2013-03-25 14:05:15.004 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Got semaphore "iptables" for method "_apply"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:185
2013-03-25 14:05:15.004 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Attempting to grab file lock "iptables" for method "_apply"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:196
2013-03-25 14:05:15.005 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Got file lock "iptables" at /opt/stack/data/nova/nova-iptables for method "_apply"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:227
2013-03-25 14:05:15.006 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在运行cmd (subprocess)：sudo nova-rootwrap /etc/nova/rootwrap.conf iptables-save -c from (pid=17829) execute /opt/stack/nova/nova/utils.py:208
2013-03-25 14:05:15.187 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 运行结果为 0 from (pid=17829) execute /opt/stack/nova/nova/utils.py:232
2013-03-25 14:05:15.190 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在运行cmd (subprocess)：sudo nova-rootwrap /etc/nova/rootwrap.conf iptables-restore -c from (pid=17829) execute /opt/stack/nova/nova/utils.py:208
2013-03-25 14:05:15.373 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 运行结果为 0 from (pid=17829) execute /opt/stack/nova/nova/utils.py:232
2013-03-25 14:05:15.374 DEBUG nova.network.linux_net [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] IPTablesManager.apply成功完成 from (pid=17829) _apply /opt/stack/nova/nova/network/linux_net.py:420
2013-03-25 14:05:15.374 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Released file lock "iptables" at /opt/stack/data/nova/nova-iptables for method "_apply"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:234
2013-03-25 14:05:18.267 DEBUG nova.virt.driver [-] Emitting event <nova.virt.event.LifecycleEvent object at 0x3293710> from (pid=17829) emit_event /opt/stack/nova/nova/virt/driver.py:898
2013-03-25 14:05:18.268 INFO nova.compute.manager [-] Lifecycle event 0 on VM 61ce878b-1836-4bf7-b220-134e24aa6100
2013-03-25 14:05:18.269 DEBUG nova.openstack.common.rpc.amqp [-] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:18.269 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is e5c11a592631476f99b8d4db28eabd52 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:18.270 DEBUG nova.openstack.common.rpc.amqp [-] UNIQUE_ID is 85f0bde3072247ca8dd90d16219af056. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:18.282 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在运行cmd (subprocess)：sudo nova-rootwrap /etc/nova/rootwrap.conf tee /sys/class/net/vnet0/brport/hairpin_mode from (pid=17829) execute /opt/stack/nova/nova/utils.py:208
2013-03-25 14:05:18.491 DEBUG nova.openstack.common.rpc.amqp [-] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:18.492 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is 516cd8d626cf4dba848f3f90376c0b92 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:18.492 DEBUG nova.openstack.common.rpc.amqp [-] UNIQUE_ID is be9e37747d5944b2a21d9e997af92c85. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:18.585 DEBUG nova.utils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 运行结果为 0 from (pid=17829) execute /opt/stack/nova/nova/utils.py:232
2013-03-25 14:05:18.586 DEBUG nova.virt.libvirt.driver [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] 实例在运行 from (pid=17829) spawn /opt/stack/nova/nova/virt/libvirt/driver.py:1521
2013-03-25 14:05:18.592 INFO nova.virt.libvirt.driver [-] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] 实例成功生产。
2013-03-25 14:05:18.592 DEBUG nova.compute.manager [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] 正在检查状态 from (pid=17829) _get_power_state /opt/stack/nova/nova/compute/manager.py:594
2013-03-25 14:05:18.598 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:18.599 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is 31946c600fa94514a41ca61f1ff25e90 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:18.600 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is 9b0e48544e844401a8adb6192e14f5e2. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:18.807 INFO nova.compute.manager [-] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] During sync_power_state the instance has a pending task. Skip.
2013-03-25 14:05:18.848 DEBUG nova.virt.libvirt.driver [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] 正在更新主机状态 from (pid=17829) update_status /opt/stack/nova/nova/virt/libvirt/driver.py:3690
2013-03-25 14:05:18.883 DEBUG nova.openstack.common.lockutils [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Got semaphore "compute_resources" for method "update_usage"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:185
2013-03-25 14:05:18.885 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:18.886 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is 436e95b03725478eac3e29667be2e2f9 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:18.887 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is 299f2c9f70b74872a437bd8ce8e0bb7f. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:19.119 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:19.119 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] MSG_ID is 4ff01731f4d24202b532813d4b8d127e from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:19.120 DEBUG nova.openstack.common.rpc.amqp [req-8845c4a1-4d3e-4b19-a8eb-6a6d023dfc71 admin admin] UNIQUE_ID is 782d529996ca4d59bd76163b09688ffb. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:23.466 DEBUG nova.openstack.common.rpc.amqp [-] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:23.467 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is 90a039809182489c85f3db69d62ffe2d from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:23.467 DEBUG nova.openstack.common.rpc.amqp [-] UNIQUE_ID is ae282a467f2347e28d5cd0b6980ee831. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:33.664 DEBUG nova.openstack.common.rpc.amqp [-] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:33.664 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is 1c8bf643304c467688c37e107ea1998d from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:33.664 DEBUG nova.openstack.common.rpc.amqp [-] UNIQUE_ID is 470d7e445a2b4d89b4bec0390851c0b6. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:35.145 DEBUG nova.manager [-] 正在运行周期性任务 ComputeManager.publish_service_capabilities from (pid=17829) periodic_tasks /opt/stack/nova/nova/manager.py:237
2013-03-25 14:05:35.145 DEBUG nova.manager [-] 向调度器通报能力。 from (pid=17829) publish_service_capabilities /opt/stack/nova/nova/manager.py:318
2013-03-25 14:05:35.146 DEBUG nova.openstack.common.rpc.amqp [-] Making asynchronous fanout cast... from (pid=17829) fanout_cast /opt/stack/nova/nova/openstack/common/rpc/amqp.py:630
2013-03-25 14:05:35.146 DEBUG nova.openstack.common.rpc.amqp [-] UNIQUE_ID is a18ae8c39a0340eebb07a5b518ce1717. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:35.188 DEBUG nova.manager [-] 正在运行周期性任务 ComputeManager._poll_volume_usage from (pid=17829) periodic_tasks /opt/stack/nova/nova/manager.py:237
2013-03-25 14:05:35.189 DEBUG nova.manager [-] 正在运行周期性任务 ComputeManager._instance_usage_audit from (pid=17829) periodic_tasks /opt/stack/nova/nova/manager.py:237
2013-03-25 14:05:35.189 DEBUG nova.manager [-] 正在运行周期性任务 ComputeManager.update_available_resource from (pid=17829) periodic_tasks /opt/stack/nova/nova/manager.py:237
2013-03-25 14:05:35.190 DEBUG nova.virt.libvirt.driver [-] 正在更新主机状态 from (pid=17829) update_status /opt/stack/nova/nova/virt/libvirt/driver.py:3690
2013-03-25 14:05:35.224 DEBUG nova.openstack.common.lockutils [-] Got semaphore "compute_resources" for method "update_available_resource"... from (pid=17829) inner /opt/stack/nova/nova/openstack/common/lockutils.py:185
2013-03-25 14:05:35.224 AUDIT nova.compute.resource_tracker [-] Auditing locally available compute resources
2013-03-25 14:05:35.274 DEBUG nova.virt.libvirt.driver [-] 因它像卷，所以跳过 None from (pid=17829) get_instance_disk_info /opt/stack/nova/nova/virt/libvirt/driver.py:3327
2013-03-25 14:05:35.274 DEBUG nova.compute.resource_tracker [-] Hypervisor: free ram (MB): 930 from (pid=17829) _report_hypervisor_resource_view /opt/stack/nova/nova/compute/resource_tracker.py:334
2013-03-25 14:05:35.275 DEBUG nova.compute.resource_tracker [-] Hypervisor: free disk (GB): 29 from (pid=17829) _report_hypervisor_resource_view /opt/stack/nova/nova/compute/resource_tracker.py:335
2013-03-25 14:05:35.275 DEBUG nova.compute.resource_tracker [-] Hypervisor: free VCPUs: 3 from (pid=17829) _report_hypervisor_resource_view /opt/stack/nova/nova/compute/resource_tracker.py:340
2013-03-25 14:05:35.275 DEBUG nova.openstack.common.rpc.amqp [-] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:35.276 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is fea2b70694024fbd92ab7f81ad1e611a from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:35.276 DEBUG nova.openstack.common.rpc.amqp [-] UNIQUE_ID is c4e01bb2e3b44ea88ba2ff947e56b45b. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:35.453 DEBUG nova.openstack.common.rpc.amqp [-] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:35.453 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is e588de343fb64f1d9b59e7b43b7afd66 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:35.454 DEBUG nova.openstack.common.rpc.amqp [-] UNIQUE_ID is 8a3a6ada5675450d9f1dd89524ce9b99. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:35.603 AUDIT nova.compute.resource_tracker [-] Free ram (MB): 2792
2013-03-25 14:05:35.604 AUDIT nova.compute.resource_tracker [-] Free disk (GB): 36
2013-03-25 14:05:35.604 AUDIT nova.compute.resource_tracker [-] Free VCPUS: 3
2013-03-25 14:05:35.608 DEBUG nova.openstack.common.rpc.amqp [-] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:35.609 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is 7262e92257164ebc89b13f28d77299a6 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:35.609 DEBUG nova.openstack.common.rpc.amqp [-] UNIQUE_ID is 5fcf7281b7a54494b0d463b23f612b34. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:35.832 INFO nova.compute.resource_tracker [-] Compute_service record updated for vincent-ThinkPad-T410:vincent-ThinkPad-T410
2013-03-25 14:05:35.833 DEBUG nova.manager [-] 正在运行周期性任务 ComputeManager._poll_rebooting_instances from (pid=17829) periodic_tasks /opt/stack/nova/nova/manager.py:237
2013-03-25 14:05:35.833 DEBUG nova.manager [-] 正在运行周期性任务 ComputeManager._reclaim_queued_deletes from (pid=17829) periodic_tasks /opt/stack/nova/nova/manager.py:237
2013-03-25 14:05:35.833 DEBUG nova.compute.manager [-] CONF.reclaim_instance_interval <= 0, skipping... from (pid=17829) _reclaim_queued_deletes /opt/stack/nova/nova/compute/manager.py:3810
2013-03-25 14:05:35.834 DEBUG nova.manager [-] 正在运行周期性任务 ComputeManager._report_driver_status from (pid=17829) periodic_tasks /opt/stack/nova/nova/manager.py:237
2013-03-25 14:05:35.834 DEBUG nova.manager [-] 正在运行周期性任务 ComputeManager._poll_unconfirmed_resizes from (pid=17829) periodic_tasks /opt/stack/nova/nova/manager.py:237
2013-03-25 14:05:35.834 DEBUG nova.manager [-] 正在运行周期性任务 ComputeManager._poll_rescued_instances from (pid=17829) periodic_tasks /opt/stack/nova/nova/manager.py:237
2013-03-25 14:05:35.835 DEBUG nova.manager [-] 正在运行周期性任务 ComputeManager._poll_bandwidth_usage from (pid=17829) periodic_tasks /opt/stack/nova/nova/manager.py:237
2013-03-25 14:05:35.835 DEBUG nova.manager [-] 正在运行周期性任务 ComputeManager._check_instance_build_time from (pid=17829) periodic_tasks /opt/stack/nova/nova/manager.py:237
2013-03-25 14:05:35.835 DEBUG nova.manager [-] 正在运行周期性任务 ComputeManager._heal_instance_info_cache from (pid=17829) periodic_tasks /opt/stack/nova/nova/manager.py:237
2013-03-25 14:05:35.836 DEBUG nova.openstack.common.rpc.amqp [-] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:35.836 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is 96bfebc5b6c54734ab901c2b03aa877a from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:35.836 DEBUG nova.openstack.common.rpc.amqp [-] UNIQUE_ID is e6ed77f4875947c08fb786ea0809d0b3. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:35.996 DEBUG nova.openstack.common.rpc.amqp [-] Making synchronous call on network ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:35.996 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is 9f4c2f6a95bd438dbd76f75a30c0430d from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:35.997 DEBUG nova.openstack.common.rpc.amqp [-] UNIQUE_ID is 19c9b89c21bf4152a4473828f6242127. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:36.237 DEBUG nova.openstack.common.rpc.amqp [-] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:36.238 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is 0a401738697648e7bb223f9bcd3726f4 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:36.238 DEBUG nova.openstack.common.rpc.amqp [-] UNIQUE_ID is ad31fb2889dd4d809c8a162d26bce10e. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:36.384 DEBUG nova.compute.manager [-] [instance: 61ce878b-1836-4bf7-b220-134e24aa6100] Updated the info_cache for instance from (pid=17829) _heal_instance_info_cache /opt/stack/nova/nova/compute/manager.py:3355
2013-03-25 14:05:36.384 DEBUG nova.utils [-] Periodic task processor sleeping for 60.00 seconds from (pid=17829) _inner /opt/stack/nova/nova/utils.py:638
2013-03-25 14:05:43.832 DEBUG nova.openstack.common.rpc.amqp [-] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:43.833 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is c0d6618895ba4546addf7c2394a9fac9 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:43.833 DEBUG nova.openstack.common.rpc.amqp [-] UNIQUE_ID is 8026a95805364cb0902b143930f9d092. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337
2013-03-25 14:05:54.033 DEBUG nova.openstack.common.rpc.amqp [-] Making synchronous call on conductor ... from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:584
2013-03-25 14:05:54.033 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is 8f8a257bc90742b08f9bbbc211d5d777 from (pid=17829) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:587
2013-03-25 14:05:54.034 DEBUG nova.openstack.common.rpc.amqp [-] UNIQUE_ID is c5378cfaaa3e4c8b9436f4657c6c6e9a. from (pid=17829) _add_unique_id /opt/stack/nova/nova/openstack/common/rpc/amqp.py:337