Running the same test alongside it's siblings: all develop-inst-nodeps: /opt/stack/tempest all installed: anyjson==0.3.3,Babel==1.3,boto==2.38.0,cffi==1.1.2,cliff==1.13.0,cmd2==0.6.8,cryptography==0.9.2,debtcollector==0.5.0,ecdsa==0.13,enum34==1.0.4,extras==0.0.3,fasteners==0.12.0,fixtures==1.3.1,functools32==3.2.3.post1,httplib2==0.9.1,idna==2.0,ipaddress==1.0.7,iso8601==0.1.10,jsonschema==2.5.1,linecache2==1.0.0,monotonic==0.2,msgpack-python==0.4.6,netaddr==0.7.15,netifaces==0.10.4,os-testr==0.2.0,oslo.concurrency==2.2.0,oslo.config==1.14.0,oslo.context==0.4.0,oslo.i18n==2.1.0,oslo.log==1.6.0,oslo.serialization==1.7.0,oslo.utils==1.8.0,paramiko==1.15.2,pbr==1.2.0,posix-ipc==1.0.0,prettytable==0.7.2,pyasn1==0.1.8,pycparser==2.14,pycrypto==2.6.1,pyOpenSSL==0.15.1,pyparsing==2.0.3,python-mimeparse==0.1.4,python-subunit==1.1.0,pytz==2015.4,PyYAML==3.11,retrying==1.3.3,six==1.9.0,stevedore==1.6.0,-e git+https://git.openstack.org/openstack/tempest.git@91ebdfac2f6021aa1bccceaf4274bb801cc0891b#egg=tempest-master,tempest-lib==0.6.1,testrepository==0.0.20,testscenarios==0.5.0,testtools==1.8.0,traceback2==1.4.0,unittest2==1.1.0,wheel==0.24.0,wrapt==1.10.5 all runtests: PYTHONHASHSEED='125578107' all runtests: commands[0] | find . -type f -name *.pyc -delete all runtests: commands[1] | bash tools/pretty_tox.sh tempest.api.volume.test_volumes_snapshots.VolumesV1SnapshotTestJSON running testr running=OS_STDOUT_CAPTURE=${OS_STDOUT_CAPTURE:-1} \ OS_STDERR_CAPTURE=${OS_STDERR_CAPTURE:-1} \ OS_TEST_TIMEOUT=${OS_TEST_TIMEOUT:-500} \ OS_TEST_LOCK_PATH=${OS_TEST_LOCK_PATH:-${TMPDIR:-'/tmp'}} \ ${PYTHON:-python} -m subunit.run discover -t ${OS_TOP_LEVEL:-./} ${OS_TEST_PATH:-./tempest/test_discover} --list running=OS_STDOUT_CAPTURE=${OS_STDOUT_CAPTURE:-1} \ OS_STDERR_CAPTURE=${OS_STDERR_CAPTURE:-1} \ OS_TEST_TIMEOUT=${OS_TEST_TIMEOUT:-500} \ OS_TEST_LOCK_PATH=${OS_TEST_LOCK_PATH:-${TMPDIR:-'/tmp'}} \ ${PYTHON:-python} -m subunit.run discover -t ${OS_TOP_LEVEL:-./} ${OS_TEST_PATH:-./tempest/test_discover} --load-list /tmp/tmpi5ePgK {0} tempest.api.volume.test_volumes_snapshots.VolumesV1SnapshotTestJSON.test_snapshot_create_get_list_update_delete [4.529798s] ... ok {0} tempest.api.volume.test_volumes_snapshots.VolumesV1SnapshotTestJSON.test_snapshot_create_with_volume_in_use [32.171595s] ... ok {0} tempest.api.volume.test_volumes_snapshots.VolumesV1SnapshotTestJSON.test_snapshots_list_details_with_params [2.493639s] ... FAILED {0} tempest.api.volume.test_volumes_snapshots.VolumesV1SnapshotTestJSON.test_snapshots_list_with_params [2.654264s] ... FAILED {0} tempest.api.volume.test_volumes_snapshots.VolumesV1SnapshotTestJSON.test_volume_from_snapshot [3.125463s] ... FAILED ============================== Failed 3 tests - output below: ============================== tempest.api.volume.test_volumes_snapshots.VolumesV1SnapshotTestJSON.test_snapshots_list_details_with_params[id-220a1022-1fcd-4a74-a7bd-6b859156cda2] ---------------------------------------------------------------------------------------------------------------------------------------------------- Captured pythonlogging: ~~~~~~~~~~~~~~~~~~~~~~~ 2015-07-09 09:52:26,287 17084 INFO [tempest_lib.common.rest_client] Request (VolumesV1SnapshotTestJSON:test_snapshots_list_details_with_params): 200 POST http://127.0.0.1:8776/v1/0c08b888ef6a4308aaf72026b5b504bb/snapshots 0.419s 2015-07-09 09:52:26,288 17084 DEBUG [tempest_lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '', 'Content-Type': 'application/json'} Body: {"snapshot": {"volume_id": "838f4497-f2ba-4d72-a066-f857610fdb34", "display_name": "tempest-snap-1703685071"}} Response - Headers: {'x-compute-request-id': 'req-d9deecfa-d847-4093-be77-e18b726f370c', 'date': 'Thu, 09 Jul 2015 09:52:26 GMT', 'content-length': '278', 'status': '200', 'content-type': 'application/json', 'x-openstack-request-id': 'req-d9deecfa-d847-4093-be77-e18b726f370c', 'connection': 'close'} Body: {"snapshot": {"status": "creating", "display_name": "tempest-snap-1703685071", "created_at": "2015-07-09T09:52:26.113456", "display_description": null, "volume_id": "838f4497-f2ba-4d72-a066-f857610fdb34", "metadata": {}, "id": "80b17f4c-9f03-4ddf-bfa9-8a060479cf86", "size": 1}} 2015-07-09 09:52:26,335 17084 INFO [tempest_lib.common.rest_client] Request (VolumesV1SnapshotTestJSON:test_snapshots_list_details_with_params): 200 GET http://127.0.0.1:8776/v1/0c08b888ef6a4308aaf72026b5b504bb/snapshots/80b17f4c-9f03-4ddf-bfa9-8a060479cf86 0.047s 2015-07-09 09:52:26,335 17084 DEBUG [tempest_lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '', 'Content-Type': 'application/json'} Body: None Response - Headers: {'x-compute-request-id': 'req-65af0291-3a4b-4f2c-8950-48482bc258b2', 'date': 'Thu, 09 Jul 2015 09:52:26 GMT', 'content-length': '410', 'status': '200', 'content-type': 'application/json', 'content-location': 'http://127.0.0.1:8776/v1/0c08b888ef6a4308aaf72026b5b504bb/snapshots/80b17f4c-9f03-4ddf-bfa9-8a060479cf86', 'x-openstack-request-id': 'req-65af0291-3a4b-4f2c-8950-48482bc258b2', 'connection': 'close'} Body: {"snapshot": {"status": "creating", "display_name": "tempest-snap-1703685071", "created_at": "2015-07-09T09:52:26.000000", "display_description": null, "os-extended-snapshot-attributes:progress": "0%", "volume_id": "838f4497-f2ba-4d72-a066-f857610fdb34", "os-extended-snapshot-attributes:project_id": "0c08b888ef6a4308aaf72026b5b504bb", "metadata": {}, "id": "80b17f4c-9f03-4ddf-bfa9-8a060479cf86", "size": 1}} 2015-07-09 09:52:28,360 17084 INFO [tempest_lib.common.rest_client] Request (VolumesV1SnapshotTestJSON:test_snapshots_list_details_with_params): 200 GET http://127.0.0.1:8776/v1/0c08b888ef6a4308aaf72026b5b504bb/snapshots/80b17f4c-9f03-4ddf-bfa9-8a060479cf86 0.023s 2015-07-09 09:52:28,360 17084 DEBUG [tempest_lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '', 'Content-Type': 'application/json'} Body: None Response - Headers: {'x-compute-request-id': 'req-de8ba547-fc47-44a2-be85-defb32dfb2c6', 'date': 'Thu, 09 Jul 2015 09:52:28 GMT', 'content-length': '407', 'status': '200', 'content-type': 'application/json', 'content-location': 'http://127.0.0.1:8776/v1/0c08b888ef6a4308aaf72026b5b504bb/snapshots/80b17f4c-9f03-4ddf-bfa9-8a060479cf86', 'x-openstack-request-id': 'req-de8ba547-fc47-44a2-be85-defb32dfb2c6', 'connection': 'close'} Body: {"snapshot": {"status": "error", "display_name": "tempest-snap-1703685071", "created_at": "2015-07-09T09:52:26.000000", "display_description": null, "os-extended-snapshot-attributes:progress": "0%", "volume_id": "838f4497-f2ba-4d72-a066-f857610fdb34", "os-extended-snapshot-attributes:project_id": "0c08b888ef6a4308aaf72026b5b504bb", "metadata": {}, "id": "80b17f4c-9f03-4ddf-bfa9-8a060479cf86", "size": 1}} Captured traceback: ~~~~~~~~~~~~~~~~~~~ Traceback (most recent call last): File "tempest/api/volume/test_volumes_snapshots.py", line 158, in test_snapshots_list_details_with_params snapshot = self.create_snapshot(self.volume_origin['id'], **params) File "tempest/api/volume/base.py", line 128, in create_snapshot 'available') File "tempest/services/volume/json/snapshots_client.py", line 112, in wait_for_snapshot_status value = self._get_snapshot_status(snapshot_id) File "tempest/services/volume/json/snapshots_client.py", line 85, in _get_snapshot_status snapshot_id=snapshot_id) tempest.exceptions.SnapshotBuildErrorException: Snapshot 80b17f4c-9f03-4ddf-bfa9-8a060479cf86 failed to build and is in ERROR status tempest.api.volume.test_volumes_snapshots.VolumesV1SnapshotTestJSON.test_snapshots_list_with_params[id-59f41f43-aebf-48a9-ab5d-d76340fab32b] -------------------------------------------------------------------------------------------------------------------------------------------- Captured pythonlogging: ~~~~~~~~~~~~~~~~~~~~~~~ 2015-07-09 09:52:28,967 17084 INFO [tempest_lib.common.rest_client] Request (VolumesV1SnapshotTestJSON:test_snapshots_list_with_params): 200 POST http://127.0.0.1:8776/v1/0c08b888ef6a4308aaf72026b5b504bb/snapshots 0.600s 2015-07-09 09:52:28,968 17084 DEBUG [tempest_lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '', 'Content-Type': 'application/json'} Body: {"snapshot": {"volume_id": "838f4497-f2ba-4d72-a066-f857610fdb34", "display_name": "tempest-snap-337168816"}} Response - Headers: {'x-compute-request-id': 'req-1e13e28d-ade2-4be6-9007-e33dfc40b8c5', 'date': 'Thu, 09 Jul 2015 09:52:28 GMT', 'content-length': '277', 'status': '200', 'content-type': 'application/json', 'x-openstack-request-id': 'req-1e13e28d-ade2-4be6-9007-e33dfc40b8c5', 'connection': 'close'} Body: {"snapshot": {"status": "creating", "display_name": "tempest-snap-337168816", "created_at": "2015-07-09T09:52:28.728177", "display_description": null, "volume_id": "838f4497-f2ba-4d72-a066-f857610fdb34", "metadata": {}, "id": "7686f391-8e34-449c-b4f2-9a55cd4c9075", "size": 1}} 2015-07-09 09:52:28,991 17084 INFO [tempest_lib.common.rest_client] Request (VolumesV1SnapshotTestJSON:test_snapshots_list_with_params): 200 GET http://127.0.0.1:8776/v1/0c08b888ef6a4308aaf72026b5b504bb/snapshots/7686f391-8e34-449c-b4f2-9a55cd4c9075 0.023s 2015-07-09 09:52:28,992 17084 DEBUG [tempest_lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '', 'Content-Type': 'application/json'} Body: None Response - Headers: {'x-compute-request-id': 'req-ca7d2fa8-442b-42a8-9bec-10cce1a13e92', 'date': 'Thu, 09 Jul 2015 09:52:28 GMT', 'content-length': '409', 'status': '200', 'content-type': 'application/json', 'content-location': 'http://127.0.0.1:8776/v1/0c08b888ef6a4308aaf72026b5b504bb/snapshots/7686f391-8e34-449c-b4f2-9a55cd4c9075', 'x-openstack-request-id': 'req-ca7d2fa8-442b-42a8-9bec-10cce1a13e92', 'connection': 'close'} Body: {"snapshot": {"status": "creating", "display_name": "tempest-snap-337168816", "created_at": "2015-07-09T09:52:28.000000", "display_description": null, "os-extended-snapshot-attributes:progress": "0%", "volume_id": "838f4497-f2ba-4d72-a066-f857610fdb34", "os-extended-snapshot-attributes:project_id": "0c08b888ef6a4308aaf72026b5b504bb", "metadata": {}, "id": "7686f391-8e34-449c-b4f2-9a55cd4c9075", "size": 1}} 2015-07-09 09:52:31,020 17084 INFO [tempest_lib.common.rest_client] Request (VolumesV1SnapshotTestJSON:test_snapshots_list_with_params): 200 GET http://127.0.0.1:8776/v1/0c08b888ef6a4308aaf72026b5b504bb/snapshots/7686f391-8e34-449c-b4f2-9a55cd4c9075 0.025s 2015-07-09 09:52:31,020 17084 DEBUG [tempest_lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '', 'Content-Type': 'application/json'} Body: None Response - Headers: {'x-compute-request-id': 'req-b57570e7-375d-4d97-b870-9147ed35276a', 'date': 'Thu, 09 Jul 2015 09:52:31 GMT', 'content-length': '406', 'status': '200', 'content-type': 'application/json', 'content-location': 'http://127.0.0.1:8776/v1/0c08b888ef6a4308aaf72026b5b504bb/snapshots/7686f391-8e34-449c-b4f2-9a55cd4c9075', 'x-openstack-request-id': 'req-b57570e7-375d-4d97-b870-9147ed35276a', 'connection': 'close'} Body: {"snapshot": {"status": "error", "display_name": "tempest-snap-337168816", "created_at": "2015-07-09T09:52:28.000000", "display_description": null, "os-extended-snapshot-attributes:progress": "0%", "volume_id": "838f4497-f2ba-4d72-a066-f857610fdb34", "os-extended-snapshot-attributes:project_id": "0c08b888ef6a4308aaf72026b5b504bb", "metadata": {}, "id": "7686f391-8e34-449c-b4f2-9a55cd4c9075", "size": 1}} Captured traceback: ~~~~~~~~~~~~~~~~~~~ Traceback (most recent call last): File "tempest/api/volume/test_volumes_snapshots.py", line 137, in test_snapshots_list_with_params snapshot = self.create_snapshot(self.volume_origin['id'], **params) File "tempest/api/volume/base.py", line 128, in create_snapshot 'available') File "tempest/services/volume/json/snapshots_client.py", line 112, in wait_for_snapshot_status value = self._get_snapshot_status(snapshot_id) File "tempest/services/volume/json/snapshots_client.py", line 85, in _get_snapshot_status snapshot_id=snapshot_id) tempest.exceptions.SnapshotBuildErrorException: Snapshot 7686f391-8e34-449c-b4f2-9a55cd4c9075 failed to build and is in ERROR status tempest.api.volume.test_volumes_snapshots.VolumesV1SnapshotTestJSON.test_volume_from_snapshot[id-677863d1-3142-456d-b6ac-9924f667a7f4] -------------------------------------------------------------------------------------------------------------------------------------- Captured pythonlogging: ~~~~~~~~~~~~~~~~~~~~~~~ 2015-07-09 09:52:32,100 17084 INFO [tempest_lib.common.rest_client] Request (VolumesV1SnapshotTestJSON:test_volume_from_snapshot): 200 POST http://127.0.0.1:8776/v1/0c08b888ef6a4308aaf72026b5b504bb/snapshots 1.074s 2015-07-09 09:52:32,100 17084 DEBUG [tempest_lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '', 'Content-Type': 'application/json'} Body: {"snapshot": {"volume_id": "838f4497-f2ba-4d72-a066-f857610fdb34"}} Response - Headers: {'x-compute-request-id': 'req-fca1868a-f862-415c-a793-09f42c535dd2', 'date': 'Thu, 09 Jul 2015 09:52:32 GMT', 'content-length': '257', 'status': '200', 'content-type': 'application/json', 'x-openstack-request-id': 'req-fca1868a-f862-415c-a793-09f42c535dd2', 'connection': 'close'} Body: {"snapshot": {"status": "creating", "display_name": null, "created_at": "2015-07-09T09:52:31.394165", "display_description": null, "volume_id": "838f4497-f2ba-4d72-a066-f857610fdb34", "metadata": {}, "id": "eaf8fa6d-1fd2-4a28-90f3-ebb2eeb19f8d", "size": 1}} 2015-07-09 09:52:32,124 17084 INFO [tempest_lib.common.rest_client] Request (VolumesV1SnapshotTestJSON:test_volume_from_snapshot): 200 GET http://127.0.0.1:8776/v1/0c08b888ef6a4308aaf72026b5b504bb/snapshots/eaf8fa6d-1fd2-4a28-90f3-ebb2eeb19f8d 0.024s 2015-07-09 09:52:32,125 17084 DEBUG [tempest_lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '', 'Content-Type': 'application/json'} Body: None Response - Headers: {'x-compute-request-id': 'req-28da4c96-8ed8-4533-a642-052398a144db', 'date': 'Thu, 09 Jul 2015 09:52:32 GMT', 'content-length': '389', 'status': '200', 'content-type': 'application/json', 'content-location': 'http://127.0.0.1:8776/v1/0c08b888ef6a4308aaf72026b5b504bb/snapshots/eaf8fa6d-1fd2-4a28-90f3-ebb2eeb19f8d', 'x-openstack-request-id': 'req-28da4c96-8ed8-4533-a642-052398a144db', 'connection': 'close'} Body: {"snapshot": {"status": "creating", "display_name": null, "created_at": "2015-07-09T09:52:31.000000", "display_description": null, "os-extended-snapshot-attributes:progress": "0%", "volume_id": "838f4497-f2ba-4d72-a066-f857610fdb34", "os-extended-snapshot-attributes:project_id": "0c08b888ef6a4308aaf72026b5b504bb", "metadata": {}, "id": "eaf8fa6d-1fd2-4a28-90f3-ebb2eeb19f8d", "size": 1}} 2015-07-09 09:52:34,149 17084 INFO [tempest_lib.common.rest_client] Request (VolumesV1SnapshotTestJSON:test_volume_from_snapshot): 200 GET http://127.0.0.1:8776/v1/0c08b888ef6a4308aaf72026b5b504bb/snapshots/eaf8fa6d-1fd2-4a28-90f3-ebb2eeb19f8d 0.022s 2015-07-09 09:52:34,150 17084 DEBUG [tempest_lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '', 'Content-Type': 'application/json'} Body: None Response - Headers: {'x-compute-request-id': 'req-49d003b5-802a-43be-bec9-58a5f06abf01', 'date': 'Thu, 09 Jul 2015 09:52:34 GMT', 'content-length': '386', 'status': '200', 'content-type': 'application/json', 'content-location': 'http://127.0.0.1:8776/v1/0c08b888ef6a4308aaf72026b5b504bb/snapshots/eaf8fa6d-1fd2-4a28-90f3-ebb2eeb19f8d', 'x-openstack-request-id': 'req-49d003b5-802a-43be-bec9-58a5f06abf01', 'connection': 'close'} Body: {"snapshot": {"status": "error", "display_name": null, "created_at": "2015-07-09T09:52:31.000000", "display_description": null, "os-extended-snapshot-attributes:progress": "0%", "volume_id": "838f4497-f2ba-4d72-a066-f857610fdb34", "os-extended-snapshot-attributes:project_id": "0c08b888ef6a4308aaf72026b5b504bb", "metadata": {}, "id": "eaf8fa6d-1fd2-4a28-90f3-ebb2eeb19f8d", "size": 1}} Captured traceback: ~~~~~~~~~~~~~~~~~~~ Traceback (most recent call last): File "tempest/api/volume/test_volumes_snapshots.py", line 175, in test_volume_from_snapshot snapshot = self.create_snapshot(self.volume_origin['id']) File "tempest/api/volume/base.py", line 128, in create_snapshot 'available') File "tempest/services/volume/json/snapshots_client.py", line 112, in wait_for_snapshot_status value = self._get_snapshot_status(snapshot_id) File "tempest/services/volume/json/snapshots_client.py", line 85, in _get_snapshot_status snapshot_id=snapshot_id) tempest.exceptions.SnapshotBuildErrorException: Snapshot eaf8fa6d-1fd2-4a28-90f3-ebb2eeb19f8d failed to build and is in ERROR status ====== Totals ====== Ran: 5 tests in 57.0000 sec. - Passed: 2 - Skipped: 0 - Expected Fail: 0 - Unexpected Success: 0 - Failed: 3 Sum of execute time for each test: 44.9748 sec. ============== Worker Balance ============== - Worker 0 (5 tests) => 0:00:44.994916s Slowest Tests: Test id Runtime (s) ------------------------------------------------------------------------------------------------------------------------------------------------------------ ----------- tempest.api.volume.test_volumes_snapshots.VolumesV1SnapshotTestJSON.test_snapshot_create_with_volume_in_use[compute,id-b467b54c-07a4-446d-a1cf-651dedcc3ff1] 32.172 tempest.api.volume.test_volumes_snapshots.VolumesV1SnapshotTestJSON.test_snapshot_create_get_list_update_delete[id-2a8abbe4-d871-46db-b049-c41f5af8216e] 4.530 tempest.api.volume.test_volumes_snapshots.VolumesV1SnapshotTestJSON.test_volume_from_snapshot[id-677863d1-3142-456d-b6ac-9924f667a7f4] 3.125 tempest.api.volume.test_volumes_snapshots.VolumesV1SnapshotTestJSON.test_snapshots_list_with_params[id-59f41f43-aebf-48a9-ab5d-d76340fab32b] 2.654 tempest.api.volume.test_volumes_snapshots.VolumesV1SnapshotTestJSON.test_snapshots_list_details_with_params[id-220a1022-1fcd-4a74-a7bd-6b859156cda2] 2.494 ERROR: InvocationError: '/bin/bash tools/pretty_tox.sh tempest.api.volume.test_volumes_snapshots.VolumesV1SnapshotTestJSON' ___________________________________ summary ____________________________________ ERROR: all: commands failed