yahoo-eng-team team mailing list archive

Thread
Date
[Bug 1569122] Re: return 500 when referring an image with status "SAVING"

To: yahoo-eng-team@xxxxxxxxxxxxxxxxxxx
From: Sylvain Bauza <sbauza@xxxxxxx>
Date: Tue, 12 Apr 2016 20:32:16 -0000
Reply-to: Bug 1569122 <1569122@xxxxxxxxxxxxxxxxxx>
Sender: bounces@xxxxxxxxxxxxx
Per the n-api logs, it seems the problem is related to glanceclient
rather than Nova.

** Also affects: python-glanceclient
   Importance: Undecided
       Status: New

** Changed in: nova
       Status: New => Invalid

-- 
You received this bug notification because you are a member of Yahoo!
Engineering Team, which is subscribed to OpenStack Compute (nova).
https://bugs.launchpad.net/bugs/1569122

Title:
  return 500 when referring an image with status "SAVING"

Status in OpenStack Compute (nova):
  Invalid
Status in python-glanceclient:
  New

Bug description:
  When I referred an image which is "SAVING" state by using 'nova' command,
  it returns 500 error.

  [How to reproduce]
  1. Create empty image
  $ glance image-create --name test --visibility public --disk-format ari

  +------------------+--------------------------------------+
  | Property         | Value                                |
  +------------------+--------------------------------------+
  | checksum         | None                                 |
  | container_format | None                                 |
  | created_at       | 2016-04-12T01:27:49Z                 |
  | disk_format      | ari                                  |
  | id               | 6d1ce183-e6ed-4dd0-a799-2f1b5abc6b3b |
  | min_disk         | 0                                    |
  | min_ram          | 0                                    |
  | name             | test                                 |
  | owner            | 662765a438ed40a9bc85b82e9f2a2cab     |
  | protected        | False                                |
  | size             | None                                 |
  | status           | queued                               |
  | tags             | []                                   |
  | updated_at       | 2016-04-12T01:27:49Z                 |
  | virtual_size     | None                                 |
  | visibility       | public                               |
  +------------------+--------------------------------------+

  2. Confirm the image status.
  $ nova image-list
  +--------------------------------------+------+--------+--------+
  | ID                                   | Name | Status | Server |
  +--------------------------------------+------+--------+--------+
  | 6d1ce183-e6ed-4dd0-a799-2f1b5abc6b3b | test | SAVING |        |
  +--------------------------------------+------+--------+--------+

  3. Execute 'nova image-show' or 'nova image-delete'
  (This is example of 'nova image-show')
  $ nova --debug image-show 6d1ce183-e6ed-4dd0-a799-2f1b5abc6b3b
  DEBUG (extension:157) found extension EntryPoint.parse('v2token = keystoneauth1.loading._plugins.identity.v2:Token')
  DEBUG (extension:157) found extension EntryPoint.parse('admin_token = keystoneauth1.loading._plugins.admin_token:AdminToken')
  DEBUG (extension:157) found extension EntryPoint.parse('v3oidcauthcode = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAuthorizationCode')
  DEBUG (extension:157) found extension EntryPoint.parse('v2password = keystoneauth1.loading._plugins.identity.v2:Password')
  DEBUG (extension:157) found extension EntryPoint.parse('v3password = keystoneauth1.loading._plugins.identity.v3:Password')
  DEBUG (extension:157) found extension EntryPoint.parse('v3oidcpassword = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectPassword')
  DEBUG (extension:157) found extension EntryPoint.parse('token = keystoneauth1.loading._plugins.identity.generic:Token')
  DEBUG (extension:157) found extension EntryPoint.parse('v3token = keystoneauth1.loading._plugins.identity.v3:Token')
  DEBUG (extension:157) found extension EntryPoint.parse('password = keystoneauth1.loading._plugins.identity.generic:Password')
  DEBUG (session:248) REQ: curl -g -i -X GET http://192.168.3.223:5000/v2.0 -H "Accept: application/json" -H "User-Agent: keystoneauth1/2.3.0 python-requests/2.9.1 CPython/2.7.6"
  INFO (connectionpool:207) Starting new HTTP connection (1): 192.168.3.223
  DEBUG (connectionpool:387) "GET /v2.0 HTTP/1.1" 200 339
  DEBUG (session:277) RESP: [200] Content-Length: 339 Vary: X-Auth-Token Keep-Alive: timeout=5, max=100 Server: Apache/2.4.7 (Ubuntu) Connection: Keep-Alive Date: Tue, 12 Apr 2016
  01:28:44 GMT Content-Type: application/json x-openstack-request-id: req-bc96ffbe-fe92-4fbe-931e-51c071766609
  RESP BODY: {"version": {"status": "stable", "updated": "2014-04-17T00:00:00Z", "media-types": [{"base": "application/json", "type": "application/vnd.openstack.identity-v2.0+json"
  }], "id": "v2.0", "links": [{"href": "http://192.168.3.223:5000/v2.0/";, "rel": "self"}, {"href": "http://docs.openstack.org/";, "type": "text/html", "rel": "describedby"}]}}

  DEBUG (v2:63) Making authentication request to http://192.168.3.223:5000/v2.0/tokens
  DEBUG (connectionpool:387) "POST /v2.0/tokens HTTP/1.1" 200 3569
  DEBUG (session:248) REQ: curl -g -i -X GET http://192.168.3.223:8774/v2.1/662765a438ed40a9bc85b82e9f2a2cab -H "User-Agent: python-novaclient" -H "Accept: application/json" -H "X-
  Auth-Token: {SHA1}d52cbbc7997c02255185fbf06a3c608866de4091"
  INFO (connectionpool:207) Starting new HTTP connection (1): 192.168.3.223
  DEBUG (connectionpool:387) "GET /v2.1/662765a438ed40a9bc85b82e9f2a2cab HTTP/1.1" 404 52
  DEBUG (session:277) RESP: [404] Date: Tue, 12 Apr 2016 01:28:44 GMT Connection: keep-alive Content-Type: text/plain; charset=UTF-8 Content-Length: 52 X-Compute-Request-Id: req-3c
  c87b6b-21c1-4c75-ada3-2bb7a6441267
  RESP BODY: 404 Not Found

  The resource could not be found.

  DEBUG (session:248) REQ: curl -g -i -X GET http://192.168.3.223:8774/v2.1/ -H "User-Agent: python-novaclient" -H "Accept: application/json" -H "X-Auth-Token: {SHA1}d52cbbc7997c02
  255185fbf06a3c608866de4091"
  DEBUG (connectionpool:387) "GET /v2.1/ HTTP/1.1" 200 387
  DEBUG (session:277) RESP: [200] Content-Length: 387 X-Compute-Request-Id: req-9d6fa002-6769-4c7b-b8a6-70a9e7b4a270 Vary: X-OpenStack-Nova-API-Version Connection: keep-alive X-Ope
  nstack-Nova-Api-Version: 2.1 Date: Tue, 12 Apr 2016 01:28:44 GMT Content-Type: application/json
  RESP BODY: {"version": {"status": "CURRENT", "updated": "2013-07-23T11:33:21Z", "links": [{"href": "http://192.168.3.223:8774/v2.1/";, "rel": "self"}, {"href": "http://docs.openstack.org/";, "type": "text/html", "rel": "describedby"}], "min_version": "2.1", "version": "2.26", "media-types": [{"base": "application/json", "type": "application/vnd.openstack.compute+json;version=2.1"}], "id": "v2.1"}}

  DEBUG (extension:157) found extension EntryPoint.parse('v2token = keystoneauth1.loading._plugins.identity.v2:Token')
  DEBUG (extension:157) found extension EntryPoint.parse('admin_token = keystoneauth1.loading._plugins.admin_token:AdminToken')
  DEBUG (extension:157) found extension EntryPoint.parse('v3oidcauthcode = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAuthorizationCode')
  DEBUG (extension:157) found extension EntryPoint.parse('v2password = keystoneauth1.loading._plugins.identity.v2:Password')
  DEBUG (extension:157) found extension EntryPoint.parse('v3password = keystoneauth1.loading._plugins.identity.v3:Password')
  DEBUG (extension:157) found extension EntryPoint.parse('v3oidcpassword = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectPassword')
  DEBUG (extension:157) found extension EntryPoint.parse('token = keystoneauth1.loading._plugins.identity.generic:Token')
  DEBUG (extension:157) found extension EntryPoint.parse('v3token = keystoneauth1.loading._plugins.identity.v3:Token')
  DEBUG (extension:157) found extension EntryPoint.parse('password = keystoneauth1.loading._plugins.identity.generic:Password')
  DEBUG (session:248) REQ: curl -g -i -X GET http://192.168.3.223:8774/v2.1/662765a438ed40a9bc85b82e9f2a2cab/images/6d1ce183-e6ed-4dd0-a799-2f1b5abc6b3b -H "User-Agent: python-novaclient" -H "Accept: application/json" -H "X-OpenStack-Nova-API-Version: 2.25" -H "X-Auth-Token: {SHA1}d52cbbc7997c02255185fbf06a3c608866de4091"
  DEBUG (connectionpool:387) "GET /v2.1/662765a438ed40a9bc85b82e9f2a2cab/images/6d1ce183-e6ed-4dd0-a799-2f1b5abc6b3b HTTP/1.1" 500 198
  DEBUG (session:277) RESP: [500] Content-Length: 198 X-Compute-Request-Id: req-fd6835d9-50df-40a3-993f-1f71798966cc Vary: X-OpenStack-Nova-API-Version Connection: keep-alive X-Openstack-Nova-Api-Version: 2.25 Date: Tue, 12 Apr 2016 01:28:45 GMT Content-Type: application/json; charset=UTF-8
  RESP BODY: {"computeFault": {"message": "Unexpected API Error. Please report this at http://bugs.launchpad.net/nova/ and attach the Nova API log if possible.\n<type 'exceptions.AttributeError'>", "code": 500}}

  DEBUG (shell:1082) Unexpected API Error. Please report this at http://bugs.launchpad.net/nova/ and attach the Nova API log if possible.
  <type 'exceptions.AttributeError'> (HTTP 500) (Request-ID: req-fd6835d9-50df-40a3-993f-1f71798966cc)
  Traceback (most recent call last):
    File "/usr/local/lib/python2.7/dist-packages/novaclient/shell.py", line 1080, in main
      OpenStackComputeShell().main(argv)
    File "/usr/local/lib/python2.7/dist-packages/novaclient/shell.py", line 1007, in main
      args.func(self.cs, args)
    File "/usr/local/lib/python2.7/dist-packages/novaclient/v2/shell.py", line 1304, in do_image_show
      image = _find_image(cs, args.image)
    File "/usr/local/lib/python2.7/dist-packages/novaclient/v2/shell.py", line 2139, in _find_image
      return utils.find_resource(cs.images, image)
    File "/usr/local/lib/python2.7/dist-packages/novaclient/utils.py", line 294, in find_resource
      return manager.get(tmp_id)
    File "/usr/local/lib/python2.7/dist-packages/novaclient/v2/images.py", line 55, in get
      return self._get("/images/%s" % base.getid(image), "image")
    File "/usr/local/lib/python2.7/dist-packages/novaclient/base.py", line 339, in _get
      resp, body = self.api.client.get(url)
    File "/usr/local/lib/python2.7/dist-packages/keystoneauth1/adapter.py", line 173, in get
      return self.request(url, 'GET', **kwargs)
    File "/usr/local/lib/python2.7/dist-packages/novaclient/client.py", line 94, in request
      raise exceptions.from_response(resp, body, url, method)
  ClientException: Unexpected API Error. Please report this at http://bugs.launchpad.net/nova/ and attach the Nova API log if possible.
  <type 'exceptions.AttributeError'> (HTTP 500) (Request-ID: req-fd6835d9-50df-40a3-993f-1f71798966cc)
  ERROR (ClientException): Unexpected API Error. Please report this at http://bugs.launchpad.net/nova/ and attach the Nova API log if possible.
  <type 'exceptions.AttributeError'> (HTTP 500) (Request-ID: req-fd6835d9-50df-40a3-993f-1f71798966cc)
  $ 

  [n-api.log]
  2016-04-12 10:28:45.824 ERROR nova.api.openstack.extensions [req-fd6835d9-50df-40a3-993f-1f71798966cc admin admin] Unexpected exception in API method
  2016-04-12 10:28:45.824 TRACE nova.api.openstack.extensions Traceback (most recent call last):
  2016-04-12 10:28:45.824 TRACE nova.api.openstack.extensions   File "/opt/stack/nova/nova/api/openstack/extensions.py", line 478, in wrapped
  2016-04-12 10:28:45.824 TRACE nova.api.openstack.extensions     return f(*args, **kwargs)
  2016-04-12 10:28:45.824 TRACE nova.api.openstack.extensions   File "/opt/stack/nova/nova/api/openstack/compute/images.py", line 87, in show
  2016-04-12 10:28:45.824 TRACE nova.api.openstack.extensions     image = self._image_api.get(context, id)
  2016-04-12 10:28:45.824 TRACE nova.api.openstack.extensions   File "/opt/stack/nova/nova/image/api.py", line 93, in get
  2016-04-12 10:28:45.824 TRACE nova.api.openstack.extensions     show_deleted=show_deleted)
  2016-04-12 10:28:45.824 TRACE nova.api.openstack.extensions   File "/opt/stack/nova/nova/image/glance.py", line 283, in show
  2016-04-12 10:28:45.824 TRACE nova.api.openstack.extensions     include_locations=include_locations)
  2016-04-12 10:28:45.824 TRACE nova.api.openstack.extensions   File "/opt/stack/nova/nova/image/glance.py", line 513, in _translate_from_glance
  2016-04-12 10:28:45.824 TRACE nova.api.openstack.extensions     include_locations=include_locations)
  2016-04-12 10:28:45.824 TRACE nova.api.openstack.extensions   File "/opt/stack/nova/nova/image/glance.py", line 597, in _extract_attributes
  2016-04-12 10:28:45.824 TRACE nova.api.openstack.extensions     output[attr] = getattr(image, attr) or 0
  2016-04-12 10:28:45.824 TRACE nova.api.openstack.extensions   File "/usr/local/lib/python2.7/dist-packages/glanceclient/openstack/common/apiclient/base.py", line 490, in __getattr__
  2016-04-12 10:28:45.824 TRACE nova.api.openstack.extensions     self.get()
  2016-04-12 10:28:45.824 TRACE nova.api.openstack.extensions   File "/usr/local/lib/python2.7/dist-packages/glanceclient/openstack/common/apiclient/base.py", line 512, in get
  2016-04-12 10:28:45.824 TRACE nova.api.openstack.extensions     {'x_request_id': self.manager.client.last_request_id})
  2016-04-12 10:28:45.824 TRACE nova.api.openstack.extensions AttributeError: 'HTTPClient' object has no attribute 'last_request_id'
  2016-04-12 10:28:45.824 TRACE nova.api.openstack.extensions
  2016-04-12 10:28:45.826 INFO nova.api.openstack.wsgi [req-fd6835d9-50df-40a3-993f-1f71798966cc admin admin] HTTP exception thrown: Unexpected API Error. Please report this at http://bugs.launchpad.net/nova/ and attach the Nova API log if possible.

To manage notifications about this bug go to:
https://bugs.launchpad.net/nova/+bug/1569122/+subscriptions
References

[Bug 1569122] [NEW] return 500 when referring an image with status "SAVING"
From: Yushiro FURUKAWA, 2016-04-12