Revision history [back]

click to hide/show revision 1
initial version

VMware with nova compute issue br100 not found

while creating an instance getting below error. using esx4.1 as hypervisor. Not able to launch a single instance successfully. Please help.

2012-07-04 09:09:07 DEBUG nova.manager [-] Running periodic task ComputeManager._publish_service_capabilities from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:152 2012-07-04 09:09:07 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_rescued_instances from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:152 2012-07-04 09:09:07 DEBUG nova.manager [-] Skipping ComputeManager._sync_power_states, 1 ticks left until next run from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:147 2012-07-04 09:09:07 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_bandwidth_usage from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:152 2012-07-04 09:09:07 DEBUG nova.manager [-] Running periodic task ComputeManager.update_available_resource from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:152 2012-07-04 09:09:07 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_rebooting_instances from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:152 2012-07-04 09:09:07 DEBUG nova.manager [-] Skipping ComputeManager._cleanup_running_deleted_instances, 22 ticks left until next run from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:147 2012-07-04 09:09:07 DEBUG nova.manager [-] Running periodic task ComputeManager._heal_instance_info_cache from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:152 2012-07-04 09:09:07 DEBUG nova.rpc.amqp [-] Making asynchronous call on network ... from (pid=5854) multicall /usr/lib/python2.7/dist-packages/nova/rpc/amqp.py:321 2012-07-04 09:09:07 DEBUG nova.rpc.amqp [-] MSG_ID is 5d9fc12781a74be1b29db816e489ae53 from (pid=5854) multicall /usr/lib/python2.7/dist-packages/nova/rpc/amqp.py:324 2012-07-04 09:09:07 DEBUG nova.compute.manager [-] Updated the info_cache for instance df7c43c1-ba3b-4c4b-9e0f-20606d20deb8 from (pid=5854) _heal_instance_info_cache /usr/lib/python2.7/dist-packages/nova/compute/manager.py:2227 2012-07-04 09:09:07 DEBUG nova.manager [-] Skipping ComputeManager._run_image_cache_manager_pass, 15 ticks left until next run from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:147 2012-07-04 09:09:07 DEBUG nova.manager [-] Running periodic task ComputeManager._reclaim_queued_deletes from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:152 2012-07-04 09:09:07 DEBUG nova.compute.manager [-] FLAGS.reclaim_instance_interval <= 0, skipping... from (pid=5854) _reclaim_queued_deletes /usr/lib/python2.7/dist-packages/nova/compute/manager.py:2380 2012-07-04 09:09:07 DEBUG nova.manager [-] Running periodic task ComputeManager._report_driver_status from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:152 2012-07-04 09:09:07 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_unconfirmed_resizes from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:152 2012-07-04 09:09:27 DEBUG nova.rpc.amqp [-] received {u'_context_roles': [u'admin', u'Member'], u'_context_request_id': u'req-2897fe85-5bfd-468d-bbb8-fdce336ff964', u'_context_read_deleted': u'no', u'args': {u'instance_uuid': u'df7c43c1-ba3b-4c4b-9e0f-20606d20deb8'}, u'_context_auth_token': '<sanitized>', u'_context_is_admin': True, u'_context_project_id': u'5025a843d0684553922f1c20ae64550a', u'_context_timestamp': u'2012-07-04T03:39:27.678802', u'_context_user_id': u'131cf2e26c8145ec9cdedf95e9a3fbca', u'method': u'terminate_instance', u'_context_remote_address': u'192.168.230.74'} from (pid=5854) _safe_log /usr/lib/python2.7/dist-packages/nova/rpc/common.py:160 2012-07-04 09:09:27 DEBUG nova.rpc.amqp [req-2897fe85-5bfd-468d-bbb8-fdce336ff964 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] unpacked context: {'user_id': u'131cf2e26c8145ec9cdedf95e9a3fbca', 'roles': [u'admin', u'Member'], 'timestamp': '2012-07-04T03:39:27.678802', 'auth_token': '<sanitized>', 'remote_address': u'192.168.230.74', 'is_admin': True, 'request_id': u'req-2897fe85-5bfd-468d-bbb8-fdce336ff964', 'project_id': u'5025a843d0684553922f1c20ae64550a', 'read_deleted': u'no'} from (pid=5854) _safe_log /usr/lib/python2.7/dist-packages/nova/rpc/common.py:160 2012-07-04 09:09:27 INFO nova.compute.manager [req-2897fe85-5bfd-468d-bbb8-fdce336ff964 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] check_instance_lock: decorating: |<function terminate_instance="" at="" 0x22cbed8="">| 2012-07-04 09:09:27 INFO nova.compute.manager [req-2897fe85-5bfd-468d-bbb8-fdce336ff964 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] check_instance_lock: arguments: |<nova.compute.manager.computemanager object="" at="" 0x7f4cac659c10="">| |<nova.rpc.amqp.rpccontext object="" at="" 0xbc1c5d0="">| |df7c43c1-ba3b-4c4b-9e0f-20606d20deb8| 2012-07-04 09:09:27 DEBUG nova.compute.manager [req-2897fe85-5bfd-468d-bbb8-fdce336ff964 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] instance df7c43c1-ba3b-4c4b-9e0f-20606d20deb8: getting locked state from (pid=5854) get_lock /usr/lib/python2.7/dist-packages/nova/compute/manager.py:1597 2012-07-04 09:09:27 INFO nova.compute.manager [req-2897fe85-5bfd-468d-bbb8-fdce336ff964 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] check_instance_lock: locked: |False| 2012-07-04 09:09:27 INFO nova.compute.manager [req-2897fe85-5bfd-468d-bbb8-fdce336ff964 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] check_instance_lock: admin: |True| 2012-07-04 09:09:27 INFO nova.compute.manager [req-2897fe85-5bfd-468d-bbb8-fdce336ff964 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] check_instance_lock: executing: |<function terminate_instance="" at="" 0x22cbed8="">| 2012-07-04 09:09:27 DEBUG nova.utils [req-2897fe85-5bfd-468d-bbb8-fdce336ff964 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] Attempting to grab semaphore "df7c43c1-ba3b-4c4b-9e0f-20606d20deb8" for method "do_terminate_instance"... from (pid=5854) inner /usr/lib/python2.7/dist-packages/nova/utils.py:927 2012-07-04 09:09:27 DEBUG nova.utils [req-2897fe85-5bfd-468d-bbb8-fdce336ff964 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] Got semaphore "df7c43c1-ba3b-4c4b-9e0f-20606d20deb8" for method "do_terminate_instance"... from (pid=5854) inner /usr/lib/python2.7/dist-packages/nova/utils.py:931 2012-07-04 09:09:27 WARNING nova.utils [req-2897fe85-5bfd-468d-bbb8-fdce336ff964 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] /usr/lib/python2.7/dist-packages/sqlalchemy/sql/expression.py:1869: SAWarning: The IN-predicate on "bw_usage_cache.mac" was invoked with an empty sequence. This results in a contradiction, which nonetheless can be expensive to evaluate. Consider alternative strategies for improved performance. return self._in_impl(operators.in_op, operators.notin_op, other)

2012-07-04 09:09:27 AUDIT nova.compute.manager [req-2897fe85-5bfd-468d-bbb8-fdce336ff964 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] [instance: df7c43c1-ba3b-4c4b-9e0f-20606d20deb8] Terminating instance 2012-07-04 09:09:27 DEBUG nova.rpc.amqp [req-2897fe85-5bfd-468d-bbb8-fdce336ff964 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] Making asynchronous call on network ... from (pid=5854) multicall /usr/lib/python2.7/dist-packages/nova/rpc/amqp.py:321 2012-07-04 09:09:27 DEBUG nova.rpc.amqp [req-2897fe85-5bfd-468d-bbb8-fdce336ff964 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] MSG_ID is c9b0d811d60040d29709dd5539e68f5b from (pid=5854) multicall /usr/lib/python2.7/dist-packages/nova/rpc/amqp.py:324 2012-07-04 09:09:28 DEBUG nova.compute.manager [req-2897fe85-5bfd-468d-bbb8-fdce336ff964 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] [instance: df7c43c1-ba3b-4c4b-9e0f-20606d20deb8] Deallocating network for instance from (pid=5854) _deallocate_network /usr/lib/python2.7/dist-packages/nova/compute/manager.py:616 2012-07-04 09:09:28 DEBUG nova.rpc.amqp [req-2897fe85-5bfd-468d-bbb8-fdce336ff964 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] Making asynchronous cast on network... from (pid=5854) cast /usr/lib/python2.7/dist-packages/nova/rpc/amqp.py:346 2012-07-04 09:09:28 DEBUG nova.virt.vmwareapi.vmops [req-2897fe85-5bfd-468d-bbb8-fdce336ff964 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] instance - instance-00000001 not present from (pid=5854) destroy /usr/lib/python2.7/dist-packages/nova/virt/vmwareapi/vmops.py:548 2012-07-04 09:10:07 DEBUG nova.manager [-] Running periodic task ComputeManager._publish_service_capabilities from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:152 2012-07-04 09:10:07 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_rescued_instances from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:152 2012-07-04 09:10:07 DEBUG nova.manager [-] Running periodic task ComputeManager._sync_power_states from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:152 2012-07-04 09:10:07 DEBUG nova.virt.vmwareapi.vmops [-] Getting list of instances from (pid=5854) list_instances /usr/lib/python2.7/dist-packages/nova/virt/vmwareapi/vmops.py:66 2012-07-04 09:10:07 DEBUG nova.virt.vmwareapi.vmops [-] Got total of 2 instances from (pid=5854) list_instances /usr/lib/python2.7/dist-packages/nova/virt/vmwareapi/vmops.py:82 2012-07-04 09:10:07 WARNING nova.compute.manager [-] Found 0 in the database and 2 on the hypervisor. 2012-07-04 09:10:07 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_bandwidth_usage from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:152 2012-07-04 09:10:07 DEBUG nova.manager [-] Running periodic task ComputeManager.update_available_resource from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:152 2012-07-04 09:10:07 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_rebooting_instances from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:152 2012-07-04 09:10:07 DEBUG nova.manager [-] Skipping ComputeManager._cleanup_running_deleted_instances, 21 ticks left until next run from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:147 2012-07-04 09:10:07 DEBUG nova.manager [-] Running periodic task ComputeManager._heal_instance_info_cache from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:152 2012-07-04 09:10:07 DEBUG nova.manager [-] Skipping ComputeManager._run_image_cache_manager_pass, 14 ticks left until next run from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:147 2012-07-04 09:10:07 DEBUG nova.manager [-] Running periodic task ComputeManager._reclaim_queued_deletes from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:152 2012-07-04 09:10:07 DEBUG nova.compute.manager [-] FLAGS.reclaim_instance_interval <= 0, skipping... from (pid=5854) _reclaim_queued_deletes /usr/lib/python2.7/dist-packages/nova/compute/manager.py:2380 2012-07-04 09:10:07 DEBUG nova.manager [-] Running periodic task ComputeManager._report_driver_status from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:152 2012-07-04 09:10:07 INFO nova.compute.manager [-] Updating host status 2012-07-04 09:10:07 ERROR nova.manager [-] Error during ComputeManager._report_driver_status: 2012-07-04 09:10:07 TRACE nova.manager Traceback (most recent call last): 2012-07-04 09:10:07 TRACE nova.manager File "/usr/lib/python2.7/dist-packages/nova/manager.py", line 155, in periodic_tasks 2012-07-04 09:10:07 TRACE nova.manager task(self, context) 2012-07-04 09:10:07 TRACE nova.manager File "/usr/lib/python2.7/dist-packages/nova/compute/manager.py", line 2281, in _report_driver_status 2012-07-04 09:10:07 TRACE nova.manager self.driver.get_host_stats(refresh=True)) 2012-07-04 09:10:07 TRACE nova.manager File "/usr/lib/python2.7/dist-packages/nova/virt/driver.py", line 576, in get_host_stats 2012-07-04 09:10:07 TRACE nova.manager raise NotImplementedError() 2012-07-04 09:10:07 TRACE nova.manager NotImplementedError 2012-07-04 09:10:07 TRACE nova.manager 2012-07-04 09:10:07 DEBUG nova.manager [-] Running periodic task ComputeManager._poll_unconfirmed_resizes from (pid=5854) periodic_tasks /usr/lib/python2.7/dist-packages/nova/manager.py:152 2012-07-04 09:10:16 DEBUG nova.rpc.amqp [-] received {u'_context_roles': [u'admin', u'Member'], u'_context_request_id': u'req-e7cbfa86-dedc-43ba-a061-5575a85272c1', u'_context_read_deleted': u'no', u'args': {u'instance_uuid': u'5443f83c-88a8-4169-80a9-4cecd6170f21', u'is_first_time': True, u'filter_properties': {u'scheduler_hints': {}}, u'admin_password': '<sanitized>', u'injected_files': [], u'requested_networks': None}, u'_context_auth_token': '<sanitized>', u'_context_is_admin': True, u'_context_project_id': u'5025a843d0684553922f1c20ae64550a', u'_context_timestamp': u'2012-07-04T03:40:16.032733', u'_context_user_id': u'131cf2e26c8145ec9cdedf95e9a3fbca', u'method': u'run_instance', u'_context_remote_address': u'192.168.230.74'} from (pid=5854) _safe_log /usr/lib/python2.7/dist-packages/nova/rpc/common.py:160 2012-07-04 09:10:16 DEBUG nova.rpc.amqp [req-e7cbfa86-dedc-43ba-a061-5575a85272c1 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] unpacked context: {'user_id': u'131cf2e26c8145ec9cdedf95e9a3fbca', 'roles': [u'admin', u'Member'], 'timestamp': '2012-07-04T03:40:16.032733', 'auth_token': '<sanitized>', 'remote_address': u'192.168.230.74', 'is_admin': True, 'request_id': u'req-e7cbfa86-dedc-43ba-a061-5575a85272c1', 'project_id': u'5025a843d0684553922f1c20ae64550a', 'read_deleted': u'no'} from (pid=5854) _safe_log /usr/lib/python2.7/dist-packages/nova/rpc/common.py:160 2012-07-04 09:10:16 DEBUG nova.utils [req-e7cbfa86-dedc-43ba-a061-5575a85272c1 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] Attempting to grab semaphore "5443f83c-88a8-4169-80a9-4cecd6170f21" for method "do_run_instance"... from (pid=5854) inner /usr/lib/python2.7/dist-packages/nova/utils.py:927 2012-07-04 09:10:16 DEBUG nova.utils [req-e7cbfa86-dedc-43ba-a061-5575a85272c1 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] Got semaphore "5443f83c-88a8-4169-80a9-4cecd6170f21" for method "do_run_instance"... from (pid=5854) inner /usr/lib/python2.7/dist-packages/nova/utils.py:931 2012-07-04 09:10:16 DEBUG nova.virt.vmwareapi.vmops [req-e7cbfa86-dedc-43ba-a061-5575a85272c1 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] Getting list of instances from (pid=5854) list_instances /usr/lib/python2.7/dist-packages/nova/virt/vmwareapi/vmops.py:66 2012-07-04 09:10:16 DEBUG nova.virt.vmwareapi.vmops [req-e7cbfa86-dedc-43ba-a061-5575a85272c1 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] Got total of 2 instances from (pid=5854) list_instances /usr/lib/python2.7/dist-packages/nova/virt/vmwareapi/vmops.py:82 2012-07-04 09:10:16 DEBUG nova.compute.manager [req-e7cbfa86-dedc-43ba-a061-5575a85272c1 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] image_id=6f544711-0a90-468f-ad7d-0fce91e609f4, image_size_bytes=2666070016, allowed_size_bytes=21474836480 from (pid=5854) _check_image_size /usr/lib/python2.7/dist-packages/nova/compute/manager.py:525 2012-07-04 09:10:16 AUDIT nova.compute.manager [req-e7cbfa86-dedc-43ba-a061-5575a85272c1 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] [instance: 5443f83c-88a8-4169-80a9-4cecd6170f21] Starting instance... 2012-07-04 09:10:16 DEBUG nova.rpc.amqp [req-e7cbfa86-dedc-43ba-a061-5575a85272c1 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] Making asynchronous call on network ... from (pid=5854) multicall /usr/lib/python2.7/dist-packages/nova/rpc/amqp.py:321 2012-07-04 09:10:16 DEBUG nova.rpc.amqp [req-e7cbfa86-dedc-43ba-a061-5575a85272c1 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] MSG_ID is 60ebe5abc5ad4d029d1781780d3ec861 from (pid=5854) multicall /usr/lib/python2.7/dist-packages/nova/rpc/amqp.py:324 2012-07-04 09:10:18 DEBUG nova.compute.manager [req-e7cbfa86-dedc-43ba-a061-5575a85272c1 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] [instance: 5443f83c-88a8-4169-80a9-4cecd6170f21] Instance network_info: |[VIF({'network': Network({'bridge': u'br100', 'subnets': [Subnet({'ips': [FixedIP({'meta': {}, 'version': 4, 'type': u'fixed', 'floating_ips': [], 'address': u'192.168.230.34'})], 'version': 4, 'meta': {u'dhcp_server': u'192.168.230.33'}, 'dns': [IP({'meta': {}, 'version': 4, 'type': u'dns', 'address': u'8.8.4.4'})], 'routes': [], 'cidr': u'192.168.230.32/27', 'gateway': IP({'meta': {}, 'version': 4, 'type': u'gateway', 'address': u'192.168.230.33'})}), Subnet({'ips': [], 'version': None, 'meta': {u'dhcp_server': None}, 'dns': [], 'routes': [], 'cidr': None, 'gateway': IP({'meta': {}, 'version': None, 'type': u'gateway', 'address': None})})], 'meta': {u'tenant_id': None, u'should_create_bridge': True, u'bridge_interface': u'eth1'}, 'id': u'27532003-a3f0-4bb0-b47f-b6e37a62ffde', 'label': u'private'}), 'meta': {}, 'id': u'ac717eb9-9ee9-4395-af37-0b6e0a689748', 'address': u'fa:16:3e:10:e8:a2'})]| from (pid=5854) _allocate_network /usr/lib/python2.7/dist-packages/nova/compute/manager.py:566 2012-07-04 09:10:18 DEBUG nova.virt.vmwareapi.vmware_images [req-e7cbfa86-dedc-43ba-a061-5575a85272c1 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] Getting image size for the image 6f544711-0a90-468f-ad7d-0fce91e609f4 from (pid=5854) get_vmdk_size_and_properties /usr/lib/python2.7/dist-packages/nova/virt/vmwareapi/vmware_images.py:139 2012-07-04 09:10:18 DEBUG nova.virt.vmwareapi.vmware_images [req-e7cbfa86-dedc-43ba-a061-5575a85272c1 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] Got image size of 2666070016 for the image 6f544711-0a90-468f-ad7d-0fce91e609f4 from (pid=5854) get_vmdk_size_and_properties /usr/lib/python2.7/dist-packages/nova/virt/vmwareapi/vmware_images.py:144 2012-07-04 09:10:18 WARNING nova.virt.vmwareapi.network_utils [req-e7cbfa86-dedc-43ba-a061-5575a85272c1 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] [(ManagedObjectReference){ value = "HaNetwork-VM Network" _type = "Network" }, (ManagedObjectReference){ value = "HaNetwork-Test Network" _type = "Network" }] 2012-07-04 09:10:19 ERROR nova.compute.manager [req-e7cbfa86-dedc-43ba-a061-5575a85272c1 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] [instance: 5443f83c-88a8-4169-80a9-4cecd6170f21] Instance failed to spawn 2012-07-04 09:10:19 TRACE nova.compute.manager [instance: 5443f83c-88a8-4169-80a9-4cecd6170f21] Traceback (most recent call last): 2012-07-04 09:10:19 TRACE nova.compute.manager [instance: 5443f83c-88a8-4169-80a9-4cecd6170f21] File "/usr/lib/python2.7/dist-packages/nova/compute/manager.py", line 592, in _spawn 2012-07-04 09:10:19 TRACE nova.compute.manager [instance: 5443f83c-88a8-4169-80a9-4cecd6170f21] self._legacy_nw_info(network_info), block_device_info) 2012-07-04 09:10:19 TRACE nova.compute.manager [instance: 5443f83c-88a8-4169-80a9-4cecd6170f21] File "/usr/lib/python2.7/dist-packages/nova/virt/vmwareapi_conn.py", line 135, in spawn 2012-07-04 09:10:19 TRACE nova.compute.manager [instance: 5443f83c-88a8-4169-80a9-4cecd6170f21] self._vmops.spawn(context, instance, image_meta, network_info) 2012-07-04 09:10:19 TRACE nova.compute.manager [instance: 5443f83c-88a8-4169-80a9-4cecd6170f21] File "/usr/lib/python2.7/dist-packages/nova/virt/vmwareapi/vmops.py", line 187, in spawn 2012-07-04 09:10:19 TRACE nova.compute.manager [instance: 5443f83c-88a8-4169-80a9-4cecd6170f21] vif_infos = _get_vif_infos() 2012-07-04 09:10:19 TRACE nova.compute.manager [instance: 5443f83c-88a8-4169-80a9-4cecd6170f21] File "/usr/lib/python2.7/dist-packages/nova/virt/vmwareapi/vmops.py", line 180, in _get_vif_infos 2012-07-04 09:10:19 TRACE nova.compute.manager [instance: 5443f83c-88a8-4169-80a9-4cecd6170f21] network_ref = _check_if_network_bridge_exists(network_name) 2012-07-04 09:10:19 TRACE nova.compute.manager [instance: 5443f83c-88a8-4169-80a9-4cecd6170f21] File "/usr/lib/python2.7/dist-packages/nova/virt/vmwareapi/vmops.py", line 168, in _check_if_network_bridge_exists 2012-07-04 09:10:19 TRACE nova.compute.manager [instance: 5443f83c-88a8-4169-80a9-4cecd6170f21] raise exception.NetworkNotFoundForBridge(bridge=network_name) 2012-07-04 09:10:19 TRACE nova.compute.manager [instance: 5443f83c-88a8-4169-80a9-4cecd6170f21] NetworkNotFoundForBridge: Network could not be found for bridge br100 2012-07-04 09:10:19 TRACE nova.compute.manager [instance: 5443f83c-88a8-4169-80a9-4cecd6170f21] 2012-07-04 09:10:19 DEBUG nova.compute.manager [req-e7cbfa86-dedc-43ba-a061-5575a85272c1 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] [instance: 5443f83c-88a8-4169-80a9-4cecd6170f21] Deallocating network for instance from (pid=5854) _deallocate_network /usr/lib/python2.7/dist-packages/nova/compute/manager.py:616 2012-07-04 09:10:19 DEBUG nova.rpc.amqp [req-e7cbfa86-dedc-43ba-a061-5575a85272c1 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] Making asynchronous cast on network... from (pid=5854) cast /usr/lib/python2.7/dist-packages/nova/rpc/amqp.py:346 2012-07-04 09:10:19 ERROR nova.rpc.amqp [req-e7cbfa86-dedc-43ba-a061-5575a85272c1 131cf2e26c8145ec9cdedf95e9a3fbca 5025a843d0684553922f1c20ae64550a] Exception during message handling 2012-07-04 09:10:19 TRACE nova.rpc.amqp Traceback (most recent call last): 2012-07-04 09:10:19 TRACE nova.rpc.amqp File "/usr/lib/python2.7/dist-packages/nova/rpc/amqp.py", line 252, in _process_data 2012-07-04 09:10:19 TRACE nova.rpc.amqp rval = node_func(context=ctxt, *node_args) 2012-07-04 09:10:19 TRACE nova.rpc.amqp File "/usr/lib/python2.7/dist-packages/nova/exception.py", line 114, in wrapped 2012-07-04 09:10:19 TRACE nova.rpc.amqp return f(args, *kw) 2012-07-04 09:10:19 TRACE nova.rpc.amqp File "/usr/lib/python2.7/dist-packages/nova/compute/manager.py", line 177, in decorated_function 2012-07-04 09:10:19 TRACE nova.rpc.amqp sys.exc_info()) 2012-07-04 09:10:19 TRACE nova.rpc.amqp File "/usr/lib/python2.7/contextlib.py", line 24, in __exit__ 2012-07-04 09:10:19 TRACE nova.rpc.amqp self.gen.next() 2012-07-04 09:10:19 TRACE nova.rpc.amqp File "/usr/lib/python2.7/dist-packages/nova/compute/manager.py", line 171, in decorated_function 2012-07-04 09:10:19 TRACE nova.rpc.amqp return function(self, context, instance_uuid, *args, *kwargs) 2012-07-04 09:10:19 TRACE nova.rpc.amqp File "/usr/lib/python2.7/dist-packages/nova/compute/manager.py", line 651, in run_instance 2012-07-04 09:10:19 TRACE nova.rpc.amqp do_run_instance() 2012-07-04 09:10:19 TRACE nova.rpc.amqp File "/usr/lib/python2.7/dist-packages/nova/utils.py", line 945, in inner 2012-07-04 09:10:19 TRACE nova.rpc.amqp retval = f(args, *kwargs) 2012-07-04 09:10:19 TRACE nova.rpc.amqp File "/usr/lib/python2.7/dist-packages/nova/compute/manager.py", line 650, in do_run_instance 2012-07-04 09:10:19 TRACE nova.rpc.amqp self._run_instance(context, instance_uuid, **kwargs) 2012-07-04 09:10:19 TRACE nova.rpc.amqp File "/usr/lib/python2.7/dist-packages/nova/compute/manager.py", line 451, in _run_instance 2012-07-04 09:10:19 TRACE nova.rpc.amqp self._set_instance_error_state(context, instance_uuid) 2012-07-04 09:10:19 TRACE nova.rpc.amqp File "/usr/lib/python2.7/contextlib.py", line 24, in __exit__ 2012-07-04 09:10:19 TRACE nova.rpc.amqp self.gen.next() 2012-07-04 09:10:19 TRACE nova.rpc.amqp File "/usr/lib/python2.7/dist-packages/nova/compute/manager.py", line 432, in _run_instance 2012-07-04 09:10:19 TRACE nova.rpc.amqp self._deallocate_network(context, instance) 2012-07-04 09:10:19 TRACE nova.rpc.amqp File "/usr/lib/python2.7/contextlib.py", line 24, in __exit__ 2012-07-04 09:10:19 TRACE nova.rpc.amqp self.gen.next() 2012-07-04 09:10:19 TRACE nova.rpc.amqp File "/usr/lib/python2.7/dist-packages/nova/compute/manager.py", line 429, in _run_instance 2012-07-04 09:10:19 TRACE nova.rpc.amqp injected_files, admin_password) 2012-07-04 09:10:19 TRACE nova.rpc.amqp File "/usr/lib/python2.7/dist-packages/nova/compute/manager.py", line 592, in _spawn 2012-07-04 09:10:19 TRACE nova.rpc.amqp self._legacy_nw_info(network_info), block_device_info) 2012-07-04 09:10:19 TRACE nova.rpc.amqp File "/usr/lib/python2.7/dist-packages/nova/virt/vmwareapi_conn.py", line 135, in spawn 2012-07-04 09:10:19 TRACE nova.rpc.amqp self._vmops.spawn(context, instance, image_meta, network_info) 2012-07-04 09:10:19 TRACE nova.rpc.amqp File "/usr/lib/python2.7/dist-packages/nova/virt/vmwareapi/vmops.py", line 187, in spawn 2012-07-04 09:10:19 TRACE nova.rpc.amqp vif_infos = _get_vif_infos() 2012-07-04 09:10:19 TRACE nova.rpc.amqp File "/usr/lib/python2.7/dist-packages/nova/virt/vmwareapi/vmops.py", line 180, in _get_vif_infos 2012-07-04 09:10:19 TRACE nova.rpc.amqp network_ref = _check_if_network_bridge_exists(network_name) 2012-07-04 09:10:19 TRACE nova.rpc.amqp File "/usr/lib/python2.7/dist-packages/nova/virt/vmwareapi/vmops.py", line 168, in _check_if_network_bridge_exists 2012-07-04 09:10:19 TRACE nova.rpc.amqp raise exception.NetworkNotFoundForBridge(bridge=network_name) 2012-07-04 09:10:19 TRACE nova.rpc.amqp NetworkNotFoundForBridge: Network could not be found for bridge br100 2012-07-04 09:10:19 TRACE nova.rpc.amqp