Cinder volume requires restart every 30 minutes when configured with VMwareVcVmdkDriver driver

Bug #1317490 reported by S. Pragadeeswaran
10
This bug affects 2 people
Affects Status Importance Assigned to Milestone
Cinder
New
Undecided
Unassigned

Bug Description

Icehouse GA on Debian & Ubuntu

volume_driver = cinder.volume.drivers.vmware.vmdk.VMwareVcVmdkDriver

------------------------------------------------------------------------------------------------------------------------

cinder-volume service needs to be restarted every 30 minutes when no operations are performed. If any operation like delete volume or attach volume is performed after the service is left idle then the below error message is seen

2014-05-05 04:13:58.123 3274 WARNING cinder.context [-] Arguments dropped when creating context: {'user': u'79335fd786124ab582faa400d95d1e43', 'tenant': u'ba5c46dee0154e578990a3009be4271e', 'user_identity': u'79335fd786124ab582faa400d95d1e43 ba5c46dee0154e578990a3009be4271e - - -'}
2014-05-05 04:56:25.377 3274 WARNING cinder.context [-] Arguments dropped when creating context: {'user': u'79335fd786124ab582faa400d95d1e43', 'tenant': u'ba5c46dee0154e578990a3009be4271e', 'user_identity': u'79335fd786124ab582faa400d95d1e43 ba5c46dee0154e578990a3009be4271e - - -'}
2014-05-05 04:56:25.492 3274 WARNING cinder.context [-] Arguments dropped when creating context: {'user': u'79335fd786124ab582faa400d95d1e43', 'tenant': u'ba5c46dee0154e578990a3009be4271e', 'user_identity': u'79335fd786124ab582faa400d95d1e43 ba5c46dee0154e578990a3009be4271e - - -'}
2014-05-05 04:56:31.759 3274 WARNING cinder.context [-] Arguments dropped when creating context: {'user': u'79335fd786124ab582faa400d95d1e43', 'tenant': u'ba5c46dee0154e578990a3009be4271e', 'user_identity': u'79335fd786124ab582faa400d95d1e43 ba5c46dee0154e578990a3009be4271e - - -'}
2014-05-05 04:56:31.923 3274 ERROR suds.client [-] <?xml version="1.0" encoding="UTF-8"?>
<SOAP-ENV:Envelope xmlns:ns0="urn:vim25" xmlns:ns1="http://schemas.xmlsoap.org/soap/envelope/" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/">
   <ns1:Body>
      <ns0:SessionIsActive>
         <ns0:_this type="SessionManager">SessionManager</ns0:_this>
         <ns0:sessionID>521242ca-1a89-b6e4-9c59-8c3e90eed20b</ns0:sessionID>
         <ns0:userName>satya</ns0:userName>
      </ns0:SessionIsActive>
   </ns1:Body>
</SOAP-ENV:Envelope>
2014-05-05 04:56:31.926 3274 ERROR cinder.openstack.common.loopingcall [-] in dynamic looping call
2014-05-05 04:56:31.926 3274 TRACE cinder.openstack.common.loopingcall Traceback (most recent call last):
2014-05-05 04:56:31.926 3274 TRACE cinder.openstack.common.loopingcall File "/usr/lib/python2.7/dist-packages/cinder/openstack/common/loopingcall.py", line 123, in _inner
2014-05-05 04:56:31.926 3274 TRACE cinder.openstack.common.loopingcall idle = self.f(*self.args, **self.kw)
2014-05-05 04:56:31.926 3274 TRACE cinder.openstack.common.loopingcall File "/usr/lib/python2.7/dist-packages/cinder/volume/drivers/vmware/api.py", line 82, in _func
2014-05-05 04:56:31.926 3274 TRACE cinder.openstack.common.loopingcall raise excep
2014-05-05 04:56:31.926 3274 TRACE cinder.openstack.common.loopingcall VimFaultException: Server raised fault: 'The session is not authenticated.'
2014-05-05 04:56:31.926 3274 TRACE cinder.openstack.common.loopingcall
2014-05-05 04:56:31.966 3274 ERROR oslo.messaging.rpc.dispatcher [req-0458a6c2-717f-454d-9926-aa9765c97943 79335fd786124ab582faa400d95d1e43 ba5c46dee0154e578990a3009be4271e - - -] Exception during message handling: Server raised fault: 'The session is not authenticated.'
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher Traceback (most recent call last):
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher File "/usr/lib/python2.7/dist-packages/oslo/messaging/rpc/dispatcher.py", line 133, in _dispatch_and_reply
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher incoming.message))
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher File "/usr/lib/python2.7/dist-packages/oslo/messaging/rpc/dispatcher.py", line 176, in _dispatch
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher return self._do_dispatch(endpoint, method, ctxt, args)
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher File "/usr/lib/python2.7/dist-packages/oslo/messaging/rpc/dispatcher.py", line 122, in _do_dispatch
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher result = getattr(endpoint, method)(ctxt, **new_args)
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher File "/usr/lib/python2.7/dist-packages/cinder/volume/manager.py", line 144, in lvo_inner1
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher return lvo_inner2(inst, context, volume_id, **kwargs)
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher File "/usr/lib/python2.7/dist-packages/cinder/openstack/common/lockutils.py", line 233, in inner
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher retval = f(*args, **kwargs)
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher File "/usr/lib/python2.7/dist-packages/cinder/volume/manager.py", line 143, in lvo_inner2
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher return f(*_args, **_kwargs)
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher File "/usr/lib/python2.7/dist-packages/cinder/volume/manager.py", line 416, in delete_volume
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher {'status': 'error_deleting'})
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher File "/usr/lib/python2.7/dist-packages/cinder/openstack/common/excutils.py", line 68, in __exit__
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher six.reraise(self.type_, self.value, self.tb)
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher File "/usr/lib/python2.7/dist-packages/cinder/volume/manager.py", line 405, in delete_volume
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher self.driver.delete_volume(volume_ref)
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher File "/usr/lib/python2.7/dist-packages/cinder/volume/drivers/vmware/vmdk.py", line 280, in delete_volume
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher self._delete_volume(volume)
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher File "/usr/lib/python2.7/dist-packages/cinder/volume/drivers/vmware/vmdk.py", line 269, in _delete_volume
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher backing = self.volumeops.get_backing(volume['name'])
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher File "/usr/lib/python2.7/dist-packages/cinder/volume/drivers/vmware/volumeops.py", line 77, in get_backing
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher self._max_objects)
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher File "/usr/lib/python2.7/dist-packages/cinder/volume/drivers/vmware/api.py", line 252, in invoke_api
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher return _invoke_api(module, method, *args, **kwargs)
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher File "/usr/lib/python2.7/dist-packages/cinder/volume/drivers/vmware/api.py", line 89, in func
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher return timer.wait()
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher File "/usr/lib/python2.7/dist-packages/eventlet/event.py", line 116, in wait
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher return hubs.get_hub().switch()
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher File "/usr/lib/python2.7/dist-packages/eventlet/hubs/hub.py", line 187, in switch
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher return self.greenlet.switch()
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher File "/usr/lib/python2.7/dist-packages/cinder/openstack/common/loopingcall.py", line 123, in _inner
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher idle = self.f(*self.args, **self.kw)
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher File "/usr/lib/python2.7/dist-packages/cinder/volume/drivers/vmware/api.py", line 82, in _func
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher raise excep
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher VimFaultException: Server raised fault: 'The session is not authenticated.'
2014-05-05 04:56:31.966 3274 TRACE oslo.messaging.rpc.dispatcher
2014-05-05 04:56:31.971 3274 ERROR oslo.messaging._drivers.common [req-0458a6c2-717f-454d-9926-aa9765c97943 79335fd786124ab582faa400d95d1e43 ba5c46dee0154e578990a3009be4271e - - -] Returning exception Server raised fault: 'The session is not authenticated.' to caller
2014-05-05 04:56:31.971 3274 ERROR oslo.messaging._drivers.common [req-0458a6c2-717f-454d-9926-aa9765c97943 79335fd786124ab582faa400d95d1e43 ba5c46dee0154e578990a3009be4271e - - -] ['Traceback (most recent call last):\n', ' File "/usr/lib/python2.7/dist-packages/oslo/messaging/rpc/dispatcher.py", line 133, in _dispatch_and_reply\n incoming.message))\n', ' File "/usr/lib/python2.7/dist-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/dist-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/dist-packages/cinder/volume/manager.py", line 144, in lvo_inner1\n return lvo_inner2(inst, context, volume_id, **kwargs)\n', ' File "/usr/lib/python2.7/dist-packages/cinder/openstack/common/lockutils.py", line 233, in inner\n retval = f(*args, **kwargs)\n', ' File "/usr/lib/python2.7/dist-packages/cinder/volume/manager.py", line 143, in lvo_inner2\n return f(*_args, **_kwargs)\n', ' File "/usr/lib/python2.7/dist-packages/cinder/volume/manager.py", line 416, in delete_volume\n {\'status\': \'error_deleting\'})\n', ' File "/usr/lib/python2.7/dist-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/dist-packages/cinder/volume/manager.py", line 405, in delete_volume\n self.driver.delete_volume(volume_ref)\n', ' File "/usr/lib/python2.7/dist-packages/cinder/volume/drivers/vmware/vmdk.py", line 280, in delete_volume\n self._delete_volume(volume)\n', ' File "/usr/lib/python2.7/dist-packages/cinder/volume/drivers/vmware/vmdk.py", line 269, in _delete_volume\n backing = self.volumeops.get_backing(volume[\'name\'])\n', ' File "/usr/lib/python2.7/dist-packages/cinder/volume/drivers/vmware/volumeops.py", line 77, in get_backing\n self._max_objects)\n', ' File "/usr/lib/python2.7/dist-packages/cinder/volume/drivers/vmware/api.py", line 252, in invoke_api\n return _invoke_api(module, method, *args, **kwargs)\n', ' File "/usr/lib/python2.7/dist-packages/cinder/volume/drivers/vmware/api.py", line 89, in func\n return timer.wait()\n', ' File "/usr/lib/python2.7/dist-packages/eventlet/event.py", line 116, in wait\n return hubs.get_hub().switch()\n', ' File "/usr/lib/python2.7/dist-packages/eventlet/hubs/hub.py", line 187, in switch\n return self.greenlet.switch()\n', ' File "/usr/lib/python2.7/dist-packages/cinder/openstack/common/loopingcall.py", line 123, in _inner\n idle = self.f(*self.args, **self.kw)\n', ' File "/usr/lib/python2.7/dist-packages/cinder/volume/drivers/vmware/api.py", line 82, in _func\n raise excep\n', "VimFaultException: Server raised fault: 'The session is not authenticated.'\n"]
2014-05-05 05:11:44.796 3320 WARNING cinder.volume.drivers.vmware.vmdk [-] The VMware ESX VMDK driver is now deprecated and will be removed in the Juno release. The VMware vCenter VMDK driver will remain and continue to be supported.
2014-05-05 05:17:27.929 3342 WARNING cinder.context [-] Arguments dropped when creating context: {'user': u'79335fd786124ab582faa400d95d1e43', 'tenant': u'ba5c46dee0154e578990a3009be4271e', 'user_identity': u'79335fd786124ab582faa400d95d1e43 ba5c46dee0154e578990a3009be4271e - - -'}

Revision history for this message
satyadev svn (svnsatya) wrote :

seems to be duplicate of : 1302514

Revision history for this message
satyadev svn (svnsatya) wrote :

seems to be duplicate of : 1302514 [https://bugs.launchpad.net/cinder/+bug/1302514]

Revision history for this message
S. Pragadeeswaran (pragadeeswaran) wrote :

Yes, the errors are the same.

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.