test_create_get_delete_volume_with_volume_type_and_extra_specs flakey failure

[Christopher Yeoh]

ft146.1: tempest.api.volume.admin.test_volume_types.VolumeTypesTest.test_create_get_delete_volume_with_volume_type_and_extra_specs[gate,smoke]_StringException: Empty attachments:
  stderr
  stdout

pythonlogging:'': {{{
2013-09-10 02:38:42,465 Request: POST http://127.0.0.1:5000/v2.0/tokens
2013-09-10 02:38:42,559 Response Status: 200
2013-09-10 02:38:42,560 Request: POST http://127.0.0.1:8776/v1/944fca4ef6fb4f5c91ee2c06f1f25341/types
2013-09-10 02:38:43,026 Response Status: 200
2013-09-10 02:38:43,026 Nova request id: req-0ef904fd-fd20-4507-8d1e-388a115c7ce4
2013-09-10 02:38:43,026 Request: POST http://127.0.0.1:5000/v2.0/tokens
2013-09-10 02:38:43,132 Response Status: 200
2013-09-10 02:38:43,132 Request: POST http://127.0.0.1:8776/v1/724b983a62e643d1abfb57e08d20754b/volumes
2013-09-10 02:38:43,302 Response Status: 200
2013-09-10 02:38:43,303 Nova request id: req-f6edd60c-747f-48a9-8476-5ce672e8b6a9
2013-09-10 02:38:43,303 Request: GET http://127.0.0.1:8776/v1/724b983a62e643d1abfb57e08d20754b/volumes/c9d6d160-dbf8-460b-b22e-a3d423fb1dec
2013-09-10 02:38:43,380 Response Status: 200
2013-09-10 02:38:43,381 Nova request id: req-762e3002-267f-4aef-91c6-6bcd17b8f34a
2013-09-10 02:38:44,382 Request: GET http://127.0.0.1:8776/v1/724b983a62e643d1abfb57e08d20754b/volumes/c9d6d160-dbf8-460b-b22e-a3d423fb1dec
2013-09-10 02:38:44,463 Response Status: 200
2013-09-10 02:38:44,463 Nova request id: req-d759a65e-4069-4b5d-b354-9d2f93dd3931
2013-09-10 02:38:44,464 Request: GET http://127.0.0.1:8776/v1/724b983a62e643d1abfb57e08d20754b/volumes/c9d6d160-dbf8-460b-b22e-a3d423fb1dec
2013-09-10 02:38:44,537 Response Status: 200
2013-09-10 02:38:44,538 Nova request id: req-3a49480d-caa5-41cd-abe8-011502328775
2013-09-10 02:38:44,539 Request: DELETE http://127.0.0.1:8776/v1/724b983a62e643d1abfb57e08d20754b/volumes/c9d6d160-dbf8-460b-b22e-a3d423fb1dec
2013-09-10 02:38:44,584 Response Status: 202
2013-09-10 02:38:44,584 Request: GET http://127.0.0.1:8776/v1/724b983a62e643d1abfb57e08d20754b/volumes/c9d6d160-dbf8-460b-b22e-a3d423fb1dec
2013-09-10 02:38:44,661 Response Status: 200
2013-09-10 02:38:44,662 Nova request id: req-cd4c6d51-6f4b-4694-9c46-3af3de146183
2013-09-10 02:38:45,663 Request: GET http://127.0.0.1:8776/v1/724b983a62e643d1abfb57e08d20754b/volumes/c9d6d160-dbf8-460b-b22e-a3d423fb1dec
2013-09-10 02:38:45,739 Response Status: 200
2013-09-10 02:38:45,739 Nova request id: req-2415ae84-b499-4bd1-bfcc-ecbba439b2a6
2013-09-10 02:38:46,739 Request: GET http://127.0.0.1:8776/v1/724b983a62e643d1abfb57e08d20754b/volumes/c9d6d160-dbf8-460b-b22e-a3d423fb1dec

.....

2013-09-10 02:45:24,249 Request: GET http://127.0.0.1:8776/v1/724b983a62e643d1abfb57e08d20754b/volumes/c9d6d160-dbf8-460b-b22e-a3d423fb1dec
2013-09-10 02:45:24,332 Response Status: 200
2013-09-10 02:45:24,333 Nova request id: req-90b0bae8-7a7c-4c87-a17c-3c7d6ae4bce7
2013-09-10 02:45:24,334 Request: DELETE http://127.0.0.1:8776/v1/944fca4ef6fb4f5c91ee2c06f1f25341/types/826524b6-cd8e-49fa-ba75-76ce98ec84b5
2013-09-10 02:45:24,755 Response Status: 400
2013-09-10 02:45:24,755 Nova request id: req-0224042d-5183-41b1-bf70-6ecc16d5747f
}}}

traceback-1: {{{
Traceback (most recent call last):
  File "tempest/api/volume/admin/test_volume_types.py", line 47, in _delete_volume_type
    resp, _ = self.client.delete_volume_type(volume_type_id)
  File "tempest/services/volume/json/admin/volume_types_client.py", line 73, in delete_volume_type
    return self.delete("types/%s" % str(volume_id))
  File "tempest/common/rest_client.py", line 266, in delete
    return self.request('DELETE', url, headers)
  File "tempest/common/rest_client.py", line 394, in request
    resp, resp_body)
  File "tempest/common/rest_client.py", line 444, in _error_checker
    raise exceptions.BadRequest(resp_body)
BadRequest: Bad request
Details: {u'badRequest': {u'message': u'Target volume type is still in use.', u'code': 400}}
}}}

Traceback (most recent call last):
  File "tempest/api/volume/admin/test_volume_types.py", line 44, in _delete_volume
    self.volumes_client.wait_for_resource_deletion(volume_id)
  File "tempest/common/rest_client.py", line 503, in wait_for_resource_deletion
    raise exceptions.TimeoutException
TimeoutException: Request timed out

http://logs.openstack.org/68/40168/6/check/gate-tempest-devstack-vm-full/9a6d61c/testr_results.html.gz

[Matthew Treinish (treinish)]

This has the fingerprint of an open cinder bug:

http://logs.openstack.org/68/40168/6/check/gate-tempest-devstack-vm-full/9a6d61c/logs/screen-c-vol.txt.gz#_2013-09-10_02_38_45_595