Error during test test_delete_server.DeleteServersV3Test.test_delete_server_while_in_attached_volume

[Sergey Kraynev]

There is error for test:

tempest.api.compute.v3.servers.test_delete_server.DeleteServersV3Test.test_delete_server_while_in_attached_volume[gate]

Traceback for current error:

2014-04-14 08:47:16.113 | 2014-04-14 08:38:15,655 Request (DeleteServersV3Test:test_delete_server_while_in_attached_volume): 200 GET http://127.0.0.1:8776/v1/3feb95bed82a43d6ad73b799a5fdb81f/volumes/c64dbce8-c5ad-420b-be72-837a6c305ca4 0.191s
2014-04-14 08:47:16.113 | 2014-04-14 08:38:16,735 Request (DeleteServersV3Test:test_delete_server_while_in_attached_volume): 200 GET http://127.0.0.1:8776/v1/3feb95bed82a43d6ad73b799a5fdb81f/volumes/c64dbce8-c5ad-420b-be72-837a6c305ca4 0.077s
2014-04-14 08:47:16.113 | 2014-04-14 08:38:17,911 Request (DeleteServersV3Test:test_delete_server_while_in_attached_volume): 200 GET http://127.0.0.1:8776/v1/3feb95bed82a43d6ad73b799a5fdb81f/volumes/c64dbce8-c5ad-420b-be72-837a6c305ca4 0.174s
2014-04-14 08:47:16.113 | 2014-04-14 08:38:19,040 Request (DeleteServersV3Test:test_delete_server_while_in_attached_volume): 200 GET http://127.0.0.1:8776/v1/3feb95bed82a43d6ad73b799a5fdb81f/volumes/c64dbce8-c5ad-420b-be72-837a6c305ca4 0.128s
2014-04-14 08:47:16.114 | 2014-04-14 08:38:20,415 Request (DeleteServersV3Test:test_delete_server_while_in_attached_volume): 200 GET http://127.0.0.1:8776/v1/3feb95bed82a43d6ad73b799a5fdb81f/volumes/c64dbce8-c5ad-420b-be72-837a6c305ca4 0.373s
2014-04-14 08:47:16.114 | 2014-04-14 08:38:21,543 Request (DeleteServersV3Test:test_delete_server_while_in_attached_volume): 200 GET http://127.0.0.1:8776/v1/3feb95bed82a43d6ad73b799a5fdb81f/volumes/c64dbce8-c5ad-420b-be72-837a6c305ca4 0.125s
2014-04-14 08:47:16.114 | 2014-04-14 08:38:22,651 Request (DeleteServersV3Test:test_delete_server_while_in_attached_volume): 200 GET http://127.0.0.1:8776/v1/3feb95bed82a43d6ad73b799a5fdb81f/volumes/c64dbce8-c5ad-420b-be72-837a6c305ca4 0.105s
2014-04-14 08:47:16.114 | 2014-04-14 08:38:23,812 Request (DeleteServersV3Test:test_delete_server_while_in_attached_volume): 200 GET http://127.0.0.1:8776/v1/3feb95bed82a43d6ad73b799a5fdb81f/volumes/c64dbce8-c5ad-420b-be72-837a6c305ca4 0.158s
2014-04-14 08:47:16.114 | 2014-04-14 08:38:24,917 Request (DeleteServersV3Test:test_delete_server_while_in_attached_volume): 200 GET http://127.0.0.1:8776/v1/3feb95bed82a43d6ad73b799a5fdb81f/volumes/c64dbce8-c5ad-420b-be72-837a6c305ca4 0.102s
2014-04-14 08:47:16.114 | 2014-04-14 08:38:26,031 Request (DeleteServersV3Test:test_delete_server_while_in_attached_volume): 200 GET http://127.0.0.1:8776/v1/3feb95bed82a43d6ad73b799a5fdb81f/volumes/c64dbce8-c5ad-420b-be72-837a6c305ca4 0.112s
2014-04-14 08:47:16.115 | 2014-04-14 08:38:27,139 Request (DeleteServersV3Test:test_delete_server_while_in_attached_volume): 200 GET http://127.0.0.1:8776/v1/3feb95bed82a43d6ad73b799a5fdb81f/volumes/c64dbce8-c5ad-420b-be72-837a6c305ca4 0.105s
2014-04-14 08:47:16.115 | 2014-04-14 08:38:27,165 Request (DeleteServersV3Test:_run_cleanups): 400 DELETE http://127.0.0.1:8776/v1/3feb95bed82a43d6ad73b799a5fdb81f/volumes/c64dbce8-c5ad-420b-be72-837a6c305ca4 0.022s
2014-04-14 08:47:16.115 | }}}
2014-04-14 08:47:16.115 |
2014-04-14 08:47:16.115 | traceback-1: {{{
2014-04-14 08:47:16.115 | Traceback (most recent call last):
2014-04-14 08:47:16.116 | File "tempest/services/volume/json/volumes_client.py", line 101, in delete_volume
2014-04-14 08:47:16.116 | return self.delete("volumes/%s" % str(volume_id))
2014-04-14 08:47:16.116 | File "tempest/common/rest_client.py", line 207, in delete
2014-04-14 08:47:16.116 | return self.request('DELETE', url, extra_headers, headers, body)
2014-04-14 08:47:16.116 | File "tempest/common/rest_client.py", line 452, in request
2014-04-14 08:47:16.116 | resp, resp_body)
2014-04-14 08:47:16.116 | File "tempest/common/rest_client.py", line 501, in _error_checker
2014-04-14 08:47:16.117 | raise exceptions.BadRequest(resp_body)
2014-04-14 08:47:16.117 | BadRequest: Bad request
2014-04-14 08:47:16.117 | Details: {u'message': u'Invalid volume: Volume status must be available or error, but current status is: attaching', u'code': 400}
2014-04-14 08:47:16.117 | }}}
2014-04-14 08:47:16.117 |
2014-04-14 08:47:16.117 | Traceback (most recent call last):
2014-04-14 08:47:16.118 | File "tempest/api/compute/v3/servers/test_delete_server.py", line 114, in test_delete_server_while_in_attached_volume
2014-04-14 08:47:16.118 | self.volumes_client.wait_for_volume_status(volume['id'], 'in-use')
2014-04-14 08:47:16.118 | File "tempest/services/volume/json/volumes_client.py", line 168, in wait_for_volume_status
2014-04-14 08:47:16.118 | raise exceptions.TimeoutException(message)
2014-04-14 08:47:16.118 | TimeoutException: Request timed out
2014-04-14 08:47:16.119 | Details: Volume None failed to reach in-use status within the required time (196 s).

For additional information look: http://logs.openstack.org/03/86803/4/check/check-tempest-dsvm-full/bde6df5/

[Ghanshyam Mann (ghanshyammann)]

Tempest test try to do cleanup of resources in addCleanup in case of any exception in tests. Here also when cinder volume failed to reach in-use status within the required time, timeout exception happened and addCleanup try to delete the 'attaching' volume.

Needs to look from cinder side for volume taking long time to reach to 'in-use' state.

[Sean McGinnis (sean-mcginnis)]

Closing stale bug. If this is still an issue please reopen.