Attachment 899474 Details for Bug 1101504 – cinder volume

cinder volume

volume.log (text/x-log), 11.89 KB, created by bkopilov on 2014-05-27 11:18:32 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: bkopilov

Created: 2014-05-27 11:18:32 UTC

Size: 11.89 KB

patch

obsolete

>2014-05-27 14:08:22.779 4082 WARNING cinder.context [-] Arguments dropped when creating context: {'user': u'eeb749506d324861865debcfd44be297', 'tenant': u'186b603b6cd04627b9aa42eb9e79f5e2', 'user_identity': u'eeb749506d324861865debcfd44be297 186b603b6cd04627b9aa42eb9e79f5e2 - - -'}
>2014-05-27 14:08:22.822 4082 INFO cinder.volume.flows.manager.create_volume [req-c81c83a9-080f-4804-aaf5-6bc8d3f42a2b eeb749506d324861865debcfd44be297 186b603b6cd04627b9aa42eb9e79f5e2 - - -] Volume 808c94f2-4eec-472a-8d4f-2f70de58680e: being created using CreateVolumeFromSpecTask._create_raw_volume with specification: {'status': u'creating', 'volume_size': 1, 'volume_name': u'volume-808c94f2-4eec-472a-8d4f-2f70de58680e'}
>2014-05-27 14:08:23.152 4082 INFO cinder.volume.flows.manager.create_volume [req-c81c83a9-080f-4804-aaf5-6bc8d3f42a2b eeb749506d324861865debcfd44be297 186b603b6cd04627b9aa42eb9e79f5e2 - - -] Volume volume-808c94f2-4eec-472a-8d4f-2f70de58680e (808c94f2-4eec-472a-8d4f-2f70de58680e): created successfully
>2014-05-27 14:08:27.549 4082 INFO cinder.volume.manager [-] Updating volume status
>2014-05-27 14:08:33.739 4082 WARNING cinder.context [-] Arguments dropped when creating context: {'user': u'eeb749506d324861865debcfd44be297', 'tenant': u'186b603b6cd04627b9aa42eb9e79f5e2', 'user_identity': u'eeb749506d324861865debcfd44be297 186b603b6cd04627b9aa42eb9e79f5e2 - - -'}
>2014-05-27 14:08:33.767 4082 INFO cinder.volume.manager [req-2c5dccb9-ba87-48f4-9012-12ca9bdd6b89 eeb749506d324861865debcfd44be297 186b603b6cd04627b9aa42eb9e79f5e2 - - -] snapshot 9ba2dcf9-fc7e-4082-a618-94f1cfb949fc: creating
>2014-05-27 14:08:34.221 4082 INFO cinder.volume.manager [req-2c5dccb9-ba87-48f4-9012-12ca9bdd6b89 eeb749506d324861865debcfd44be297 186b603b6cd04627b9aa42eb9e79f5e2 - - -] snapshot 9ba2dcf9-fc7e-4082-a618-94f1cfb949fc: created successfully
>2014-05-27 14:09:04.331 4082 WARNING cinder.context [-] Arguments dropped when creating context: {'user': u'eeb749506d324861865debcfd44be297', 'tenant': u'186b603b6cd04627b9aa42eb9e79f5e2', 'user_identity': u'eeb749506d324861865debcfd44be297 186b603b6cd04627b9aa42eb9e79f5e2 - - -'}
>2014-05-27 14:09:04.359 4082 INFO cinder.volume.manager [req-4551e229-126a-4791-97a8-d9ee71dbc8a8 eeb749506d324861865debcfd44be297 186b603b6cd04627b9aa42eb9e79f5e2 - - -] snapshot 9ba2dcf9-fc7e-4082-a618-94f1cfb949fc: deleting
>2014-05-27 14:09:04.444 4082 INFO cinder.volume.utils [req-4551e229-126a-4791-97a8-d9ee71dbc8a8 eeb749506d324861865debcfd44be297 186b603b6cd04627b9aa42eb9e79f5e2 - - -] Performing secure delete on volume: /dev/mapper/cinder--volumes-_snapshot--9ba2dcf9--fc7e--4082--a618--94f1cfb949fc-cow
>2014-05-27 14:09:16.182 4082 INFO cinder.volume.manager [req-4551e229-126a-4791-97a8-d9ee71dbc8a8 eeb749506d324861865debcfd44be297 186b603b6cd04627b9aa42eb9e79f5e2 - - -] snapshot 9ba2dcf9-fc7e-4082-a618-94f1cfb949fc: deleted successfully
>2014-05-27 14:09:27.550 4082 INFO cinder.volume.manager [-] Updating volume status
>2014-05-27 14:09:42.099 4082 WARNING cinder.context [-] Arguments dropped when creating context: {'user': u'eeb749506d324861865debcfd44be297', 'tenant': u'186b603b6cd04627b9aa42eb9e79f5e2', 'user_identity': u'eeb749506d324861865debcfd44be297 186b603b6cd04627b9aa42eb9e79f5e2 - - -'}
>2014-05-27 14:09:44.525 4082 WARNING cinder.context [-] Arguments dropped when creating context: {'user': u'eeb749506d324861865debcfd44be297', 'tenant': u'186b603b6cd04627b9aa42eb9e79f5e2', 'user_identity': u'eeb749506d324861865debcfd44be297 186b603b6cd04627b9aa42eb9e79f5e2 - - -'}
>2014-05-27 14:09:44.551 4082 INFO cinder.volume.manager [req-0f70a695-d3f0-4ecf-b9f8-0ad40d5b2ead eeb749506d324861865debcfd44be297 186b603b6cd04627b9aa42eb9e79f5e2 - - -] snapshot af92b885-de85-450f-952b-4fd710b9910b: creating
>2014-05-27 14:09:45.001 4082 INFO cinder.volume.manager [req-0f70a695-d3f0-4ecf-b9f8-0ad40d5b2ead eeb749506d324861865debcfd44be297 186b603b6cd04627b9aa42eb9e79f5e2 - - -] snapshot af92b885-de85-450f-952b-4fd710b9910b: created successfully
>2014-05-27 14:10:17.590 4082 WARNING cinder.context [-] Arguments dropped when creating context: {'user': u'eeb749506d324861865debcfd44be297', 'tenant': u'186b603b6cd04627b9aa42eb9e79f5e2', 'user_identity': u'eeb749506d324861865debcfd44be297 186b603b6cd04627b9aa42eb9e79f5e2 - - -'}
>2014-05-27 14:10:17.622 4082 INFO cinder.volume.manager [req-c07581f7-4a63-467d-84f5-79027d43b084 eeb749506d324861865debcfd44be297 186b603b6cd04627b9aa42eb9e79f5e2 - - -] snapshot af92b885-de85-450f-952b-4fd710b9910b: deleting
>2014-05-27 14:10:17.706 4082 ERROR cinder.volume.drivers.lvm [req-c07581f7-4a63-467d-84f5-79027d43b084 eeb749506d324861865debcfd44be297 186b603b6cd04627b9aa42eb9e79f5e2 - - -] Volume device file path /dev/mapper/cinder--volumes-_snapshot--af92b885--de85--450f--952b--4fd710b9910b-cow does not exist.
>2014-05-27 14:10:17.747 4082 ERROR oslo.messaging.rpc.dispatcher [req-c07581f7-4a63-467d-84f5-79027d43b084 eeb749506d324861865debcfd44be297 186b603b6cd04627b9aa42eb9e79f5e2 - - -] Exception during message handling: Bad or unexpected response from the storage volume backend API: Volume device file path /dev/mapper/cinder--volumes-_snapshot--af92b885--de85--450f--952b--4fd710b9910b-cow does not exist.
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher Traceback (most recent call last):
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher   File "/usr/lib/python2.7/site-packages/oslo/messaging/rpc/dispatcher.py", line 133, in _dispatch_and_reply
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher     incoming.message))
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher   File "/usr/lib/python2.7/site-packages/oslo/messaging/rpc/dispatcher.py", line 176, in _dispatch
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher     return self._do_dispatch(endpoint, method, ctxt, args)
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher   File "/usr/lib/python2.7/site-packages/oslo/messaging/rpc/dispatcher.py", line 122, in _do_dispatch
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher     result = getattr(endpoint, method)(ctxt, **new_args)
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher   File "/usr/lib/python2.7/site-packages/cinder/volume/manager.py", line 166, in lso_inner1
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher     return lso_inner2(inst, context, snapshot_id, **kwargs)
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher   File "/usr/lib/python2.7/site-packages/cinder/openstack/common/lockutils.py", line 233, in inner
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher     retval = f(*args, **kwargs)
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher   File "/usr/lib/python2.7/site-packages/cinder/volume/manager.py", line 165, in lso_inner2
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher     return f(*_args, **_kwargs)
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher   File "/usr/lib/python2.7/site-packages/cinder/volume/manager.py", line 542, in delete_snapshot
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher     {'status': 'error_deleting'})
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher   File "/usr/lib/python2.7/site-packages/cinder/openstack/common/excutils.py", line 68, in __exit__
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher     six.reraise(self.type_, self.value, self.tb)
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher   File "/usr/lib/python2.7/site-packages/cinder/volume/manager.py", line 530, in delete_snapshot
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher     self.driver.delete_snapshot(snapshot_ref)
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher   File "/usr/lib/python2.7/site-packages/cinder/volume/drivers/lvm.py", line 252, in delete_snapshot
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher     self._delete_volume(snapshot, is_snapshot=True)
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher   File "/usr/lib/python2.7/site-packages/cinder/volume/drivers/lvm.py", line 128, in _delete_volume
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher     self._clear_volume(volume, is_snapshot)
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher   File "/usr/lib/python2.7/site-packages/cinder/volume/drivers/lvm.py", line 155, in _clear_volume
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher     raise exception.VolumeBackendAPIException(data=msg)
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher VolumeBackendAPIException: Bad or unexpected response from the storage volume backend API: Volume device file path /dev/mapper/cinder--volumes-_snapshot--af92b885--de85--450f--952b--4fd710b9910b-cow does not exist.
>2014-05-27 14:10:17.747 4082 TRACE oslo.messaging.rpc.dispatcher 
>2014-05-27 14:10:17.749 4082 ERROR oslo.messaging._drivers.common [req-c07581f7-4a63-467d-84f5-79027d43b084 eeb749506d324861865debcfd44be297 186b603b6cd04627b9aa42eb9e79f5e2 - - -] Returning exception Bad or unexpected response from the storage volume backend API: Volume device file path /dev/mapper/cinder--volumes-_snapshot--af92b885--de85--450f--952b--4fd710b9910b-cow does not exist. to caller
>2014-05-27 14:10:17.750 4082 ERROR oslo.messaging._drivers.common [req-c07581f7-4a63-467d-84f5-79027d43b084 eeb749506d324861865debcfd44be297 186b603b6cd04627b9aa42eb9e79f5e2 - - -] ['Traceback (most recent call last):\n', '  File "/usr/lib/python2.7/site-packages/oslo/messaging/rpc/dispatcher.py", line 133, in _dispatch_and_reply\n    incoming.message))\n', '  File "/usr/lib/python2.7/site-packages/oslo/messaging/rpc/dispatcher.py", line 176, in _dispatch\n    return self._do_dispatch(endpoint, method, ctxt, args)\n', '  File "/usr/lib/python2.7/site-packages/oslo/messaging/rpc/dispatcher.py", line 122, in _do_dispatch\n    result = getattr(endpoint, method)(ctxt, **new_args)\n', '  File "/usr/lib/python2.7/site-packages/cinder/volume/manager.py", line 166, in lso_inner1\n    return lso_inner2(inst, context, snapshot_id, **kwargs)\n', '  File "/usr/lib/python2.7/site-packages/cinder/openstack/common/lockutils.py", line 233, in inner\n    retval = f(*args, **kwargs)\n', '  File "/usr/lib/python2.7/site-packages/cinder/volume/manager.py", line 165, in lso_inner2\n    return f(*_args, **_kwargs)\n', '  File "/usr/lib/python2.7/site-packages/cinder/volume/manager.py", line 542, in delete_snapshot\n    {\'status\': \'error_deleting\'})\n', '  File "/usr/lib/python2.7/site-packages/cinder/openstack/common/excutils.py", line 68, in __exit__\n    six.reraise(self.type_, self.value, self.tb)\n', '  File "/usr/lib/python2.7/site-packages/cinder/volume/manager.py", line 530, in delete_snapshot\n    self.driver.delete_snapshot(snapshot_ref)\n', '  File "/usr/lib/python2.7/site-packages/cinder/volume/drivers/lvm.py", line 252, in delete_snapshot\n    self._delete_volume(snapshot, is_snapshot=True)\n', '  File "/usr/lib/python2.7/site-packages/cinder/volume/drivers/lvm.py", line 128, in _delete_volume\n    self._clear_volume(volume, is_snapshot)\n', '  File "/usr/lib/python2.7/site-packages/cinder/volume/drivers/lvm.py", line 155, in _clear_volume\n    raise exception.VolumeBackendAPIException(data=msg)\n', 'VolumeBackendAPIException: Bad or unexpected response from the storage volume backend API: Volume device file path /dev/mapper/cinder--volumes-_snapshot--af92b885--de85--450f--952b--4fd710b9910b-cow does not exist.\n']
>2014-05-27 14:10:27.555 4082 INFO cinder.volume.manager [-] Updating volume status
>2014-05-27 14:11:27.561 4082 INFO cinder.volume.manager [-] Updating volume status
>2014-05-27 14:12:27.564 4082 INFO cinder.volume.manager [-] Updating volume status
>2014-05-27 14:13:27.564 4082 INFO cinder.volume.manager [-] Updating volume status
>2014-05-27 14:14:27.571 4082 INFO cinder.volume.manager [-] Updating volume status
>2014-05-27 14:15:27.572 4082 INFO cinder.volume.manager [-] Updating volume status
>2014-05-27 14:16:27.575 4082 INFO cinder.volume.manager [-] Updating volume status

Actions: View

Attachments on bug 1101504: 899473 | 899474 | 899475