Internal Server Errror from Compute on Create

Bug #788211 reported by Antony Messerli
6
This bug affects 1 person
Affects Status Importance Assigned to Milestone
OpenStack Compute (nova)
Invalid
Undecided
Unassigned

Bug Description

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'

Revision history for this message
Antony Messerli (antonym) wrote :

Related to running an old version of glance.

Changed in nova:
status: New → Invalid
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.