2012-08-08 20:28:27 DEBUG nova.virt.libvirt.driver [-] skipping None since it looks like volume from (pid=31416) get_instance_disk_info /opt/stack/nova/nova/virt/libvirt/driver.py :2664 skipping None since it looks like volume 2012-08-08 20:28:27 INFO nova.virt.libvirt.driver [-] Compute_service record updated for parrish Compute_service record updated for parrish 2012-08-08 20:28:27 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_rebooting_instances from (pid=31416) periodic_tasks /opt/stack/nova/nova/manager.py:170 Running periodic task ComputeManager._poll_rebooting_instances 2012-08-08 20:28:27 DEBUG nova.manager [-] Skipping ComputeManager._cleanup_running_deleted_instances, 26 ticks left until next run from (pid=31416) periodic_tasks /opt/stack/nova /nova/manager.py:165 Skipping ComputeManager._cleanup_running_deleted_instances, 26 ticks left until next run 2012-08-08 20:28:27 DEBUG nova.manager [-] Running periodic task ComputeManager._check_instance_build_time from (pid=31416) periodic_tasks /opt/stack/nova/nova/manager.py:170 Running periodic task ComputeManager._check_instance_build_time 2012-08-08 20:28:27 DEBUG nova.manager [-] Running periodic task ComputeManager._heal_instance_info_cache from (pid=31416) periodic_tasks /opt/stack/nova/nova/manager.py:170 Running periodic task ComputeManager._heal_instance_info_cache 2012-08-08 20:28:27 DEBUG nova.openstack.common.rpc.amqp [-] Making asynchronous call on network ... from (pid=31416) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.py:3 51 Making asynchronous call on network ... 2012-08-08 20:28:27 DEBUG nova.openstack.common.rpc.amqp [-] MSG_ID is 5f8c3b78f1bd466cb8544fae26795c22 from (pid=31416) multicall /opt/stack/nova/nova/openstack/common/rpc/amqp.p y:354 MSG_ID is 5f8c3b78f1bd466cb8544fae26795c22 2012-08-08 20:28:27 DEBUG nova.compute.manager [-] [instance: 9cdf4843-54d1-497e-bd70-3f3157e8ca03] Updated the info_cache for instance from (pid=31416) _heal_instance_info_cache /opt/stack/nova/nova/compute/manager.py:2437 Updated the info_cache for instance 2012-08-08 20:28:27 DEBUG nova.manager [-] Skipping ComputeManager._run_image_cache_manager_pass, 36 ticks left until next run from (pid=31416) periodic_tasks /opt/stack/nova/nova/manager.py:165 Skipping ComputeManager._run_image_cache_manager_pass, 36 ticks left until next run 2012-08-08 20:28:27 DEBUG nova.manager [-] Running periodic task ComputeManager._reclaim_queued_deletes from (pid=31416) periodic_tasks /opt/stack/nova/nova/manager.py:170 Running periodic task ComputeManager._reclaim_queued_deletes 2012-08-08 20:28:27 DEBUG nova.compute.manager [-] FLAGS.reclaim_instance_interval <= 0, skipping... from (pid=31416) _reclaim_queued_deletes /opt/stack/nova/nova/compute/manager.py:2738 FLAGS.reclaim_instance_interval <= 0, skipping... 2012-08-08 20:28:27 DEBUG nova.manager [-] Running periodic task ComputeManager._report_driver_status from (pid=31416) periodic_tasks /opt/stack/nova/nova/manager.py:170 Running periodic task ComputeManager._report_driver_status 2012-08-08 20:28:27 INFO nova.compute.manager [-] Updating host status Updating host status 2012-08-08 20:28:27 DEBUG nova.virt.libvirt.driver [-] Updating host stats from (pid=31416) update_status /opt/stack/nova/nova/virt/libvirt/driver.py:3003 Updating host stats 2012-08-08 20:28:28 DEBUG nova.openstack.common.rpc.amqp [-] received {u'_context_roles': [u'admin'], u'_context_request_id': u'req-7b1bb5b3-e70e-4088-b6d1-0946c24e6445', u'_context_quota_class': None, u'_context_project_name': u'demo', u'_context_service_catalog': [{u'endpoints': [{u'adminURL': u'http://9.114.219.72:8774/v2/ebb53d9cfa214a85a1bdcfa89ed213a4', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:8774/v2/ebb53d9cfa214a85a1bdcfa89ed213a4', u'publicURL': u'http://9.114.219.72:8774/v2/ebb53d9cfa214a85a1bdcfa89ed213a4'}], u'endpoints_links': [], u'type': u'compute', u'name': u'Compute Service'}, {u'endpoints': [{u'adminURL': u'http://9.114.219.72:3333', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:3333', u'publicURL': u'http://9.114.219.72:3333'}], u'endpoints_links': [], u'type': u's3', u'name': u'S3 Service'}, {u'endpoints': [{u'adminURL': u'http://9.114.219.72:9292', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:9292', u'publicURL': u'http://9.114.219.72:9292'}], u'endpoints_links': [], u'type': u'image', u'name': u'Image Service'}, {u'endpoints': [{u'adminURL': u'http://9.114.219.72:8776/v1/ebb53d9cfa214a85a1bdcfa89ed213a4', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:8776/v1/ebb53d9cfa214a85a1bdcfa89ed213a4', u'publicURL': u'http://9.114.219.72:8776/v1/ebb53d9cfa214a85a1bdcfa89ed213a4'}], u'endpoints_links': [], u'type': u'volume', u'name': u'Volume Service'}, {u'endpoints': [{u'adminURL': u'http://9.114.219.72:8773/services/Admin', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:8773/services/Cloud', u'publicURL': u'http://9.114.219.72:8773/services/Cloud'}], u'endpoints_links': [], u'type': u'ec2', u'name': u'EC2 Service'}, {u'endpoints': [{u'adminURL': u'http://9.114.219.72:8080/', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:8080/v1/AUTH_ebb53d9cfa214a85a1bdcfa89ed213a4', u'publicURL': u'http://9.114.219.72:8080/v1/AUTH_ebb53d9cfa214a85a1bdcfa89ed213a4'}], u'endpoints_links': [], u'type': u'object-store', u'name': u'Swift Service'}, {u'endpoints': [{u'adminURL': u'http://9.114.219.72:35357/v2.0', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:5000/v2.0', u'publicURL': u'http://9.114.219.72:5000/v2.0'}], u'endpoints_links': [], u'type': u'identity', u'name': u'Identity Service'}], u'_context_user_name': u'admin', u'_context_auth_token': '', u'args': {u'image_type': u'snapshot', u'instance': {u'vm_state': u'active', u'availability_zone': None, u'terminated_at': None, u'ephemeral_gb': 0, u'instance_type_id': 2, u'user_data': u'', u'vm_mode': None, u'deleted_at': None, u'reservation_id': u'r-76pvcrss', u'id': 1, u'security_groups': [{u'deleted_at': None, u'user_id': u'bbb85ef7ca424c5293fda5ce32b2e896', u'name': u'default', u'deleted': False, u'created_at': u'2012-08-09T00:26:22.000000', u'updated_at': None, u'rules': [], u'project_id': u'ebb53d9cfa214a85a1bdcfa89ed213a4', u'id': 1, u'description': u'default'}], u'disable_terminate': False, u'user_id': u'bbb85ef7ca424c5293fda5ce32b2e896', u'uuid': u'9cdf4843-54d1-497e-bd70-3f3157e8ca03', u'server_name': None, u'default_swap_device': None, u'info_cache': {u'instance_uuid': u'9cdf4843-54d1-497e-bd70-3f3157e8ca03', u'deleted': False, u'created_at': u'2012-08-09T00:26:43.000000', u'updated_at': u'2012-08-09T00:26:47.000000', u'network_info': u'[{"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.1"}, "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": null}, "dns": [], "routes": [], "cidr": null, "gateway": {"meta": {}, "version": null, "type": "gateway", "address": null}}], "meta": {"tenant_id": null, "should_create_bridge": true, "bridge_interface": "eth0"}, "id": "c0d13cb6-8143-41ba-8e81-72162e633eab", "label": "private"}, "meta": {}, "id": "4c6453c4-881a-4efe-9871-906a221de10d", "address": "fa:16:3e:1c:a2:e9"}]', u'deleted_at': None, u'id': 1}, u'hostname': u'server', u'launched_on': u'parrish', u'display_description': u'server', u'key_data': None, u'kernel_id': u'dfe539db-2f73-48af-83cc-7e86f39b5db8', u'power_state': 1, u'default_ephemeral_device': None, u'progress': 0, u'project_id': u'ebb53d9cfa214a85a1bdcfa89ed213a4', u'launched_at': u'2012-08-09T00:27:02.000000', u'scheduled_at': u'2012-08-09T00:26:43.000000', u'ramdisk_id': u'a57fdd99-dfda-4b7c-bf43-e44ef3abb80e', u'access_ip_v6': None, u'access_ip_v4': None, u'deleted': False, u'key_name': None, u'updated_at': u'2012-08-09T00:27:02.000000', u'host': u'parrish', u'display_name': u'server', u'task_state': None, u'shutdown_terminate': False, u'architecture': None, u'root_gb': 0, u'locked': False, u'name': u'instance-00000001', u'created_at': u'2012-08-09T00:26:43.000000', u'launch_index': 0, u'metadata': [], u'memory_mb': 512, u'instance_type': {u'disabled': False, u'root_gb': 0, u'name': u'm1.tiny', u'deleted': False, u'created_at': None, u'ephemeral_gb': 0, u'updated_at': None, u'memory_mb': 512, u'vcpus': 1, u'flavorid': u'1', u'swap': 0, u'rxtx_factor': 1.0, u'deleted_at': None, u'vcpu_weight': None, u'id': 2}, u'vcpus': 1, u'image_ref': u'4a4ab972-ae37-4b2b-a6b4-916921598b73', u'root_device_name': u'/dev/vda', u'auto_disk_config': None, u'os_type': None, u'config_drive': u''}, u'backup_type': None, u'rotation': None, u'image_id': u'a2203a98-f634-4cf2-9df6-d197ed752f25'}, u'_context_instance_lock_checked': False, u'_context_is_admin': True, u'version': u'1.34', u'_context_project_id': u'ebb53d9cfa214a85a1bdcfa89ed213a4', u'_context_timestamp': u'2012-08-09T00:28:28.281166', u'_context_read_deleted': u'no', u'_context_user_id': u'bbb85ef7ca424c5293fda5ce32b2e896', u'method': u'snapshot_instance', u'_context_remote_address': u'9.114.219.72'} from (pid=31416) _safe_log /opt/stack/nova/nova/openstack/common/rpc/common.py:197 received {u'_context_roles': [u'admin'], u'_context_request_id': u'req-7b1bb5b3-e70e-4088-b6d1-0946c24e6445', u'_context_quota_class': None, u'_context_project_name': u'demo', u'_context_service_catalog': [{u'endpoints': [{u'adminURL': u'http://9.114.219.72:8774/v2/ebb53d9cfa214a85a1bdcfa89ed213a4', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:8774/v2/ebb53d9cfa214a85a1bdcfa89ed213a4', u'publicURL': u'http://9.114.219.72:8774/v2/ebb53d9cfa214a85a1bdcfa89ed213a4'}], u'endpoints_links': [], u'type': u'compute', u'name': u'Compute Service'}, {u'endpoints': [{u'adminURL': u'http://9.114.219.72:3333', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:3333', u'publicURL': u'http://9.114.219.72:3333'}], u'endpoints_links': [], u'type': u's3', u'name': u'S3 Service'}, {u'endpoints': [{u'adminURL': u'http://9.114.219.72:9292', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:9292', u'publicURL': u'http://9.114.219.72:9292'}], u'endpoints_links': [], u'type': u'image', u'name': u'Image Service'}, {u'endpoints': [{u'adminURL': u'http://9.114.219.72:8776/v1/ebb53d9cfa214a85a1bdcfa89ed213a4', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:8776/v1/ebb53d9cfa214a85a1bdcfa89ed213a4', u'publicURL': u'http://9.114.219.72:8776/v1/ebb53d9cfa214a85a1bdcfa89ed213a4'}], u'endpoints_links': [], u'type': u'volume', u'name': u'Volume Service'}, {u'endpoints': [{u'adminURL': ': u'http://9.114.219.72:8776/v1/ebb53d9cfa214a85a1bdcfa89ed213a4', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:8776/v1/ebb53d9cfa214a85a1bdcfa89ed213a4', u'publicURL': u'http://9.114.219.72:8776/v1/ebb53d9cfa214a85a1bdcfa89ed213a4'}], u'endpoints_links': [], u'type': u'volume', u'name': u'Volume Service'}, {u'endpoints': [{u'adminURL': u'http://9.114.219.72:8773/services/Admin', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:8773/services/Cloud', u'publicURL': u'http://9.114.219.72:8773/services/Cloud'}], u'endpoints_links': [], u'type': u'ec2', u'name': u'EC2 Service'}, {u'endpoints': [{u'adminURL': u'http://9.114.219.72:8080/', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:8080/v1/AUTH_ebb53d9cfa214a85a1bdcfa89ed213a4', u'publicURL': u'http://9.114.219.72:8080/v1/AUTH_ebb53d9cfa214a85a1bdcfa89ed213a4'}], u'endpoints_links': [], u'type': u'object-store', u'name': u'Swift Service'}, {u'endpoints': [{u'adminURL': u'http://9.114.219.72:35357/v2.0', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:5000/v2.0', u'publicURL': u'http://9.114.219.72:5000/v2.0'}], u'endpoints_links': [], u'type': u'identity', u'name': u'Identity Service'}], u'_context_user_name': u'admin', u'_context_auth_token': '', u'args': {u'image_type': u'snapshot', u'instance': {u'vm_state': u'active', u'availability_zone': None, u'terminated_at': None, u'ephemeral_gb': 0, u'instance_type_id': 2, u'user_data': u'', u'vm_mode': None, u'deleted_at': None, u'reservation_id': u'r-76pvcrss', u'id': 1, u'security_groups': [{u'deleted_at': None, u'user_id': u'bbb85ef7ca424c5293fda5ce32b2e896', u'name': u'default', u'deleted': False, u'created_at': u'2012-08-09T00:26:22.000000', u'updated_at': None, u'rules': [], u'project_id': u'ebb53d9cfa214a85a1bdcfa89ed213a4', u'id': 1, u'description': u'default'}], u'disable_terminate': False, u'user_id': u'bbb85ef7ca424c5293fda5ce32b2e896', u'uuid': u'9cdf4843-54d1-497e-bd70-3f3157e8ca03', u'server_name': None, u'default_swap_device': None, u'info_cache': {u'instance_uuid': u'9cdf4843-54d1-497e-bd70-3f3157e8ca03', u'deleted': False, u'created_at': u'2012-08-09T00:26:43.000000', u'updated_at': u'2012-08-09T00:26:47.000000', u'network_info': u'[{"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.1"}, "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": null}, "dns": [], "routes": [], "cidr": null, "gateway": {"meta": {}, "version": null, "type": "gateway", "address": null}}], "meta": {"tenant_id": null, "should_create_bridge": true, "bridge_interface": "eth0"}, "id": "c0d13cb6-8143-41ba-8e81-72162e633eab", "label": "private"}, "meta": {}, "id": "4c6453c4-881a-4efe-9871-906a221de10d", "address": "fa:16:3e:1c:a2:e9"}]', u'deleted_at': None, u'id': 1}, u'hostname': u'server', u'launched_on': u'parrish', u'display_description': u'server', u'key_data': None, u'kernel_id': u'dfe539db-2f73-48af-83cc-7e86f39b5db8', u'power_state': 1, u'default_ephemeral_device': None, u'progress': 0, u'project_id': u'ebb53d9cfa214a85a1bdcfa89ed213a4', u'launched_at': u'2012-08-09T00:27:02.000000', u'scheduled_at': u'2012-08-09T00:26:43.000000', u'ramdisk_id': u'a57fdd99-dfda-4b7c-bf43-e44ef3abb80e', u'access_ip_v6': None, u'access_ip_v4': None, u'deleted': False, u'key_name': None, u'updated_at': u'2012-08-09T00:27:02.000000', u'host': u'parrish', u'display_name': u'server', u'task_state': None, u'shutdown_terminate': False, u'architecture': None, u'root_gb': 0, u'locked': False, u'name': u'instance-00000001', u'created_at': u'2012-08-09T00:26:43.000000', u'launch_index': 0, u'metadata': [], u'memory_mb': 512, u'instance_type': {u'disabled': False, u'root_gb': 0, u'name': u'm1.tiny', u'deleted': False, u'created_at': None, u'ephemeral_gb': 0, u'updated_at': None, u'memory_mb': 512, u'vcpus': 1, u'flavorid': u'1', u'swap': 0, u'rxtx_factor': 1.0, u'deleted_at': None, u'vcpu_weight': None, u'id': 2}, u'vcpus': 1, u'image_ref': u'4a4ab972-ae37-4b2b-a6b4-916921598b73', u'root_device_name': u'/dev/vda', u'auto_disk_config': None, u'os_type': None, u'config_drive': u''}, u'backup_type': None, u'rotation': None, u'image_id': u'a2203a98-f634-4cf2-9df6-d197ed752f25'}, u'_context_instance_lock_checked': False, u'_context_is_admin': True, u'version': u'1.34', u'_context_project_id': u'ebb53d9cfa214a85a1bdcfa89ed213a4', u'_context_timestamp': u'2012-08-09T00:28:28.281166', u'_context_read_deleted': u'no', u'_context_user_id': u'bbb85ef7ca424c5293fda5ce32b2e896', u'method': u'snapshot_instance', u'_context_remote_address': u'9.114.219.72'} 2012-08-08 20:28:28 DEBUG nova.openstack.common.rpc.amqp [-] unpacked context: {'project_name': u'demo', 'user_id': u'bbb85ef7ca424c5293fda5ce32b2e896', 'roles': [u'admin'], 'timestamp': u'2012-08-09T00:28:28.281166', 'auth_token': '', 'remote_address': u'9.114.219.72', 'quota_class': None, 'is_admin': True, 'service_catalog': [{u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://9.114.219.72:8774/v2/ebb53d9cfa214a85a1bdcfa89ed213a4', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:8774/v2/ebb53d9cfa214a85a1bdcfa89ed213a4', u'publicURL': u'http://9.114.219.72:8774/v2/ebb53d9cfa214a85a1bdcfa89ed213a4'}], u'type': u'compute', u'name': u'Compute Service'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://9.114.219.72:3333', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:3333', u'publicURL': u'http://9.114.219.72:3333'}], u'type': u's3', u'name': u'S3 Service'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://9.114.219.72:9292', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:9292', u'publicURL': u'http://9.114.219.72:9292'}], u'type': u'image', u'name': u'Image Service'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://9.114.219.72:8776/v1/ebb53d9cfa214a85a1bdcfa89ed213a4', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:8776/v1/ebb53d9cfa214a85a1bdcfa89ed213a4', u'publicURL': u'http://9.114.219.72:8776/v1/ebb53d9cfa214a85a1bdcfa89ed213a4'}], u'type': u'volume', u'name': u'Volume Service'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://9.114.219.72:8773/services/Admin', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:8773/services/Cloud', u'publicURL': u'http://9.114.219.72:8773/services/Cloud'}], u'type': u'ec2', u'name': u'EC2 Service'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://9.114.219.72:8080/', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:8080/v1/AUTH_ebb53d9cfa214a85a1bdcfa89ed213a4', u'publicURL': u'http://9.114.219.72:8080/v1/AUTH_ebb53d9cfa214a85a1bdcfa89ed213a4'}], u'type': u'object-store', u'name': u'Swift Service'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://9.114.219.72:35357/v2.0', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:5000/v2.0', u'publicURL': u'http://9.114.219.72:5000/v2.0'}], u'type': u'identity', u'name': u'Identity Service'}], 'request_id': u'req-7b1bb5b3-e70e-4088-b6d1-0946c24e6445', 'instance_lock_checked': False, 'project_id': u'ebb53d9cfa214a85a1bdcfa89ed213a4', 'user_name': u'admin', 'read_deleted': u'no'} from (pid=31416) _safe_log /opt/stack/nova/nova/openstack/common/rpc/common.py:197 unpacked context: {'project_name': u'demo', 'user_id': u'bbb85ef7ca424c5293fda5ce32b2e896', 'roles': [u'admin'], 'timestamp': u'2012-08-09T00:28:28.281166', 'auth_token': '', 'remote_address': u'9.114.219.72', 'quota_class': None, 'is_admin': True, 'service_catalog': [{u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://9.114.219.72:8774/v2/ebb53d9cfa214a85a1bdcfa89ed213a4', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:8774/v2/ebb53d9cfa214a85a1bdcfa89ed213a4', u'publicURL': u'http://9.114.219.72:8774/v2/ebb53d9cfa214a85a1bdcfa89ed213a4'}], u'type': u'compute', u'name': u'Compute Service'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://9.114.219.72:3333', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:3333', u'publicURL': u'http://9.114.219.72:3333'}], u'type': u's3', u'name': u'S3 Service'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://9.114.219.72:9292', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:9292', u'publicURL': u'http://9.114.219.72:9292'}], u'type': u'image', u'name': u'Image Service'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://9.114.219.72:8776/v1/ebb53d9cfa214a85a1bdcfa89ed213a4', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:8776/v1/ebb53d9cfa214a85a1bdcfa89ed213a4', u'publicURL': u'http://9.114.219.72:8776/v1/ebb53d9cfa214a85a1bdcfa89ed213a4'}], u'type': u'volume', u'name': u'Volume Service'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://9.114.219.72:8773/services/Admin', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:8773/services/Cloud', u'publicURL': u'http://9.114.219.72:8773/services/Cloud'}], u'type': u'ec2', u'name': u'EC2 Service'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://9.114.219.72:8080/', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:8080/v1/AUTH_ebb53d9cfa214a85a1bdcfa89ed213a4', u'publicURL': u'http://9.114.219.72:8080/v1/AUTH_ebb53d9cfa214a85a1bdcfa89ed213a4'}], u'type': u'object-store', u'name': u'Swift Service'}, {u'endpoints_links': [], u'endpoints': [{u'adminURL': u'http://9.114.219.72:35357/v2.0', u'region': u'RegionOne', u'internalURL': u'http://9.114.219.72:5000/v2.0', u'publicURL': u'http://9.114.219.72:5000/v2.0'}], u'type': u'identity', u'name': u'Identity Service'}], 'request_id': u'req-7b1bb5b3-e70e-4088-b6d1-0946c24e6445', 'instance_lock_checked': False, 'project_id': u'ebb53d9cfa214a85a1bdcfa89ed213a4', 'user_name': u'admin', 'read_deleted': u'no'} 2012-08-08 20:28:28 DEBUG nova.compute.manager [req-7b1bb5b3-e70e-4088-b6d1-0946c24e6445 admin demo] [instance: 9cdf4843-54d1-497e-bd70-3f3157e8ca03] Checking state from (pid=31416) _get_power_state /opt/stack/nova/nova/compute/manager.py:318 Checking state 2012-08-08 20:28:31 AUDIT nova.compute.manager [req-7b1bb5b3-e70e-4088-b6d1-0946c24e6445 admin demo] [instance: 9cdf4843-54d1-497e-bd70-3f3157e8ca03] instance snapshotting instance snapshotting 2012-08-08 20:28:38 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_unconfirmed_resizes from (pid=31416) periodic_tasks /opt/stack/nova/nova/manager.py:170 Running periodic task ComputeManager._poll_unconfirmed_resizes 2012-08-08 20:28:40 DEBUG nova.utils [req-7b1bb5b3-e70e-4088-b6d1-0946c24e6445 admin demo] Running cmd (subprocess): sudo /usr/local/bin/nova-rootwrap /etc/nova/rootwrap.conf qemu-img snapshot -c 1479c0152ae1453e96018275fc3c9197 None from (pid=31416) execute /opt/stack/nova/nova/utils.py:178 Running cmd (subprocess): sudo /usr/local/bin/nova-rootwrap /etc/nova/rootwrap.conf qemu-img snapshot -c 1479c0152ae1453e96018275fc3c9197 None 2012-08-08 20:28:40 DEBUG nova.utils [req-7b1bb5b3-e70e-4088-b6d1-0946c24e6445 admin demo] Result was 1 from (pid=31416) execute /opt/stack/nova/nova/utils.py:194 Result was 1 2012-08-08 20:28:40 ERROR nova.openstack.common.rpc.amqp [-] Exception during message handling 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp Traceback (most recent call last): 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/openstack/common/rpc/amqp.py", line 275, in _process_data 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp rval = self.proxy.dispatch(ctxt, version, method, **args) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/openstack/common/rpc/dispatcher.py", line 145, in dispatch 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp return getattr(proxyobj, method)(ctxt, **kwargs) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/exception.py", line 117, in wrapped 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp temp_level, payload) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.7/contextlib.py", line 24, in __exit__ 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp self.gen.next() 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/exception.py", line 92, in wrapped 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp return f(*args, **kw) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/compute/manager.py", line 210, in decorated_function 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp instance_uuid, e, sys.exc_info()) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.7/contextlib.py", line 24, in __exit__ 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp self.gen.next() 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/compute/manager.py", line 199, in decorated_function 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp return function(self, context, *args, **kwargs) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/compute/manager.py", line 1118, in snapshot_instance 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp self.driver.snapshot(context, instance, image_id) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/exception.py", line 117, in wrapped 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp temp_level, payload) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.7/contextlib.py", line 24, in __exit__ 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp self.gen.next() 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/exception.py", line 92, in wrapped 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp return f(*args, **kw) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/virt/libvirt/driver.py", line 798, in snapshot 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp libvirt_utils.create_snapshot(disk_path, snapshot_name) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/virt/libvirt/utils.py", line 308, in create_snapshot 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp execute(*qemu_img_cmd, run_as_root=True) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/virt/libvirt/utils.py", line 54, in execute 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp return utils.execute(*args, **kwargs) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/utils.py", line 201, in execute 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp cmd=' '.join(cmd)) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp ProcessExecutionError: Unexpected error while running command. 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp Command: sudo /usr/local/bin/nova-rootwrap /etc/nova/rootwrap.conf qemu-img snapshot -c 1479c0152ae1453e96018275fc3c9197 None 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp Exit code: 1 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp Stdout: '' 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp Stderr: "qemu-img: Could not open 'None': No such file or directory\n" 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp Exception during message handling 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp Traceback (most recent call last): 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/openstack/common/rpc/amqp.py", line 275, in _process_data 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp rval = self.proxy.dispatch(ctxt, version, method, **args) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/openstack/common/rpc/dispatcher.py", line 145, in dispatch 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp return getattr(proxyobj, method)(ctxt, **kwargs) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/exception.py", line 117, in wrapped 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp temp_level, payload) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.7/contextlib.py", line 24, in __exit__ 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp self.gen.next() 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/exception.py", line 92, in wrapped 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp return f(*args, **kw) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/compute/manager.py", line 210, in decorated_function 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp instance_uuid, e, sys.exc_info()) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.7/contextlib.py", line 24, in __exit__ 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp self.gen.next() 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/compute/manager.py", line 199, in decorated_function 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp return function(self, context, *args, **kwargs) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/compute/manager.py", line 1118, in snapshot_instance 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp self.driver.snapshot(context, instance, image_id) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/exception.py", line 117, in wrapped 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp temp_level, payload) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/usr/lib/python2.7/contextlib.py", line 24, in __exit__ 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp self.gen.next() 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/exception.py", line 92, in wrapped 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp return f(*args, **kw) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/virt/libvirt/driver.py", line 798, in snapshot 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp libvirt_utils.create_snapshot(disk_path, snapshot_name) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/virt/libvirt/utils.py", line 308, in create_snapshot 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp execute(*qemu_img_cmd, run_as_root=True) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/virt/libvirt/utils.py", line 54, in execute 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp return utils.execute(*args, **kwargs) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp File "/opt/stack/nova/nova/utils.py", line 201, in execute 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp cmd=' '.join(cmd)) 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp ProcessExecutionError: Unexpected error while running command. 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp Command: sudo /usr/local/bin/nova-rootwrap /etc/nova/rootwrap.conf qemu-img snapshot -c 1479c0152ae1453e96018275fc3c9197 None 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp Exit code: 1 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp Stdout: '' 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp Stderr: "qemu-img: Could not open 'None': No such file or directory\n" 2012-08-08 20:28:40 TRACE nova.openstack.common.rpc.amqp