Cinder backup failed with TSM driver when backup space is full,while Swift is OK

Bug #1607141 reported by TommyLike
6
This bug affects 1 person
Affects Status Importance Assigned to Milestone
Cinder
New
Low
Unassigned

Bug Description

## Release Version

Failed on release version of Juno

## Scenario

1. set TSM as default backup driver
2. use TSM API to backup volume (space for backup is not enough )
3. the cinder-backup copy keeps in creating status (error status is expected)
4. when server restarted,the backup failed messaged showed

## Comparison with swift
while in this situation when using swift,the operation failed normally with message below:

```
VolumeBackendAPIException:Bad or unexpected response from the storage volume backend API:create lun error,result:{u'data':{},u'error':{u'code':1073797124,u'description':u'the storage pool does not have sufficient free space'}}

```

## system logs
```
2016-07-02T08:53:47.800+08:00 localhost cinder-backup ERROR [pid:2757] [GreenThread-4] [exc_filters.py:277 _raise_for_remaining_DBAPIError] DBAPIError exception wrapped from (DataError) value too long for type character varying(255) 'UPDATE backups SET updated_at=%(updated_at)s, status=%(status)s, fail_reason=%(fail_reason)s WHERE backups.id = %(backups_id)s' {'status': 'error', 'fail_reason': u"Invalid backup: backup: 293e623d-2fb7-43fb-9f36-80f98d96699f failed to obtain backup success notification from server.\nstdout: IBM Tivoli Storage Manager\nCommand Line Backup-Archive Client Interface\n Client Version 7, Release 1, Level 0.0 \n Client date/time: 07/02/16 08:53:24\n(c) Copyright by IBM Corporation and other(s) 1990, 2013. All Rights Reserved.\n\nNode Name: DPSNODE\nSession established with server TSM: Windows\n Server Version 7, Release 1, Level 1.100\n Server date/time: 07/27/16 02:19:09 Last access: 07/27/16 01:56:55\n\n\nImage backup of volume '/dev/backup-30179e5c-7d95-414e-9979-8fe6d99dcb99'\nANS1228E Sending of object '/dev/backup-30179e5c-7d95-414e-9979-8fe6d99dcb99' failed.\nANS1311E Server out of data storage space\n\nANS1813E Image Backup processing of '/dev/backup-30179e5c-7d95-414e-9979-8fe6d99dcb99' finished with failures.\n\n\nTotal number of objects inspected: 1\nTotal number of objects backed up: 0\nTotal number of objects updated: 0\nTotal number of objects rebound: 0\nTotal number of objects deleted: 0\nTotal number of objects expired: 0\nTotal number of objects failed: 1\nTotal number of bytes inspected: 20.00 GB\nTotal number of bytes transferred: 0 B\nData transfer time: 2.00 sec\nNetwork data transfer rate: 3,328.02 KB/sec\nAggregate data transfer rate: 502.50 KB/sec\nObjects compressed by: 0%\nTotal data reduction ratio: 99.97%\nElapsed processing time: 00:00:13\n\n stderr: ", 'updated_at': datetime.datetime(2016, 7, 2, 0, 53, 47, 778137), 'backups_id': u'30179e5c-7d95-414e-9979-8fe6d99dcb99'} Traceback (most recent call last): File "/usr/lib64/python2.7/site-packages/oslo/db/sqlalchemy/compat/handle_error.py", line 59, in _handle_dbapi_exception e, statement, parameters, cursor, context) File "/usr/lib64/python2.7/site-packages/sqlalchemy/engine/base.py", line 1024, in _handle_dbapi_exception exc_info File "/usr/lib64/python2.7/site-packages/sqlalchemy/util/compat.py", line 196, in raise_from_cause reraise(type(exception), exception, tb=exc_tb) File "/usr/lib64/python2.7/site-packages/sqlalchemy/engine/base.py", line 867, in _execute_context context) File "/usr/lib64/python2.7/site-packages/sqlalchemy/engine/default.py", line 324, in do_execute cursor.execute(statement, parameters) DataError: (DataError) value too long for type character varying(255) 'UPDATE backups SET updated_at=%(updated_at)s, status=%(status)s, fail_reason=%(fail_reason)s WHERE backups.id = %(backups_id)s' {'status': 'error', 'fail_reason': u"Invalid backup: backup: 293e623d-2fb7-43fb-9f36-80f98d96699f failed to obtain backup success notification from server.\nstdout: IBM Tivoli Storage Manager\nCommand Line Backup-Archive Client Interface\n Client Version 7, Release 1, Level 0.0 \n Client date/time: 07/02/16 08:53:24\n(c) Copyright by IBM Corporation and other(s) 1990, 2013. All Rights Reserved.\n\nNode Name: DPSNODE\nSession established with server TSM: Windows\n Server Version 7, Release 1, Level 1.100\n Server date/time: 07/27/16 02:19:09 Last access: 07/27/16 01:56:55\n\n\nImage backup of volume '/dev/backup-30179e5c-7d95-414e-9979-8fe6d99dcb99'\nANS1228E Sending of object '/dev/backup-30179e5c-7d95-414e-9979-8fe6d99dcb99' failed.\nANS1311E Server out of data storage space\n\nANS1813E Image Backup processing of '/dev/backup-30179e5c-7d95-414e-9979-8fe6d99dcb99' finished with failures.\n\n\nTotal number of objects inspected: 1\nTotal number of objects backed up: 0\nTotal number of objects updated: 0\nTotal number of objects rebound: 0\nTotal number of objects deleted: 0\nTotal number of objects expired: 0\nTotal number of objects failed: 1\nTotal number of bytes inspected: 20.00 GB\nTotal number of bytes transferred: 0 B\nData transfer time: 2.00 sec\nNetwork data transfer rate: 3,328.02 KB/sec\nAggregate data transfer rate: 502.50 KB/sec\nObjects compressed by: 0%\nTotal data reduction ratio: 99.97%\nElapsed processing time: 00:00:13\n\n stderr: ", 'updated_at': datetime.datetime(2016, 7, 2, 0, 53, 47, 778137), 'backups_id': u'30179e5c-7d95-414e-9979-8fe6d99dcb99'}
```

Revision history for this message
TommyLike (hu-husheng) wrote :
Changed in cinder:
importance: Undecided → Low
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Bug attachments

Remote bug watches

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