Open Stack

The first error message of note is "StorageRepositoryNotFound: Cannot find SR to read/write VDI."

This is looking for the "Local storage" SR to put the VDI into.  We use an other-config key i18n-key= local-storage to identify this, so I expect that your SR is missing that.  This is the standard configuration when set up on XenServer (and I thought, Xen Cloud Platform too) but if you've configured this in some other way, then that would explain it.

The second error is "UnboundLocalError: local variable 'instance_obj' referenced before assignment", which looks like a bug on the error reporting path.  Please report this on Launchpad.

Thanks,

Ewan.

> ---------- Forwarded message ----------
> From: Leandro Reox <leandro.reox at gmail.com>
> Date: Thu, May 12, 2011 at 10:28 AM
> Subject: [Openstack-operators] nova-compute and xen SR - error trace
> To: openstack-operators at lists.openstack.org
> 
> 
> I manage to get everything working, but it seems that nova is not
> locating the xen SR, when i start an instance it keeps on SHUTDOWN
> state, trace below :
> 
> 2011-05-12 09:53:52,814 DEBUG nova.rpc [-] received
> {u'_context_request_id': u'ZCACXBRLE67UDK7ZOC4R',
> u'_context_read_deleted': False, u'args': {u'instance_id': 12,
> u'injected_files': None, u'availability_zone': None},
> u'_context_is_admin': True, u'_context_timestamp':
> u'2011-05-12T13:53:43Z', u'_context_user': u'admin', u'method':
> u'run_instance', u'_context_project': u'melicloud',
> u'_context_remote_address': u'172.16.133.241'} from (pid=4250)
> _receive /usr/lib/pymodules/python2.6/nova/rpc.py:177
> 2011-05-12 09:53:52,814 DEBUG nova.rpc [-] unpacked context:
> {'timestamp': u'2011-05-12T13:53:43Z', 'remote_address':
> u'172.16.133.241', 'project': u'melicloud', 'is_admin': True, 'user':
> u'admin', 'request_id': u'ZCACXBRLE67UDK7ZOC4R', 'read_deleted':
> False} from (pid=4250) _unpack_context
> /usr/lib/pymodules/python2.6/nova/rpc.py:350
> 2011-05-12 09:53:55,530 AUDIT nova.compute.manager
> [ZCACXBRLE67UDK7ZOC4R admin melicloud] instance 12: starting...
> 2011-05-12 09:53:55,660 DEBUG nova.rpc [-] Making asynchronous call on
> network.novacontroller ... from (pid=4250) call
> /usr/lib/pymodules/python2.6/nova/rpc.py:370
> 2011-05-12 09:53:55,661 DEBUG nova.rpc [-] MSG_ID is
> c03c04ff49f04d8d9d80b9f5e55d161c from (pid=4250) call
> /usr/lib/pymodules/python2.6/nova/rpc.py:373
> 2011-05-12 09:53:56,191 DEBUG nova.virt.xenapi.vm_utils [-] Detected
> DISK_RAW format for image 4, instance 12 from (pid=4250)
> log_disk_format
> /usr/lib/pymodules/python2.6/nova/virt/xenapi/vm_utils.py:494
> 2011-05-12 09:53:56,804 ERROR nova.compute.manager
> [ZCACXBRLE67UDK7ZOC4R admin melicloud] Instance '12' failed to spawn.
> Is virtualization enabled in the BIOS?
> (nova.compute.manager): TRACE: Traceback (most recent call last):
> (nova.compute.manager): TRACE:   File
> "/usr/lib/pymodules/python2.6/nova/compute/manager.py", line 240, in
> run_instance
> (nova.compute.manager): TRACE:     self.driver.spawn(instance_ref)
> (nova.compute.manager): TRACE:   File
> "/usr/lib/pymodules/python2.6/nova/virt/xenapi_conn.py", line 188, in
> spawn
> (nova.compute.manager): TRACE:     self._vmops.spawn(instance)
> (nova.compute.manager): TRACE:   File
> "/usr/lib/pymodules/python2.6/nova/virt/xenapi/vmops.py", line 117, in
> spawn
> (nova.compute.manager): TRACE:     vdi_uuid =
> self._create_disk(instance)
> (nova.compute.manager): TRACE:   File
> "/usr/lib/pymodules/python2.6/nova/virt/xenapi/vmops.py", line 113, in
> _create_disk
> (nova.compute.manager): TRACE:     instance.image_id, user, project,
> disk_image_type)
> (nova.compute.manager): TRACE:   File
> "/usr/lib/pymodules/python2.6/nova/virt/xenapi/vm_utils.py", line 382,
> in fetch_image
> (nova.compute.manager): TRACE:     access, image_type)
> (nova.compute.manager): TRACE:   File
> "/usr/lib/pymodules/python2.6/nova/virt/xenapi/vm_utils.py", line 535,
> in _fetch_image_glance
> (nova.compute.manager): TRACE:     session, instance_id, image,
> access, image_type)
> (nova.compute.manager): TRACE:   File
> "/usr/lib/pymodules/python2.6/nova/virt/xenapi/vm_utils.py", line 436,
> in _fetch_image_glance_disk
> (nova.compute.manager): TRACE:     sr_ref = safe_find_sr(session)
> (nova.compute.manager): TRACE:   File
> "/usr/lib/pymodules/python2.6/nova/virt/xenapi/vm_utils.py", line 853,
> in safe_find_sr
> (nova.compute.manager): TRACE:     raise
> exception.StorageRepositoryNotFound()
> (nova.compute.manager): TRACE: StorageRepositoryNotFound: Cannot find
> SR to read/write VDI.
> (nova.compute.manager): TRACE:
> 2011-05-12 09:53:57,076 ERROR nova.exception [-] Uncaught exception
> (nova.exception): TRACE: Traceback (most recent call last):
> (nova.exception): TRACE:   File
> "/usr/lib/pymodules/python2.6/nova/exception.py", line 79, in _wrap
> (nova.exception): TRACE:     return f(*args, **kw)
> (nova.exception): TRACE:   File
> "/usr/lib/pymodules/python2.6/nova/compute/manager.py", line 265, in
> run_instance
> (nova.exception): TRACE:     self._update_state(context, instance_id)
> (nova.exception): TRACE:   File
> "/usr/lib/pymodules/python2.6/nova/compute/manager.py", line 145, in
> _update_state
> (nova.exception): TRACE:     info =
> self.driver.get_info(instance_ref['name'])
> (nova.exception): TRACE:   File
> "/usr/lib/pymodules/python2.6/nova/virt/xenapi_conn.py", line 263, in
> get_info
> (nova.exception): TRACE:     return self._vmops.get_info(instance_id)
> (nova.exception): TRACE:   File
> "/usr/lib/pymodules/python2.6/nova/virt/xenapi/vmops.py", line 771, in
> get_info
> (nova.exception): TRACE:     vm_ref = self._get_vm_opaque_ref(instance)
> (nova.exception): TRACE:   File
> "/usr/lib/pymodules/python2.6/nova/virt/xenapi/vmops.py", line 262, in
> _get_vm_opaque_ref
> (nova.exception): TRACE:     raise
> exception.InstanceNotFound(instance_id=instance_obj.id)
> (nova.exception): TRACE: UnboundLocalError: local variable
> 'instance_obj' referenced before assignment
> (nova.exception): TRACE:
> 2011-05-12 09:53:57,111 ERROR nova [-] Exception during message
> handling
> (nova): TRACE: Traceback (most recent call last):
> (nova): TRACE:   File "/usr/lib/pymodules/python2.6/nova/rpc.py", line
> 198, in _receive
> (nova): TRACE:     rval = node_func(context=ctxt, **node_args)
> (nova): TRACE:   File
> "/usr/lib/pymodules/python2.6/nova/exception.py", line 85, in _wrap
> (nova): TRACE:     raise Error(str(e))
> (nova): TRACE: Error: local variable 'instance_obj' referenced before
> assignment
> (nova): TRACE:
> 2011-05-12 09:54:50,664 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenserver vm state -> |Running|
> 2011-05-12 09:54:50,665 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenapi power_state -> |1|
> 2011-05-12 09:54:51,060 INFO nova.compute.manager [-] Found instance
> 'instance-0000000c' in DB but no VM. State=4, so setting state to
> shutoff.
> 2011-05-12 09:54:51,060 INFO nova.compute.manager [-] DB/VM state
> mismatch. Changing state from '4' to '5'
> 2011-05-12 09:55:53,429 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenserver vm state -> |Running|
> 2011-05-12 09:55:53,429 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenapi power_state -> |1|
> 2011-05-12 09:55:53,802 INFO nova.compute.manager [-] Found instance
> 'instance-0000000c' in DB but no VM. State=5, so setting state to
> shutoff.
> 2011-05-12 09:56:56,132 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenserver vm state -> |Running|
> 2011-05-12 09:56:56,132 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenapi power_state -> |1|
> 2011-05-12 09:56:56,503 INFO nova.compute.manager [-] Found instance
> 'instance-0000000c' in DB but no VM. State=5, so setting state to
> shutoff.
> 2011-05-12 09:57:58,846 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenserver vm state -> |Running|
> 2011-05-12 09:57:58,846 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenapi power_state -> |1|
> 2011-05-12 09:57:59,216 INFO nova.compute.manager [-] Found instance
> 'instance-0000000c' in DB but no VM. State=5, so setting state to
> shutoff.
> 2011-05-12 09:59:01,539 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenserver vm state -> |Running|
> 2011-05-12 09:59:01,539 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenapi power_state -> |1|
> 2011-05-12 09:59:01,911 INFO nova.compute.manager [-] Found instance
> 'instance-0000000c' in DB but no VM. State=5, so setting state to
> shutoff.
> 2011-05-12 10:00:04,233 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenserver vm state -> |Running|
> 2011-05-12 10:00:04,233 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenapi power_state -> |1|
> 2011-05-12 10:00:04,605 INFO nova.compute.manager [-] Found instance
> 'instance-0000000c' in DB but no VM. State=5, so setting state to
> shutoff.
> 2011-05-12 10:01:06,936 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenserver vm state -> |Running|
> 2011-05-12 10:01:06,936 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenapi power_state -> |1|
> 2011-05-12 10:01:07,308 INFO nova.compute.manager [-] Found instance
> 'instance-0000000c' in DB but no VM. State=5, so setting state to
> shutoff.
> 2011-05-12 10:02:09,650 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenserver vm state -> |Running|
> 2011-05-12 10:02:09,651 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenapi power_state -> |1|
> 2011-05-12 10:02:10,022 INFO nova.compute.manager [-] Found instance
> 'instance-0000000c' in DB but no VM. State=5, so setting state to
> shutoff.
> 2011-05-12 10:03:12,344 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenserver vm state -> |Running|
> 2011-05-12 10:03:12,344 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenapi power_state -> |1|
> 2011-05-12 10:03:12,715 INFO nova.compute.manager [-] Found instance
> 'instance-0000000c' in DB but no VM. State=5, so setting state to
> shutoff.
> 2011-05-12 10:04:15,031 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenserver vm state -> |Running|
> 2011-05-12 10:04:15,031 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenapi power_state -> |1|
> 2011-05-12 10:04:15,410 INFO nova.compute.manager [-] Found instance
> 'instance-0000000c' in DB but no VM. State=5, so setting state to
> shutoff.
> 2011-05-12 10:05:17,731 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenserver vm state -> |Running|
> 2011-05-12 10:05:17,731 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS)
> xenapi power_state -> |1|
> 2011-05-12 10:05:18,138 INFO nova.compute.manager [-] Found instance
> 'instance-0000000c' in DB but no VM. State=5, so setting state to
> shutoff.
> 
> _______________________________________________
> Openstack-operators mailing list
> Openstack-operators at lists.openstack.org
> http://lists.openstack.org/cgi-bin/mailman/listinfo/openstack-operators
> 
> 
> 
> 
> --
> Todd Deshane
> http://www.linkedin.com/in/deshantm
> http://www.xen.org/products/cloudxen.html
> http://runningxen.com/