Creating volume from snapshot failed

Bug #1007436 reported by Jaroslav Pulchart
6
This bug affects 1 person
Affects Status Importance Assigned to Milestone
OpenStack Compute (nova)
New
Undecided
Unassigned

Bug Description

Creating volume from snapschot failed with this scheduler error:

--------------------------------------
$ euca-create-volume -z .... --snapshot snap-00000003
2012-06-01 15:45:06 DEBUG nova.rpc.amqp [-] received {u'_context_roles': [u'admin'], u'_context_request_id': u'....', u'_context_read_deleted': u'no', u'args': {u'topic': u'volume', u'snapshot_id': 3, u'volume_id': 224}, u'_context_auth_token': '<SANITIZED>', u'_context_is_admin': True, u'_context_project_id': u'....', u'_context_timestamp': u'2012-06-01T13:45:06.242900', u'_context_user_id': u'...', u'method': u'create_volume', u'_context_remote_address': u'......'} from (pid=4784) _safe_log /uasr/lib/python2.6/site-packages/nova/rpc/common.py:160
2012-06-01 15:45:06 DEBUG nova.rpc.amqp [... ... ...] unpacked context: {'user_id': u'....', 'roles': [u'admin'], 'timestamp': '2012-06-01T13:45:06.242900', 'auth_token': '<SANITIZED>', 'remote_address': u'...', 'is_admin': True, 'request_id': u'...', 'project_id': u'...', 'read_deleted': u'no'} from (pid=4784) _safe_log /usr/lib/python2.6/site-packages/nova/rpc/common.py:160
2012-06-01 15:45:06 WARNING nova.scheduler.manager [... ... ...] Failed to schedule_create_volume: expected string or Unicode object, long found
2012-06-01 15:45:06 ERROR nova.rpc.amqp [... ... ...] Exception during message handling
2012-06-01 15:45:06 TRACE nova.rpc.amqp Traceback (most recent call last):
2012-06-01 15:45:06 TRACE nova.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/rpc/amqp.py", line 253, in _process_data
2012-06-01 15:45:06 TRACE nova.rpc.amqp rval = node_func(context=ctxt, **node_args)
2012-06-01 15:45:06 TRACE nova.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/scheduler/manager.py", line 97, in _schedule
2012-06-01 15:45:06 TRACE nova.rpc.amqp context, ex, *args, **kwargs)
2012-06-01 15:45:06 TRACE nova.rpc.amqp File "/usr/lib64/python2.6/contextlib.py", line 23, in __exit__
2012-06-01 15:45:06 TRACE nova.rpc.amqp self.gen.next()
2012-06-01 15:45:06 TRACE nova.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/scheduler/manager.py", line 92, in _schedule
2012-06-01 15:45:06 TRACE nova.rpc.amqp return driver_method(*args, **kwargs)
2012-06-01 15:45:06 TRACE nova.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/scheduler/simple.py", line 116, in schedule_create_volume
2012-06-01 15:45:06 TRACE nova.rpc.amqp volume_ref = db.volume_get(context, volume_id)
2012-06-01 15:45:06 TRACE nova.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/db/api.py", line 948, in volume_get
2012-06-01 15:45:06 TRACE nova.rpc.amqp return IMPL.volume_get(context, volume_id)
2012-06-01 15:45:06 TRACE nova.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/db/sqlalchemy/api.py", line 120, in wrapper
2012-06-01 15:45:06 TRACE nova.rpc.amqp return f(*args, **kwargs)
2012-06-01 15:45:06 TRACE nova.rpc.amqp File "/usr/lib/python2.6/site-packages/nova/db/sqlalchemy/api.py", line 2399, in volume_get
2012-06-01 15:45:06 TRACE nova.rpc.amqp filter_by(id=volume_id).\
2012-06-01 15:45:06 TRACE nova.rpc.amqp File "/usr/lib64/python2.6/site-packages/SQLAlchemy-0.7.3-py2.6-linux-x86_64.egg/sqlalchemy/orm/query.py", line 1963, in first
2012-06-01 15:45:06 TRACE nova.rpc.amqp ret = list(self[0:1])
2012-06-01 15:45:06 TRACE nova.rpc.amqp File "/usr/lib64/python2.6/site-packages/SQLAlchemy-0.7.3-py2.6-linux-x86_64.egg/sqlalchemy/orm/query.py", line 1857, in __getitem__
2012-06-01 15:45:06 TRACE nova.rpc.amqp return list(res)
2012-06-01 15:45:06 TRACE nova.rpc.amqp File "/usr/lib64/python2.6/site-packages/SQLAlchemy-0.7.3-py2.6-linux-x86_64.egg/sqlalchemy/orm/query.py", line 2153, in instances
2012-06-01 15:45:06 TRACE nova.rpc.amqp rows = [process[0](row, None) for row in fetch]
2012-06-01 15:45:06 TRACE nova.rpc.amqp File "/usr/lib64/python2.6/site-packages/SQLAlchemy-0.7.3-py2.6-linux-x86_64.egg/sqlalchemy/orm/mapper.py", line 2647, in _instance
2012-06-01 15:45:06 TRACE nova.rpc.amqp populate_state(state, dict_, row, isnew, only_load_props)
2012-06-01 15:45:06 TRACE nova.rpc.amqp File "/usr/lib64/python2.6/site-packages/SQLAlchemy-0.7.3-py2.6-linux-x86_64.egg/sqlalchemy/orm/mapper.py", line 2501, in populate_state
2012-06-01 15:45:06 TRACE nova.rpc.amqp populator(state, dict_, row)
2012-06-01 15:45:06 TRACE nova.rpc.amqp File "/usr/lib64/python2.6/site-packages/SQLAlchemy-0.7.3-py2.6-linux-x86_64.egg/sqlalchemy/orm/strategies.py", line 150, in new_execute
2012-06-01 15:45:06 TRACE nova.rpc.amqp dict_[key] = row[col]
2012-06-01 15:45:06 TRACE nova.rpc.amqp TypeError: expected string or Unicode object, long found
2012-06-01 15:45:06 TRACE nova.rpc.amqp
--------------------------------------

After this volume list is inaccesible:
--------------------------------------
$ euca-describe-volumes
UnknownError: An unknown error has occurred. Please try your request again.
--------------------------------------

Until DB is not cleared manualy !:
---------------------------------------
$ mysql nova -e "select id, status, availability_zone, attach_status from volumes where snapshot_id is not NULL;"
+-----+----------+-------------------+---------------+
| id | status | availability_zone | attach_status |
+-----+----------+-------------------+---------------+
| 225 | creating | ................... | detached |
+-----+----------+-------------------+---------------+
$ mysql nova -e "delete from volumes where id='225';"
---------------------------------------

The "volume_scheduler_driver" is "nova.scheduler.simple.SimpleScheduler".

Revision history for this message
Jaroslav Pulchart (jaroslav-pulchart-4) wrote :

Same error for "volume_scheduler_driver=nova.scheduler.chance.ChanceScheduler"

Revision history for this message
Jaroslav Pulchart (jaroslav-pulchart-4) wrote :

Seems to be duplicit to 962615

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.