Running rev 1105, getting an internal server error on instance create:
2011-05-25 15:38:23,497 DEBUG nova.rpc [-] received {'_context_request_id': 'QGWA2YLH38NG5N9VJ9XS', '_context_read_deleted': False, 'args': {'instance_id': 565, 'instance_type': {'rxtx_quota': 0, 'deleted_at': None, 'name': 'm1.tiny', 'deleted': False, 'created_at': None, 'updated_at': None, 'memory_mb': 512, 'vcpus': 1, 'rxtx_cap': 0, 'swap': 0, 'flavorid': 1, 'id': 2, 'local_gb': 0}, 'injected_files': [], 'availability_zone': None}, '_context_is_admin': True, '_context_timestamp': '2011-05-25T15:38:22Z', '_context_user': 'admin', 'method': 'run_instance', '_context_project': 'openstack', '_context_remote_address': None} from (pid=30345) _receive /usr/lib/pymodules/python2.6/nova/rpc.py:177
2011-05-25 15:38:23,497 DEBUG nova.rpc [-] unpacked context: {'timestamp': '2011-05-25T15:38:22Z', 'remote_address': None, 'project': 'openstack', 'is_admin': True, 'user': 'admin', 'request_id': 'QGWA2YLH38NG5N9VJ9XS', 'read_deleted': False} from (pid=30345) _unpack_context /usr/lib/pymodules/python2.6/nova/rpc.py:350
2011-05-25 15:38:26,098 AUDIT nova.compute.manager [QGWA2YLH38NG5N9VJ9XS admin openstack] instance 565: starting...
2011-05-25 15:38:26,213 DEBUG nova.rpc [-] Making asynchronous call on network.z1-api1 ... from (pid=30345) call /usr/lib/pymodules/python2.6/nova/rpc.py:370
2011-05-25 15:38:26,213 DEBUG nova.rpc [-] MSG_ID is 2c3439247b934603975e7fbe43aaf8e2 from (pid=30345) call /usr/lib/pymodules/python2.6/nova/rpc.py:373
2011-05-25 15:38:26,697 DEBUG nova.rpc [-] received {'_context_request_id': 'QGWA2YLH38NG5N9VJ9XS', '_context_read_deleted': False, 'args': {'instance_id': 565, 'new_pass': 'EqTxRNPXtXf9MdvH'}, '_context_is_admin': True, '_context_timestamp': '2011-05-25T15:38:22Z', '_context_user': 'admin', 'method': 'set_admin_password', '_context_project': 'openstack', '_context_remote_address': None} from (pid=30345) _receive /usr/lib/pymodules/python2.6/nova/rpc.py:177
2011-05-25 15:38:26,698 DEBUG nova.rpc [-] unpacked context: {'timestamp': '2011-05-25T15:38:22Z', 'remote_address': None, 'project': 'openstack', 'is_admin': True, 'user': 'admin', 'request_id': 'QGWA2YLH38NG5N9VJ9XS', 'read_deleted': False} from (pid=30345) _unpack_context /usr/lib/pymodules/python2.6/nova/rpc.py:350
2011-05-25 15:38:26,698 INFO nova.compute.manager [QGWA2YLH38NG5N9VJ9XS admin openstack] check_instance_lock: decorating: |<function set_admin_password at 0x2c21758>|
2011-05-25 15:38:26,699 INFO nova.compute.manager [QGWA2YLH38NG5N9VJ9XS admin openstack] check_instance_lock: arguments: |<nova.compute.manager.ComputeManager object at 0x2b20050>| |<nova.context.RequestContext object at 0x3e99410>| |565|
2011-05-25 15:38:26,699 DEBUG nova.compute.manager [QGWA2YLH38NG5N9VJ9XS admin openstack] instance 565: getting locked state from (pid=30345) get_lock /usr/lib/pymodules/python2.6/nova/compute/manager.py:741
2011-05-25 15:38:26,750 INFO nova.compute.manager [QGWA2YLH38NG5N9VJ9XS admin openstack] check_instance_lock: locked: |False|
2011-05-25 15:38:26,750 INFO nova.compute.manager [QGWA2YLH38NG5N9VJ9XS admin openstack] check_instance_lock: admin: |True|
2011-05-25 15:38:26,750 INFO nova.compute.manager [QGWA2YLH38NG5N9VJ9XS admin openstack] check_instance_lock: executing: |<function set_admin_password at 0x2c21758>|
2011-05-25 15:38:26,806 ERROR nova.compute.manager [-] Instance '565' failed to spawn. Is virtualization enabled in the BIOS? Details: Internal Server error:
(nova.compute.manager): TRACE: Traceback (most recent call last):
(nova.compute.manager): TRACE: File "/usr/lib/pymodules/python2.6/nova/compute/manager.py", line 256, 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 199, 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 111, in _create_disk
(nova.compute.manager): TRACE: disk_image_type = VMHelper.determine_disk_image_type(instance)
(nova.compute.manager): TRACE: File "/usr/lib/pymodules/python2.6/nova/virt/xenapi/vm_utils.py", line 528, in determine_disk_image_type
(nova.compute.manager): TRACE: image_type = determine_from_glance()
(nova.compute.manager): TRACE: File "/usr/lib/pymodules/python2.6/nova/virt/xenapi/vm_utils.py", line 512, in determine_from_glance
(nova.compute.manager): TRACE: meta = client.get_image_meta(instance.image_id)
(nova.compute.manager): TRACE: File "/usr/local/lib/python2.6/dist-packages/glance/client.py", line 235, in get_image_meta
(nova.compute.manager): TRACE: res = self.do_request("HEAD", "/images/%s" % image_id)
(nova.compute.manager): TRACE: File "/usr/local/lib/python2.6/dist-packages/glance/client.py", line 161, in do_request
(nova.compute.manager): TRACE: raise Exception("Internal Server error: %s" % res.read())
(nova.compute.manager): TRACE: Exception: Internal Server error:
(nova.compute.manager): TRACE:
2011-05-25 15:38:52,875 DEBUG nova.manager [-] Notifying Schedulers of capabilities ... from (pid=30345) periodic_tasks /usr/lib/pymodules/python2.6/nova/manager.py:111
2011-05-25 15:38:52,875 DEBUG nova.rpc [-] Making asynchronous fanout cast... from (pid=30345) fanout_cast /usr/lib/pymodules/python2.6/nova/rpc.py:421
2011-05-25 15:38:52,875 INFO nova.rpc [-] Creating "scheduler_fanout" fanout exchange
2011-05-25 15:38:55,228 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS) xenserver vm state -> |Running|
2011-05-25 15:38:55,229 INFO nova.virt.xenapi.vm_utils [-] (VM_UTILS) xenapi power_state -> |1|
2011-05-25 15:38:55,503 INFO nova.compute.manager [-] Found instance 'instance-00000235' in DB but no VM. State=8, so setting state to shutoff.
2011-05-25 15:38:55,503 INFO nova.compute.manager [-] DB/VM state mismatch. Changing state from '8' to '5'
Related to running an old version of glance.