Here is the SMlog content, and after im attaching the complete Python stack trace of the failing operation : <br><br><div>[21522] 2011-05-02 11:05:23.165168<span class="Apple-tab-span" style="white-space:pre"> </span>['uuidgen', '-r']</div>
<div>[21522] 2011-05-02 11:05:23.173426<span class="Apple-tab-span" style="white-space:pre"> </span>SUCCESS</div><div>[21522] 2011-05-02 11:05:23.181877<span class="Apple-tab-span" style="white-space:pre"> </span>lock: acquired /var/lock/sm/f6c3ee92-1ee8-9250-6288-bfd82b18eaa2/sr</div>
<div>[21522] 2011-05-02 11:05:23.191170<span class="Apple-tab-span" style="white-space:pre"> </span>vdi_create {'sr_uuid': 'f6c3ee92-1ee8-9250-6288-bfd82b18eaa2', 'subtask_of': 'OpaqueRef:e0c12109-a670-7467-7b30-d7fa98c266e4', 'args': ['5368709120', ''], 'host_ref': 'OpaqueRef:96666e1c-b5c2-456c-ce42-d5de48c2c72f', 'session_ref': 'OpaqueRef:4684ee37-2dbf-2918-0e8e-604eb2d57756', 'device_config': {'SRmaster': 'true', 'serverpath': '/vol/xcp', 'server': '172.16.129.11'}, 'command': 'vdi_create', 'sr_ref': 'OpaqueRef:625b2d6a-de22-047d-8d1d-4217d301c7fd', 'vdi_sm_config': {'vmhint': '8141c6e2-ba99-4378-4648-12a8c880a74c'}}</div>
<div>[21522] 2011-05-02 11:05:23.191715<span class="Apple-tab-span" style="white-space:pre"> </span>['/usr/sbin/td-util', 'create', 'vhd', '5120', '/var/run/sr-mount/f6c3ee92-1ee8-9250-6288-bfd82b18eaa2/bfc16f29-fcae-4526-a33c-01a4b08e9e12.vhd']</div>
<div>[21522] 2011-05-02 11:05:23.223136<span class="Apple-tab-span" style="white-space:pre"> </span>SUCCESS</div><div>[21522] 2011-05-02 11:05:23.223258<span class="Apple-tab-span" style="white-space:pre"> </span>['/usr/sbin/td-util', 'query', 'vhd', '-v', '/var/run/sr-mount/f6c3ee92-1ee8-9250-6288-bfd82b18eaa2/bfc16f29-fcae-4526-a33c-01a4b08e9e12.vhd']</div>
<div>[21522] 2011-05-02 11:05:23.233492<span class="Apple-tab-span" style="white-space:pre"> </span>SUCCESS</div><div>[21522] 2011-05-02 11:05:23.288800<span class="Apple-tab-span" style="white-space:pre"> </span>lock: released /var/lock/sm/f6c3ee92-1ee8-9250-6288-bfd82b18eaa2/sr</div>
<div>[21522] 2011-05-02 11:05:23.292891<span class="Apple-tab-span" style="white-space:pre"> </span>lock: closed /var/lock/sm/f6c3ee92-1ee8-9250-6288-bfd82b18eaa2/sr</div><div>[21897] 2011-05-02 11:11:31.255700<span class="Apple-tab-span" style="white-space:pre"> </span>['uuidgen', '-r']</div>
<div>[21897] 2011-05-02 11:11:31.263973<span class="Apple-tab-span" style="white-space:pre"> </span>SUCCESS</div><div>[21897] 2011-05-02 11:11:31.272166<span class="Apple-tab-span" style="white-space:pre"> </span>lock: acquired /var/lock/sm/f6c3ee92-1ee8-9250-6288-bfd82b18eaa2/sr</div>
<div>[21897] 2011-05-02 11:11:31.274116<span class="Apple-tab-span" style="white-space:pre"> </span>vdi_create {'sr_uuid': 'f6c3ee92-1ee8-9250-6288-bfd82b18eaa2', 'subtask_of': 'OpaqueRef:2aa5b7f3-2422-7a6a-d60c-f733b5b4e536', 'args': ['8589934592', ''], 'host_ref': 'OpaqueRef:96666e1c-b5c2-456c-ce42-d5de48c2c72f', 'session_ref': 'OpaqueRef:c5f26499-7a8e-084c-ad7f-6f0a182f5dae', 'device_config': {'SRmaster': 'true', 'serverpath': '/vol/xcp', 'server': '172.16.129.11'}, 'command': 'vdi_create', 'sr_ref': 'OpaqueRef:625b2d6a-de22-047d-8d1d-4217d301c7fd', 'vdi_sm_config': {'vmhint': '2a743cb5-9896-f76d-25ad-b779a0f7cee6'}}</div>
<div>[21897] 2011-05-02 11:11:31.275393<span class="Apple-tab-span" style="white-space:pre"> </span>['/usr/sbin/td-util', 'create', 'vhd', '8192', '/var/run/sr-mount/f6c3ee92-1ee8-9250-6288-bfd82b18eaa2/cc22b2ac-519e-4548-bbaf-69d6a1e778ac.vhd']</div>
<div>[21897] 2011-05-02 11:11:31.290521<span class="Apple-tab-span" style="white-space:pre"> </span>SUCCESS</div><div>[21897] 2011-05-02 11:11:31.290643<span class="Apple-tab-span" style="white-space:pre"> </span>['/usr/sbin/td-util', 'query', 'vhd', '-v', '/var/run/sr-mount/f6c3ee92-1ee8-9250-6288-bfd82b18eaa2/cc22b2ac-519e-4548-bbaf-69d6a1e778ac.vhd']</div>
<div>[21897] 2011-05-02 11:11:31.302298<span class="Apple-tab-span" style="white-space:pre"> </span>SUCCESS</div><div>[21897] 2011-05-02 11:11:31.358807<span class="Apple-tab-span" style="white-space:pre"> </span>lock: released /var/lock/sm/f6c3ee92-1ee8-9250-6288-bfd82b18eaa2/sr</div>
<div>[21897] 2011-05-02 11:11:31.362893<span class="Apple-tab-span" style="white-space:pre"> </span>lock: closed /var/lock/sm/f6c3ee92-1ee8-9250-6288-bfd82b18eaa2/sr</div><div><br></div><div><br></div><div>Python stack trace from nova-compute : <br>
</div><div><br></div><div><br></div><div><div>2011-05-04 16:34:41,333 DEBUG nova.rpc [-] received {u'_context_request_id': u'C-FY1X8L8OPHHY4TPZE6', u'_context_read_deleted': False, u'args': {u'instance_id': 8, u'injected_files': None, u'availability_zone': None}, u'_context_is_admin': True, u'_context_timestamp': u'2011-05-04T20:34:36Z', 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=3796) _receive /usr/lib/pymodules/python2.6/nova/rpc.py:177</div>
<div>2011-05-04 16:34:41,334 DEBUG nova.rpc [-] unpacked context: {'timestamp': u'2011-05-04T20:34:36Z', 'remote_address': u'172.16.133.241', 'project': u'melicloud', 'is_admin': True, 'user': u'admin', 'request_id': u'C-FY1X8L8OPHHY4TPZE6', 'read_deleted': False} from (pid=3796) _unpack_context /usr/lib/pymodules/python2.6/nova/rpc.py:350</div>
<div>2011-05-04 16:34:44,182 AUDIT nova.compute.manager [C-FY1X8L8OPHHY4TPZE6 admin melicloud] instance 8: starting...</div><div>2011-05-04 16:34:44,411 DEBUG nova.rpc [-] Making asynchronous call on network.novacontroller ... from (pid=3796) call /usr/lib/pymodules/python2.6/nova/rpc.py:370</div>
<div>2011-05-04 16:34:44,411 DEBUG nova.rpc [-] MSG_ID is f11a7286824542449e0f9c9a790c418d from (pid=3796) call /usr/lib/pymodules/python2.6/nova/rpc.py:373</div><div>2011-05-04 16:34:44,973 DEBUG nova.virt.xenapi.vm_utils [-] Detected DISK_RAW format for image 4, instance 8 from (pid=3796) log_disk_format /usr/lib/pymodules/python2.6/nova/virt/xenapi/vm_utils.py:494</div>
<div>2011-05-04 16:34:45,566 ERROR nova.compute.manager [C-FY1X8L8OPHHY4TPZE6 admin melicloud] Instance '8' failed to spawn. Is virtualization enabled in the BIOS?</div><div>(nova.compute.manager): TRACE: Traceback (most recent call last):</div>
<div>(nova.compute.manager): TRACE: File "/usr/lib/pymodules/python2.6/nova/compute/manager.py", line 240, in run_instance</div><div>(nova.compute.manager): TRACE: self.driver.spawn(instance_ref)</div><div>
(nova.compute.manager): TRACE: File "/usr/lib/pymodules/python2.6/nova/virt/xenapi_conn.py", line 188, in spawn</div><div>(nova.compute.manager): TRACE: self._vmops.spawn(instance)</div><div>(nova.compute.manager): TRACE: File "/usr/lib/pymodules/python2.6/nova/virt/xenapi/vmops.py", line 117, in spawn</div>
<div>(nova.compute.manager): TRACE: vdi_uuid = self._create_disk(instance)</div><div>(nova.compute.manager): TRACE: File "/usr/lib/pymodules/python2.6/nova/virt/xenapi/vmops.py", line 113, in _create_disk</div>
<div>(nova.compute.manager): TRACE: instance.image_id, user, project, disk_image_type)</div><div>(nova.compute.manager): TRACE: File "/usr/lib/pymodules/python2.6/nova/virt/xenapi/vm_utils.py", line 382, in fetch_image</div>
<div>(nova.compute.manager): TRACE: access, image_type)</div><div>(nova.compute.manager): TRACE: File "/usr/lib/pymodules/python2.6/nova/virt/xenapi/vm_utils.py", line 535, in _fetch_image_glance</div><div>
(nova.compute.manager): TRACE: session, instance_id, image, access, image_type)</div><div>(nova.compute.manager): TRACE: File "/usr/lib/pymodules/python2.6/nova/virt/xenapi/vm_utils.py", line 436, in _fetch_image_glance_disk</div>
<div>(nova.compute.manager): TRACE: sr_ref = safe_find_sr(session)</div><div>(nova.compute.manager): TRACE: File "/usr/lib/pymodules/python2.6/nova/virt/xenapi/vm_utils.py", line 853, in safe_find_sr</div>
<div>(nova.compute.manager): TRACE: raise exception.StorageRepositoryNotFound()</div><div>(nova.compute.manager): TRACE: StorageRepositoryNotFound: Cannot find SR to read/write VDI.</div><div>(nova.compute.manager): TRACE: </div>
<div>2011-05-04 16:34:45,851 ERROR nova.exception [-] Uncaught exception</div><div>(nova.exception): TRACE: Traceback (most recent call last):</div><div>(nova.exception): TRACE: File "/usr/lib/pymodules/python2.6/nova/exception.py", line 79, in _wrap</div>
<div>(nova.exception): TRACE: return f(*args, **kw)</div><div>(nova.exception): TRACE: File "/usr/lib/pymodules/python2.6/nova/compute/manager.py", line 265, in run_instance</div><div>(nova.exception): TRACE: self._update_state(context, instance_id)</div>
<div>(nova.exception): TRACE: File "/usr/lib/pymodules/python2.6/nova/compute/manager.py", line 145, in _update_state</div><div>(nova.exception): TRACE: info = self.driver.get_info(instance_ref['name'])</div>
<div>(nova.exception): TRACE: File "/usr/lib/pymodules/python2.6/nova/virt/xenapi_conn.py", line 263, in get_info</div><div>(nova.exception): TRACE: return self._vmops.get_info(instance_id)</div><div>(nova.exception): TRACE: File "/usr/lib/pymodules/python2.6/nova/virt/xenapi/vmops.py", line 771, in get_info</div>
<div>(nova.exception): TRACE: vm_ref = self._get_vm_opaque_ref(instance)</div><div>(nova.exception): TRACE: File "/usr/lib/pymodules/python2.6/nova/virt/xenapi/vmops.py", line 262, in _get_vm_opaque_ref</div>
<div>(nova.exception): TRACE: raise exception.InstanceNotFound(instance_id=<a href="http://instance_obj.id">instance_obj.id</a>)</div><div>(nova.exception): TRACE: UnboundLocalError: local variable 'instance_obj' referenced before assignment</div>
<div>(nova.exception): TRACE: </div><div>2011-05-04 16:34:45,852 ERROR nova [-] Exception during message handling</div><div>(nova): TRACE: Traceback (most recent call last):</div><div>(nova): TRACE: File "/usr/lib/pymodules/python2.6/nova/rpc.py", line 198, in _receive</div>
<div>(nova): TRACE: rval = node_func(context=ctxt, **node_args)</div><div>(nova): TRACE: File "/usr/lib/pymodules/python2.6/nova/exception.py", line 85, in _wrap</div><div>(nova): TRACE: raise Error(str(e))</div>
<div>(nova): TRACE: Error: local variable 'instance_obj' referenced before assignment</div><div>(nova): TRACE: </div><div>2011-05-04 16:35:26,348 INFO nova.compute.manager [-] Found instance 'instance-00000008' in DB but no VM. State=4, so setting state to shutoff.</div>
<div>2011-05-04 16:35:26,348 INFO nova.compute.manager [-] DB/VM state mismatch. Changing state from '4' to '5'</div></div><div><br></div><div><br></div><div>Best regards !</div><br><div class="gmail_quote">
On Wed, May 4, 2011 at 5:57 PM, Todd Deshane <span dir="ltr"><<a href="mailto:todd.deshane@xen.org">todd.deshane@xen.org</a>></span> wrote:<br><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex;">
<div><div></div><div class="h5">On Wed, May 4, 2011 at 4:47 PM, Leandro Reox <<a href="mailto:leandro.reox@gmail.com">leandro.reox@gmail.com</a>> wrote:<br>
> List, i almost made it, but when i launch an instance, in the logs i see<br>
> "StorageRepositoryNotFound: Cannot find SR to read/write VDI" this message<br>
> is from the xenapi. (I have xcp as hypervisor).<br>
> I got an SR created via NFS, thats showns in the xe sr-list<br>
> Anybody has a clue of why is failing ?<br>
><br>
</div></div>On your XCP server, what is the contents of:<br>
/var/log/SMlog<br>
<br>
Maybe we can find a problem there.<br>
<br>
Thanks,<br>
Todd<br>
<br>
> Best Regards<br>
> Lele<br>
> _______________________________________________<br>
> Openstack-operators mailing list<br>
> <a href="mailto:Openstack-operators@lists.openstack.org">Openstack-operators@lists.openstack.org</a><br>
> <a href="http://lists.openstack.org/cgi-bin/mailman/listinfo/openstack-operators" target="_blank">http://lists.openstack.org/cgi-bin/mailman/listinfo/openstack-operators</a><br>
><br>
><br>
<font color="#888888"><br>
<br>
<br>
--<br>
Todd Deshane<br>
<a href="http://www.linkedin.com/in/deshantm" target="_blank">http://www.linkedin.com/in/deshantm</a><br>
<a href="http://www.xen.org/products/cloudxen.html" target="_blank">http://www.xen.org/products/cloudxen.html</a><br>
<a href="http://runningxen.com/" target="_blank">http://runningxen.com/</a><br>
</font></blockquote></div><br>