Ask Your Question

Revision history [back]

click to hide/show revision 1
initial version

cinder create volume fails with http 400 image inactive

Hi,

I have deployed an openstack kilo single node environment. When I am trying to create a cinder volume from a glance image it gives an error [root@localhost ~(keystone_admin)]# cinder -d create 1 --display-name cirrvol --image-id 65b24757-163c-40a0-9285-337a246d5660 --availability-zone nova ERROR: Invalid input received: Image 65b24757-163c-40a0-9285-337a246d5660 is not active. (HTTP 400) (Request-ID: req-cad4d502-d0f4-4bd3-b9e0-c6348eadb41d)

[root@localhost ~(keystone_admin)]# glance image-list +--------------------------------------+------------+-------------+------------------+----------+--------+ | ID | Name | Disk Format | Container Format | Size | Status | +--------------------------------------+------------+-------------+------------------+----------+--------+ | 53622f06-b937-43e8-bb6b-568178a9f5d1 | cirros | qcow2 | bare | 13200896 | active | | 65b24757-163c-40a0-9285-337a246d5660 | cirrostest | qcow2 | bare | 13287936 | active | +--------------------------------------+------------+-------------+------------------+----------+--------+

When I look at the cinder debug logs from api.log file I can see the below errors captured

2016-09-26 20:05:58.212 4583 INFO eventlet.wsgi.server [-] (4583) accepted ('10.105.12.251', 40593) 2016-09-26 20:05:58.217 4583 INFO cinder.api.openstack.wsgi [-] GET http://10.105.12.251:8776/ 2016-09-26 20:05:58.217 4583 DEBUG cinder.api.openstack.wsgi [-] Empty body provided in request get_body /usr/lib/python2.7/site-packages/cinder/api/openstack/wsgi.py:870 2016-09-26 20:05:58.219 4583 INFO cinder.api.openstack.wsgi [-] http://10.105.12.251:8776/ returned with HTTP 200 2016-09-26 20:05:58.220 4583 INFO eventlet.wsgi.server [-] 10.105.12.251 - - [26/Sep/2016 20:05:58] "GET / HTTP/1.1" 200 431 0.003641 2016-09-26 20:05:58.350 4583 DEBUG keystoneclient.session [-] REQ: curl -g -i -X GET http://10.105.12.251:35357/v3/auth/tokens -H "X-Subject-Token: {SHA1}f02e708a02d76ab3e45b087f70aae3e555091cf7" -H "User-Agent: python-keystoneclient" -H "Accept: application/json" -H "X-Auth-Token: {SHA1}df1cd1b0d65ef0e4757d447d867ecd4fcc758bd6" _http_log_request /usr/lib/python2.7/site-packages/keystoneclient/session.py:195 2016-09-26 20:05:58.444 4583 DEBUG keystoneclient.session [-] RESP: [200] X-Subject-Token: {SHA1}f02e708a02d76ab3e45b087f70aae3e555091cf7 Vary: X-Auth-Token Content-Type: application/json Content-Length: 7065 X-Openstack-Request-Id: req-6a1d7f9f-f8fd-4576-a862-1f49534da83a Date: Mon, 26 Sep 2016 14:35:58 GMT Connection: keep-alive RESP BODY: {"token": {"methods": ["password", "token"], "roles": [{"id": "851784401cf24f46a5878ba2f57934a4", "name": "admin"}], "expires_at": "2016-09-26T15:35:58.000000Z", "project": {"domain": {"id": "default", "name": "Default"}, "id": "4b37935108134de1b3d1a7e9d716df42", "name": "admin"}, "catalog": "<removed>", "extras": {}, "user": {"domain": {"id": "default", "name": "Default"}, "id": "8fede32987354877ad0ef896443c9948", "name": "admin"}, "audit_ids": ["fuLG7tTXQN2blutmWL8TSA"], "issued_at": "2016-09-26T14:35:58.336796"}} _http_log_response /usr/lib/python2.7/site-packages/keystoneclient/session.py:224 2016-09-26 20:05:58.449 4583 INFO cinder.api.openstack.wsgi [req-cad4d502-d0f4-4bd3-b9e0-c6348eadb41d 8fede32987354877ad0ef896443c9948 4b37935108134de1b3d1a7e9d716df42 - - -] POST http://10.105.12.251:8776/v1/4b37935108134de1b3d1a7e9d716df42/volumes 2016-09-26 20:05:58.451 4583 DEBUG cinder.api.v1.volumes [req-cad4d502-d0f4-4bd3-b9e0-c6348eadb41d 8fede32987354877ad0ef896443c9948 4b37935108134de1b3d1a7e9d716df42 - - -] Create volume request body: {u'volume': {u'status': u'creating', u'user_id': None, u'imageRef': u'65b24757-163c-40a0-9285-337a246d5660', u'availability_zone': u'nova', 'scheduler_hints': {}, u'attach_status': u'detached', u'display_description': None, u'metadata': {}, u'source_volid': None, u'snapshot_id': None, u'display_name': u'cirrvol', u'project_id': None, u'volume_type': None, u'size': 1}} create /usr/lib/python2.7/site-packages/cinder/api/v1/volumes.py:328 2016-09-26 20:05:58.451 4583 INFO cinder.api.v1.volumes [req-cad4d502-d0f4-4bd3-b9e0-c6348eadb41d 8fede32987354877ad0ef896443c9948 4b37935108134de1b3d1a7e9d716df42 - - -] Create volume of 1 GB 2016-09-26 20:05:58.465 4583 DEBUG cinder.volume.api [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Availability zone cache updated, next update will occur around 2016-09-26 15:35:58.464714. list_availability_zones /usr/lib/python2.7/site-packages/cinder/volume/api.py:155 2016-09-26 20:05:58.469 4583 DEBUG taskflow.engines.helpers [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Looking for 'default' engine driver in 'taskflow.engines' load /usr/lib/python2.7/site-packages/taskflow/engines/helpers.py:176 2016-09-26 20:05:58.810 4583 DEBUG cinder.volume.api [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Flow 'volume_create_api' (bc4b3024-e10c-467a-8dc2-d8cdb8efe58b) transitioned into state 'RUNNING' from state 'PENDING' _flow_receiver /usr/lib/python2.7/site-packages/taskflow/listeners/logging.py:163 2016-09-26 20:05:58.811 4583 DEBUG taskflow.engines.action_engine.runner [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Exiting old state 'UNDEFINED' in response to event 'start' on_exit /usr/lib/python2.7/site-packages/taskflow/engines/action_engine/runner.py:201 2016-09-26 20:05:58.812 4583 DEBUG taskflow.engines.action_engine.runner [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Entering new state 'RESUMING' in response to event 'start' on_enter /usr/lib/python2.7/site-packages/taskflow/engines/action_engine/runner.py:205 2016-09-26 20:05:58.814 4583 DEBUG taskflow.engines.action_engine.runner [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Exiting old state 'RESUMING' in response to event 'schedule_next' on_exit /usr/lib/python2.7/site-packages/taskflow/engines/action_engine/runner.py:201 2016-09-26 20:05:58.815 4583 DEBUG taskflow.engines.action_engine.runner [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Entering new state 'SCHEDULING' in response to event 'schedule_next' on_enter /usr/lib/python2.7/site-packages/taskflow/engines/action_engine/runner.py:205 2016-09-26 20:05:58.816 4583 DEBUG cinder.volume.api [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Task 'cinder.volume.flows.api.create_volume.ExtractVolumeRequestTask;volume:create' (1b98f38f-ba41-4aee-8652-7eaf3c224d3e) transitioned into state 'RUNNING' from state 'PENDING' _task_receiver /usr/lib/python2.7/site-packages/taskflow/listeners/logging.py:202 2016-09-26 20:05:58.818 4583 DEBUG cinder.volume.flows.api.create_volume [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Validating volume '1' using validate_int _extract_size /usr/lib/python2.7/site-packages/cinder/volume/flows/api/create_volume.py:237 2016-09-26 20:05:58.836 4583 DEBUG glanceclient.common.http [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] curl -g -i -X HEAD -H 'Accept-Encoding: gzip, deflate' -H 'Accept: /' -H 'X-Auth-Token: {SHA1}f02e708a02d76ab3e45b087f70aae3e555091cf7' -H 'Connection: keep-alive' -H 'User-Agent: python-glanceclient' -H 'Content-Type: application/octet-stream' http://10.105.12.251:9292/v1/images/65b24757-163c-40a0-9285-337a246d5660 log_curl_request /usr/lib/python2.7/site-packages/glanceclient/common/http.py:123 2016-09-26 20:05:59.076 4583 DEBUG glanceclient.common.http [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] HTTP/1.1 200 OK Content-Type: text/html; charset=UTF-8 Content-Length: 0 X-Image-Meta-Id: 65b24757-163c-40a0-9285-337a246d5660 X-Image-Meta-Deleted: False X-Image-Meta-Container_format: bare X-Image-Meta-Checksum: ee1eca47dc88f4879d8a229cc70a07c6 X-Image-Meta-Protected: False X-Image-Meta-Min_disk: 0 X-Image-Meta-Created_at: 2016-09-19T14:34:41.000000 X-Image-Meta-Size: 13287936 X-Image-Meta-Status: active X-Image-Meta-Is_public: True X-Image-Meta-Min_ram: 0 X-Image-Meta-Owner: 4b37935108134de1b3d1a7e9d716df42 X-Image-Meta-Updated_at: 2016-09-19T14:34:42.000000 X-Image-Meta-Disk_format: qcow2 X-Image-Meta-Name: cirrostest Etag: ee1eca47dc88f4879d8a229cc70a07c6 X-Openstack-Request-Id: req-6e18518b-a846-4ed6-8b30-0430420c787a Date: Mon, 26 Sep 2016 14:35:59 GMT Connection: keep-alive log_http_response /usr/lib/python2.7/site-packages/glanceclient/common/http.py:136 2016-09-26 20:05:59.080 4583 DEBUG taskflow.engines.action_engine.runner [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Exiting old state 'SCHEDULING' in response to event 'wait_finished' on_exit /usr/lib/python2.7/site-packages/taskflow/engines/action_engine/runner.py:201 2016-09-26 20:05:59.080 4583 DEBUG taskflow.engines.action_engine.runner [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Entering new state 'WAITING' in response to event 'wait_finished' on_enter /usr/lib/python2.7/site-packages/taskflow/engines/action_engine/runner.py:205 2016-09-26 20:05:59.081 4583 DEBUG taskflow.engines.action_engine.runner [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Exiting old state 'WAITING' in response to event 'examine_finished' on_exit /usr/lib/python2.7/site-packages/taskflow/engines/action_engine/runner.py:201 2016-09-26 20:05:59.082 4583 DEBUG taskflow.engines.action_engine.runner [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Entering new state 'ANALYZING' in response to event 'examine_finished' on_enter /usr/lib/python2.7/site-packages/taskflow/engines/action_engine/runner.py:205 2016-09-26 20:05:59.083 4583 WARNING cinder.volume.api [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Task 'cinder.volume.flows.api.create_volume.ExtractVolumeRequestTask;volume:create' (1b98f38f-ba41-4aee-8652-7eaf3c224d3e) transitioned into state 'FAILURE' from state 'RUNNING' Failure: cinder.exception.InvalidInput: Invalid input received: Image 65b24757-163c-40a0-9285-337a246d5660 is not active. 2016-09-26 20:05:59.083 4583 DEBUG taskflow.engines.action_engine.runner [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Discarding failure 'Failure: cinder.exception.InvalidInput: Invalid input received: Image 65b24757-163c-40a0-9285-337a246d5660 is not active.' (in response to event 'executed') under completion units request during completion of node 'cinder.volume.flows.api.create_volume.ExtractVolumeRequestTask;volume:create==1.0' (intention is to REVERT) analyze /usr/lib/python2.7/site-packages/taskflow/engines/action_engine/runner.py:181 2016-09-26 20:05:59.084 4583 DEBUG taskflow.engines.action_engine.runner [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Exiting old state 'ANALYZING' in response to event 'schedule_next' on_exit /usr/lib/python2.7/site-packages/taskflow/engines/action_engine/runner.py:201 2016-09-26 20:05:59.085 4583 DEBUG taskflow.engines.action_engine.runner [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Entering new state 'SCHEDULING' in response to event 'schedule_next' on_enter /usr/lib/python2.7/site-packages/taskflow/engines/action_engine/runner.py:205 2016-09-26 20:05:59.086 4583 DEBUG cinder.volume.api [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Task 'cinder.volume.flows.api.create_volume.ExtractVolumeRequestTask;volume:create' (1b98f38f-ba41-4aee-8652-7eaf3c224d3e) transitioned into state 'REVERTING' from state 'FAILURE' _task_receiver /usr/lib/python2.7/site-packages/taskflow/listeners/logging.py:202 2016-09-26 20:05:59.087 4583 DEBUG taskflow.engines.action_engine.runner [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Exiting old state 'SCHEDULING' in response to event 'wait_finished' on_exit /usr/lib/python2.7/site-packages/taskflow/engines/action_engine/runner.py:201 2016-09-26 20:05:59.088 4583 DEBUG taskflow.engines.action_engine.runner [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Entering new state 'WAITING' in response to event 'wait_finished' on_enter /usr/lib/python2.7/site-packages/taskflow/engines/action_engine/runner.py:205 2016-09-26 20:05:59.089 4583 DEBUG taskflow.engines.action_engine.runner [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Exiting old state 'WAITING' in response to event 'examine_finished' on_exit /usr/lib/python2.7/site-packages/taskflow/engines/action_engine/runner.py:201 2016-09-26 20:05:59.089 4583 DEBUG taskflow.engines.action_engine.runner [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Entering new state 'ANALYZING' in response to event 'examine_finished' on_enter /usr/lib/python2.7/site-packages/taskflow/engines/action_engine/runner.py:205 2016-09-26 20:05:59.090 4583 WARNING cinder.volume.api [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Task 'cinder.volume.flows.api.create_volume.ExtractVolumeRequestTask;volume:create' (1b98f38f-ba41-4aee-8652-7eaf3c224d3e) transitioned into state 'REVERTED' from state 'REVERTING' 2016-09-26 20:05:59.091 4583 DEBUG taskflow.engines.action_engine.runner [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Exiting old state 'ANALYZING' in response to event 'completed' on_exit /usr/lib/python2.7/site-packages/taskflow/engines/action_engine/runner.py:201 2016-09-26 20:05:59.091 4583 DEBUG taskflow.engines.action_engine.runner [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Entering new state 'GAME_OVER' in response to event 'completed' on_enter /usr/lib/python2.7/site-packages/taskflow/engines/action_engine/runner.py:205 2016-09-26 20:05:59.093 4583 DEBUG taskflow.engines.action_engine.runner [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Exiting old state 'GAME_OVER' in response to event 'reverted' on_exit /usr/lib/python2.7/site-packages/taskflow/engines/action_engine/runner.py:201 2016-09-26 20:05:59.093 4583 DEBUG taskflow.engines.action_engine.runner [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Entering new state 'REVERTED' in response to event 'reverted' on_enter /usr/lib/python2.7/site-packages/taskflow/engines/action_engine/runner.py:205 2016-09-26 20:05:59.094 4583 WARNING cinder.volume.api [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] Flow 'volume_create_api' (bc4b3024-e10c-467a-8dc2-d8cdb8efe58b) transitioned into state 'REVERTED' from state 'RUNNING' 2016-09-26 20:05:59.095 4583 INFO cinder.api.openstack.wsgi [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] http://10.105.12.251:8776/v1/4b37935108134de1b3d1a7e9d716df42/volumes returned with HTTP 400 2016-09-26 20:05:59.097 4583 INFO eventlet.wsgi.server [req-4c8c2ee3-2426-4193-9d72-863a5c359ed2 - - - - -] 10.105.12.251 - - [26/Sep/2016 20:05:59] "POST /v1/4b37935108134de1b3d1a7e9d716df42/volumes HTTP/1.1" 400 401 0.748552

When i tried to look at the image details using glance image-show command it returns a blank output.

[root@localhost ~(keystone_admin)]# glance image-show 65b24757-163c-40a0-9285-337a246d5660 id

I am able to create a normal volume. Kindly provide help in resolving the issue.

TIA, Sudhir