tempest failed with errors:
======
Dec 11 20:27:29.984410 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters [None req-a912d20f-53c7-4454-a849-5ae6e5c0bfb3 tempest-TestVolumeSwap-1376654027 None] DBAPIError exception wrapped from (pymysql.err.InternalError) (1241, u'Operand should contain 1 column(s)') [SQL: u'INSERT INTO attachment_specs (created_at, updated_at, deleted_at, deleted, `key`, value, attachment_id) VALUES (%(created_at)s, %(updated_at)s, %(deleted_at)s, %(deleted)s, %(key)s, %(value)s, %(attachment_id)s)'] [parameters: {'attachment_id': 'b8b76b83-5e66-4df5-835c-a8c74e447951', 'deleted': 0, 'created_at': datetime.datetime(2017, 12, 12, 1, 27, 29, 982203), 'updated_at': None, 'value': [u'2100000e1ed04988', u'2100000e1ed04989'], 'key': u'wwpns', 'deleted_at': None}]: InternalError: (1241, u'Operand should contain 1 column(s)')
Dec 11 20:27:29.984880 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters Traceback (most recent call last):
Dec 11 20:27:29.984984 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters File "/usr/local/lib/python2.7/dist-packages/sqlalchemy/engine/base.py", line 1182, in _execute_context
Dec 11 20:27:29.985084 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters context)
Dec 11 20:27:29.985183 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters File "/usr/local/lib/python2.7/dist-packages/sqlalchemy/engine/default.py", line 470, in do_execute
Dec 11 20:27:29.985280 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters cursor.execute(statement, parameters)
Dec 11 20:27:29.985382 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters File "/usr/local/lib/python2.7/dist-packages/pymysql/cursors.py", line 166, in execute
Dec 11 20:27:29.985478 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters result = self._query(query)
Dec 11 20:27:29.985574 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters File "/usr/local/lib/python2.7/dist-packages/pymysql/cursors.py", line 322, in _query
Dec 11 20:27:29.985675 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters conn.query(q)
Dec 11 20:27:29.985771 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters File "/usr/local/lib/python2.7/dist-packages/pymysql/connections.py", line 856, in query
Dec 11 20:27:29.985874 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters self._affected_rows = self._read_query_result(unbuffered=unbuffered)
Dec 11 20:27:29.985970 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters File "/usr/local/lib/python2.7/dist-packages/pymysql/connections.py", line 1057, in _read_query_result
Dec 11 20:27:29.986065 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters result.read()
Dec 11 20:27:29.986161 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters File "/usr/local/lib/python2.7/dist-packages/pymysql/connections.py", line 1340, in read
Dec 11 20:27:29.986256 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters first_packet = self.connection._read_packet()
Dec 11 20:27:29.986355 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters File "/usr/local/lib/python2.7/dist-packages/pymysql/connections.py", line 1014, in _read_packet
Dec 11 20:27:29.986450 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters packet.check_error()
Dec 11 20:27:29.986547 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters File "/usr/local/lib/python2.7/dist-packages/pymysql/connections.py", line 393, in check_error
Dec 11 20:27:29.986643 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters err.raise_mysql_exception(self._data)
Dec 11 20:27:29.986762 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters File "/usr/local/lib/python2.7/dist-packages/pymysql/err.py", line 107, in raise_mysql_exception
Dec 11 20:27:29.986857 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters raise errorclass(errno, errval)
Dec 11 20:27:29.986953 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters InternalError: (1241, u'Operand should contain 1 column(s)')
Dec 11 20:27:29.987049 cdh2h5 cinder-volume[32307]: ERROR oslo_db.sqlalchemy.exc_filters
Dec 11 20:27:29.989634 cdh2h5 cinder-volume[32307]: ERROR oslo_messaging.rpc.server [None req-a912d20f-53c7-4454-a849-5ae6e5c0bfb3 tempest-TestVolumeSwap-1376654027 None] Exception during message handling: DBError: (pymysql.err.InternalError) (1241, u'Operand should contain 1 column(s)') [SQL: u'INSERT INTO attachment_specs (created_at, updated_at, deleted_at, deleted, `key`, value, attachment_id) VALUES (%(created_at)s, %(updated_at)s, %(deleted_at)s, %(deleted)s, %(key)s, %(value)s, %(attachment_id)s)'] [parameters: {'attachment_id': 'b8b76b83-5e66-4df5-835c-a8c74e447951', 'deleted': 0, 'created_at': datetime.datetime(2017, 12, 12, 1, 27, 29, 982203), 'updated_at': None, 'value': [u'2100000e1ed04988', u'2100000e1ed04989'], 'key': u'wwpns', 'deleted_at': None}]
======
for detailed logs, please check http://publiclogs.emc.com/515392/8/EMC_XtremIO_FC/1100/logs/screen-c-vol.txt.gz
This has affected some other FC jobs, eg: http://openstack.infortrend.com/32/526932/1/check/infortrend-cinder-volume-fc/8cb3059/logs/screen-c-vol.txt.gz
This is quite clearly a cinder bug. Tempest is doing it's job and making requests as part of tests, and cinder is raising a DB error. This is something that shouldn't happen to cinder just by making api requests.