After removing the orphan vdi (bab415f3-62da-8bae-b80e-d3ce45a1ca5c) no more errors happened. I am assuming, it was the result of using a non- clean environment. Marking as invalid, create a separate bug for the logging error (that's still a problem)
** Changed in: nova Importance: Undecided => Low ** Changed in: nova Status: New => Invalid ** Tags added: xenserver -- You received this bug notification because you are a member of Yahoo! Engineering Team, which is subscribed to OpenStack Compute (nova). https://bugs.launchpad.net/bugs/1194903 Title: xenapi: euca attach volume fails Status in OpenStack Compute (Nova): Invalid Bug description: Running exercise.sh, I experience the following: + timeout 1000 sh -c 'while ! euca-describe-volumes vol-00000001 | grep -q available; do sleep 1; done' + euca-attach-volume -i i-00000001 -d /dev/vdc vol-00000001 VOLUME vol-00000001 + timeout 500 sh -c 'while ! euca-describe-volumes vol-00000001 | grep -A 1 in-use | grep -q attach; do sleep 1; done' + die 100 'Could not attach vol-00000001 to i-00000001' + local exitcode=0 + set +o xtrace [ERROR] /opt/stack/devstack/exercises/euca.sh:100 Could not attach vol-00000001 to i-00000001 In the compute logs, the following lines grabbed my attention: Failed to attach volume ca6e35e2-7056-42b8-a4d4-546cc1a8c998 at /dev/xvdc Traceback (most recent call last): File "/opt/stack/nova/nova/compute/manager.py", line 3091, in _attach_volume mountpoint) File "/opt/stack/nova/nova/virt/xenapi/driver.py", line 367, in attach_volume mountpoint) File "/opt/stack/nova/nova/virt/xenapi/volumeops.py", line 58, in attach_volume hotplug=hotplug) File "/opt/stack/nova/nova/virt/xenapi/volumeops.py", line 96, in _connect_volume self._session, sr_uuid, sr_label, sr_params) File "/opt/stack/nova/nova/virt/xenapi/volume_utils.py", line 74, in introduce_sr session.call_xenapi("SR.scan", sr_ref) File "/opt/stack/nova/nova/virt/xenapi/driver.py", line 725, in call_xenapi return session.xenapi_request(method, args) File "/usr/local/lib/python2.7/dist-packages/XenAPI.py", line 133, in xenapi_request result = _parse_result(getattr(self, methodname)(*full_params)) File "/usr/local/lib/python2.7/dist-packages/XenAPI.py", line 203, in _parse_result raise Failure(result['ErrorDescription']) Failure: ['SR_BACKEND_FAILURE_40', '', 'The SR scan failed [opterr=[\'INTERNAL_ERROR\', \'Db_exn.Uniqueness_constraint_violation("VDI", "uuid", "bab415f3-62da-8bae-b80e-d3ce45a1ca5c")\']]', ''] ... [req-286ae122-06e4-4dfc-9e91-4416e37689b1 demo demo] Exception during message handling Traceback (most recent call last): File "/opt/stack/nova/nova/openstack/common/rpc/amqp.py", line 433, in _process_data **args) File "/opt/stack/nova/nova/openstack/common/rpc/dispatcher.py", line 172, in dispatch result = getattr(proxyobj, method)(ctxt, **kwargs) File "/opt/stack/nova/nova/exception.py", line 99, in wrapped temp_level, payload) File "/usr/lib/python2.7/contextlib.py", line 24, in __exit__ self.gen.next() File "/opt/stack/nova/nova/exception.py", line 76, in wrapped return f(self, context, *args, **kw) File "/opt/stack/nova/nova/compute/manager.py", line 215, in decorated_function pass File "/usr/lib/python2.7/contextlib.py", line 24, in __exit__ self.gen.next() File "/opt/stack/nova/nova/compute/manager.py", line 201, in decorated_function return function(self, context, *args, **kwargs) File "/opt/stack/nova/nova/compute/manager.py", line 243, in decorated_function e, sys.exc_info()) File "/usr/lib/python2.7/contextlib.py", line 24, in __exit__ self.gen.next() File "/opt/stack/nova/nova/compute/manager.py", line 230, in decorated_function return function(self, context, *args, **kwargs) File "/opt/stack/nova/nova/compute/manager.py", line 3064, in attach_volume context, instance, mountpoint) File "/usr/lib/python2.7/contextlib.py", line 24, in __exit__ self.gen.next() File "/opt/stack/nova/nova/compute/manager.py", line 3059, in attach_volume mountpoint, instance) File "/opt/stack/nova/nova/compute/manager.py", line 3101, in _attach_volume connector) File "/usr/lib/python2.7/contextlib.py", line 24, in __exit__ self.gen.next() File "/opt/stack/nova/nova/compute/manager.py", line 3091, in _attach_volume mountpoint) File "/opt/stack/nova/nova/virt/xenapi/driver.py", line 367, in attach_volume mountpoint) File "/opt/stack/nova/nova/virt/xenapi/volumeops.py", line 58, in attach_volume hotplug=hotplug) File "/opt/stack/nova/nova/virt/xenapi/volumeops.py", line 96, in _connect_volume self._session, sr_uuid, sr_label, sr_params) File "/opt/stack/nova/nova/virt/xenapi/volume_utils.py", line 74, in introduce_sr session.call_xenapi("SR.scan", sr_ref) File "/opt/stack/nova/nova/virt/xenapi/driver.py", line 725, in call_xenapi return session.xenapi_request(method, args) File "/usr/local/lib/python2.7/dist-packages/XenAPI.py", line 133, in xenapi_request result = _parse_result(getattr(self, methodname)(*full_params)) File "/usr/local/lib/python2.7/dist-packages/XenAPI.py", line 203, in _parse_result raise Failure(result['ErrorDescription']) Failure: ['SR_BACKEND_FAILURE_40', '', 'The SR scan failed [opterr=[\'INTERNAL_ERROR\', \'Db_exn.Uniqueness_constraint_violation("VDI", "uuid", "bab415f3-62da-8bae-b80e-d3ce45a1ca5c")\']]', ''] Also interesting, but not sure if it's related: 2013-06-26 14:02:34.851 DEBUG nova.virt.xenapi.volumeops [req-286ae122-06e4-4dfc-9e91-4416e37689b1 demo demo] Attach_volume: {u'driver_volume_type': u'iscsi', 'serial': u'ca6e35e2-7056-42b8-a4d4-546cc1a8c998', u'data': {u'target_discovered': False, u'target_iqn': u'iqn.2010-10.org.openstack:volume-ca6e35e2-7056-42b8-a4d4-546cc1a8c998', u'target_portal': u'10.219.3.143:3260', u'volume_id': u'ca6e35e2-7056-42b8-a4d4- 546cc1a8c998', u'target_lun': 1, u'auth_password': u'KErojkWaXXJLGRrk9mBM', u'auth_username': u'boAtvqyJyX5i2DkdccDG', u'auth_method': u'CHAP'}}, instance-00000001," /dev/xvdc from (pid=23955) attach_volume /o pt/stack/nova/nova/virt/xenapi/volumeops.py:51 Traceback (most recent call last): File "/usr/lib/python2.7/logging/__init__.py", line 846, in emit msg = self.format(record) File "/opt/stack/nova/nova/openstack/common/log.py", line 551, in format return logging.StreamHandler.format(self, record) File "/usr/lib/python2.7/logging/__init__.py", line 723, in format return fmt.format(record) File "/opt/stack/nova/nova/openstack/common/log.py", line 515, in format return logging.Formatter.format(self, record) File "/usr/lib/python2.7/logging/__init__.py", line 464, in format record.message = record.getMessage() File "/usr/lib/python2.7/logging/__init__.py", line 328, in getMessage msg = msg % self.args TypeError: not enough arguments for format string Logged from file volume_utils.py, line 237 To manage notifications about this bug go to: https://bugs.launchpad.net/nova/+bug/1194903/+subscriptions -- Mailing list: https://launchpad.net/~yahoo-eng-team Post to : yahoo-eng-team@lists.launchpad.net Unsubscribe : https://launchpad.net/~yahoo-eng-team More help : https://help.launchpad.net/ListHelp