Attachment 890372 Details for Bug 1091864 – glance-api log

glance-api log

glance-api.log (text/x-log), 107.53 KB, created by Yogev Rabl on 2014-04-28 08:41:20 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Yogev Rabl

Created: 2014-04-28 08:41:20 UTC

Size: 107.53 KB

patch

obsolete

>2014-04-28 10:55:06.132 11791 DEBUG glance.api.middleware.version_negotiation [-] Determining version of request: HEAD /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 Accept:  process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:46
>2014-04-28 10:55:06.133 11791 DEBUG glance.api.middleware.version_negotiation [-] Using url versioning process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:59
>2014-04-28 10:55:06.133 11791 DEBUG glance.api.middleware.version_negotiation [-] Matched version: v1 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:71
>2014-04-28 10:55:06.133 11791 DEBUG glance.api.middleware.version_negotiation [-] new path /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:72
>2014-04-28 10:55:06.133 11791 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:569
>2014-04-28 10:55:06.133 11791 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:628
>2014-04-28 10:55:06.136 11791 DEBUG iso8601.iso8601 [-] Parsed 2014-04-29T07:44:18.000000Z into {'tz_sign': None, 'second_fraction': u'000000', 'hour': u'07', 'tz_hour': None, 'month': u'04', 'timezone': u'Z', 'second': u'18', 'tz_minute': None, 'year': u'2014', 'separator': u'T', 'day': u'29', 'minute': u'44'} with default timezone <iso8601.iso8601.Utc object at 0x109a2d0> parse_date /usr/lib/python2.6/site-packages/iso8601/iso8601.py:166
>2014-04-28 10:55:06.136 11791 DEBUG iso8601.iso8601 [-] Got u'2014' for 'year' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:06.136 11791 DEBUG iso8601.iso8601 [-] Got u'04' for 'month' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:06.137 11791 DEBUG iso8601.iso8601 [-] Got u'29' for 'day' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:06.137 11791 DEBUG iso8601.iso8601 [-] Got u'07' for 'hour' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:06.137 11791 DEBUG iso8601.iso8601 [-] Got u'44' for 'minute' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:06.137 11791 DEBUG iso8601.iso8601 [-] Got u'18' for 'second' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:06.137 11791 DEBUG keystoneclient.middleware.auth_token [-] Returning cached token _cache_get /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:1034
>2014-04-28 10:55:06.137 11791 DEBUG keystoneclient.middleware.auth_token [-] Received request from user: c5993ce8cd7a4fdaab75fb9934c6dd1b with project_id : 4c787be54dfd4d4c91c79bd3d7cc37d2 and roles: admin  _build_user_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:933
>2014-04-28 10:55:06.141 11791 DEBUG routes.middleware [-] Matched HEAD /images/38901f15-7e01-4cd4-9e9b-1239e514e148 __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:100
>2014-04-28 10:55:06.141 11791 DEBUG routes.middleware [-] Route path: '/images/{id}', defaults: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:102
>2014-04-28 10:55:06.141 11791 DEBUG routes.middleware [-] Match dict: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>, 'id': u'38901f15-7e01-4cd4-9e9b-1239e514e148'} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:103
>2014-04-28 10:55:06.141 11791 DEBUG glance.api.policy [3c7d6712-2e56-42eb-8e5b-18d0a5359f3b c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Loaded policy rules: {u'context_is_admin': 'role:admin', u'default': '@', u'manage_image_cache': 'role:admin'} load_rules /usr/lib/python2.6/site-packages/glance/api/policy.py:75
>2014-04-28 10:55:06.142 11791 DEBUG glance.common.client [3c7d6712-2e56-42eb-8e5b-18d0a5359f3b c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Constructed URL: http://0.0.0.0:9191/images/38901f15-7e01-4cd4-9e9b-1239e514e148 _construct_url /usr/lib/python2.6/site-packages/glance/common/client.py:408
>2014-04-28 10:55:06.171 11791 DEBUG glance.registry.client.v1.client [3c7d6712-2e56-42eb-8e5b-18d0a5359f3b c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Registry request GET /images/38901f15-7e01-4cd4-9e9b-1239e514e148 HTTP 200 request id req-2e462b73-cc8a-4cea-be83-bf30b8369e37 do_request /usr/lib/python2.6/site-packages/glance/registry/client/v1/client.py:115
>2014-04-28 10:55:06.179 11792 DEBUG glance.api.middleware.version_negotiation [-] Determining version of request: HEAD /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 Accept:  process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:46
>2014-04-28 10:55:06.179 11792 DEBUG glance.api.middleware.version_negotiation [-] Using url versioning process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:59
>2014-04-28 10:55:06.179 11792 DEBUG glance.api.middleware.version_negotiation [-] Matched version: v1 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:71
>2014-04-28 10:55:06.179 11792 DEBUG glance.api.middleware.version_negotiation [-] new path /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:72
>2014-04-28 10:55:06.180 11792 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:569
>2014-04-28 10:55:06.180 11792 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:628
>2014-04-28 10:55:06.182 11792 DEBUG iso8601.iso8601 [-] Parsed 2014-04-29T07:44:18.000000Z into {'tz_sign': None, 'second_fraction': u'000000', 'hour': u'07', 'tz_hour': None, 'month': u'04', 'timezone': u'Z', 'second': u'18', 'tz_minute': None, 'year': u'2014', 'separator': u'T', 'day': u'29', 'minute': u'44'} with default timezone <iso8601.iso8601.Utc object at 0x109a2d0> parse_date /usr/lib/python2.6/site-packages/iso8601/iso8601.py:166
>2014-04-28 10:55:06.183 11792 DEBUG iso8601.iso8601 [-] Got u'2014' for 'year' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:06.183 11792 DEBUG iso8601.iso8601 [-] Got u'04' for 'month' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:06.183 11792 DEBUG iso8601.iso8601 [-] Got u'29' for 'day' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:06.183 11792 DEBUG iso8601.iso8601 [-] Got u'07' for 'hour' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:06.183 11792 DEBUG iso8601.iso8601 [-] Got u'44' for 'minute' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:06.184 11792 DEBUG iso8601.iso8601 [-] Got u'18' for 'second' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:06.184 11792 DEBUG keystoneclient.middleware.auth_token [-] Returning cached token _cache_get /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:1034
>2014-04-28 10:55:06.184 11792 DEBUG keystoneclient.middleware.auth_token [-] Received request from user: c5993ce8cd7a4fdaab75fb9934c6dd1b with project_id : 4c787be54dfd4d4c91c79bd3d7cc37d2 and roles: admin  _build_user_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:933
>2014-04-28 10:55:06.188 11792 DEBUG routes.middleware [-] Matched HEAD /images/38901f15-7e01-4cd4-9e9b-1239e514e148 __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:100
>2014-04-28 10:55:06.188 11792 DEBUG routes.middleware [-] Route path: '/images/{id}', defaults: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:102
>2014-04-28 10:55:06.188 11792 DEBUG routes.middleware [-] Match dict: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>, 'id': u'38901f15-7e01-4cd4-9e9b-1239e514e148'} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:103
>2014-04-28 10:55:06.189 11792 DEBUG glance.api.policy [086b65d7-5cd7-481c-a55b-91200a475aa9 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Loaded policy rules: {u'context_is_admin': 'role:admin', u'default': '@', u'manage_image_cache': 'role:admin'} load_rules /usr/lib/python2.6/site-packages/glance/api/policy.py:75
>2014-04-28 10:55:06.189 11792 DEBUG glance.common.client [086b65d7-5cd7-481c-a55b-91200a475aa9 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Constructed URL: http://0.0.0.0:9191/images/38901f15-7e01-4cd4-9e9b-1239e514e148 _construct_url /usr/lib/python2.6/site-packages/glance/common/client.py:408
>2014-04-28 10:55:06.271 11792 DEBUG glance.registry.client.v1.client [086b65d7-5cd7-481c-a55b-91200a475aa9 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Registry request GET /images/38901f15-7e01-4cd4-9e9b-1239e514e148 HTTP 200 request id req-669ad9a0-c537-43a5-bc8a-0556eb535f8b do_request /usr/lib/python2.6/site-packages/glance/registry/client/v1/client.py:115
>2014-04-28 10:55:28.788 11791 DEBUG glance.api.middleware.version_negotiation [-] Determining version of request: GET /v1/images/detail Accept:  process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:46
>2014-04-28 10:55:28.789 11791 DEBUG glance.api.middleware.version_negotiation [-] Using url versioning process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:59
>2014-04-28 10:55:28.789 11791 DEBUG glance.api.middleware.version_negotiation [-] Matched version: v1 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:71
>2014-04-28 10:55:28.789 11791 DEBUG glance.api.middleware.version_negotiation [-] new path /v1/images/detail process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:72
>2014-04-28 10:55:28.789 11791 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:569
>2014-04-28 10:55:28.789 11791 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:628
>2014-04-28 10:55:28.792 11791 INFO urllib3.connectionpool [-] Starting new HTTP connection (1): 10.35.160.133
>2014-04-28 10:55:28.834 11791 DEBUG urllib3.connectionpool [-] "GET /v2.0/tokens/revoked HTTP/1.1" 200 686 _make_request /usr/lib/python2.6/site-packages/urllib3/connectionpool.py:295
>2014-04-28 10:55:28.864 11791 DEBUG iso8601.iso8601 [-] Parsed 2014-04-29T07:55:28Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'07', 'tz_hour': None, 'month': u'04', 'timezone': u'Z', 'second': u'28', 'tz_minute': None, 'year': u'2014', 'separator': u'T', 'day': u'29', 'minute': u'55'} with default timezone <iso8601.iso8601.Utc object at 0x109a2d0> parse_date /usr/lib/python2.6/site-packages/iso8601/iso8601.py:166
>2014-04-28 10:55:28.865 11791 DEBUG iso8601.iso8601 [-] Got u'2014' for 'year' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:28.865 11791 DEBUG iso8601.iso8601 [-] Got u'04' for 'month' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:28.865 11791 DEBUG iso8601.iso8601 [-] Got u'29' for 'day' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:28.866 11791 DEBUG iso8601.iso8601 [-] Got u'07' for 'hour' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:28.866 11791 DEBUG iso8601.iso8601 [-] Got u'55' for 'minute' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:28.866 11791 DEBUG iso8601.iso8601 [-] Got u'28' for 'second' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:28.866 11791 DEBUG keystoneclient.middleware.auth_token [-] Storing token in cache _cache_put /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:1144
>2014-04-28 10:55:28.868 11791 DEBUG keystoneclient.middleware.auth_token [-] Received request from user: c5993ce8cd7a4fdaab75fb9934c6dd1b with project_id : 4c787be54dfd4d4c91c79bd3d7cc37d2 and roles: admin  _build_user_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:933
>2014-04-28 10:55:28.871 11791 DEBUG routes.middleware [-] Matched GET /images/detail __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:100
>2014-04-28 10:55:28.871 11791 DEBUG routes.middleware [-] Route path: '/images/detail', defaults: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x274da50>} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:102
>2014-04-28 10:55:28.872 11791 DEBUG routes.middleware [-] Match dict: {'action': u'detail', 'controller': <glance.common.wsgi.Resource object at 0x274da50>} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:103
>2014-04-28 10:55:28.872 11791 DEBUG glance.api.policy [050b8c27-7cce-4ed8-a1ae-4e49d5161160 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Loaded policy rules: {u'context_is_admin': 'role:admin', u'default': '@', u'manage_image_cache': 'role:admin'} load_rules /usr/lib/python2.6/site-packages/glance/api/policy.py:75
>2014-04-28 10:55:28.873 11791 DEBUG glance.common.client [050b8c27-7cce-4ed8-a1ae-4e49d5161160 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Constructed URL: http://0.0.0.0:9191/images/detail?limit=10 _construct_url /usr/lib/python2.6/site-packages/glance/common/client.py:408
>2014-04-28 10:55:28.940 11791 DEBUG glance.registry.client.v1.client [050b8c27-7cce-4ed8-a1ae-4e49d5161160 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Registry request GET /images/detail HTTP 200 request id req-4b1574a0-7233-462d-8814-57e469b7bdbb do_request /usr/lib/python2.6/site-packages/glance/registry/client/v1/client.py:115
>2014-04-28 10:55:36.591 11794 DEBUG glance.api.middleware.version_negotiation [-] Determining version of request: HEAD /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 Accept:  process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:46
>2014-04-28 10:55:36.592 11794 DEBUG glance.api.middleware.version_negotiation [-] Using url versioning process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:59
>2014-04-28 10:55:36.592 11794 DEBUG glance.api.middleware.version_negotiation [-] Matched version: v1 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:71
>2014-04-28 10:55:36.592 11794 DEBUG glance.api.middleware.version_negotiation [-] new path /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:72
>2014-04-28 10:55:36.592 11794 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:569
>2014-04-28 10:55:36.592 11794 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:628
>2014-04-28 10:55:36.594 11794 INFO urllib3.connectionpool [-] Starting new HTTP connection (1): 10.35.160.133
>2014-04-28 10:55:36.621 11794 DEBUG urllib3.connectionpool [-] "GET /v2.0/tokens/1be99997670bea9e928ebab8e897217b HTTP/1.1" 200 7833 _make_request /usr/lib/python2.6/site-packages/urllib3/connectionpool.py:295
>2014-04-28 10:55:36.624 11794 DEBUG iso8601.iso8601 [-] Parsed 2014-04-29T07:44:18Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'07', 'tz_hour': None, 'month': u'04', 'timezone': u'Z', 'second': u'18', 'tz_minute': None, 'year': u'2014', 'separator': u'T', 'day': u'29', 'minute': u'44'} with default timezone <iso8601.iso8601.Utc object at 0x109a2d0> parse_date /usr/lib/python2.6/site-packages/iso8601/iso8601.py:166
>2014-04-28 10:55:36.624 11794 DEBUG iso8601.iso8601 [-] Got u'2014' for 'year' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:36.624 11794 DEBUG iso8601.iso8601 [-] Got u'04' for 'month' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:36.625 11794 DEBUG iso8601.iso8601 [-] Got u'29' for 'day' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:36.625 11794 DEBUG iso8601.iso8601 [-] Got u'07' for 'hour' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:36.625 11794 DEBUG iso8601.iso8601 [-] Got u'44' for 'minute' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:36.625 11794 DEBUG iso8601.iso8601 [-] Got u'18' for 'second' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:36.625 11794 DEBUG keystoneclient.middleware.auth_token [-] Storing token in cache _cache_put /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:1144
>2014-04-28 10:55:36.627 11794 DEBUG keystoneclient.middleware.auth_token [-] Received request from user: c5993ce8cd7a4fdaab75fb9934c6dd1b with project_id : 4c787be54dfd4d4c91c79bd3d7cc37d2 and roles: admin  _build_user_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:933
>2014-04-28 10:55:36.630 11794 DEBUG routes.middleware [-] Matched HEAD /images/38901f15-7e01-4cd4-9e9b-1239e514e148 __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:100
>2014-04-28 10:55:36.630 11794 DEBUG routes.middleware [-] Route path: '/images/{id}', defaults: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:102
>2014-04-28 10:55:36.630 11794 DEBUG routes.middleware [-] Match dict: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>, 'id': u'38901f15-7e01-4cd4-9e9b-1239e514e148'} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:103
>2014-04-28 10:55:36.631 11794 DEBUG glance.api.policy [f861bdb9-bc01-4404-8454-0ab2700e18c1 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Loaded policy rules: {u'context_is_admin': 'role:admin', u'default': '@', u'manage_image_cache': 'role:admin'} load_rules /usr/lib/python2.6/site-packages/glance/api/policy.py:75
>2014-04-28 10:55:36.631 11794 DEBUG glance.common.client [f861bdb9-bc01-4404-8454-0ab2700e18c1 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Constructed URL: http://0.0.0.0:9191/images/38901f15-7e01-4cd4-9e9b-1239e514e148 _construct_url /usr/lib/python2.6/site-packages/glance/common/client.py:408
>2014-04-28 10:55:36.653 11794 DEBUG glance.registry.client.v1.client [f861bdb9-bc01-4404-8454-0ab2700e18c1 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Registry request GET /images/38901f15-7e01-4cd4-9e9b-1239e514e148 HTTP 200 request id req-12a5bb8c-d904-4228-9f24-7090df4e7b43 do_request /usr/lib/python2.6/site-packages/glance/registry/client/v1/client.py:115
>2014-04-28 10:55:36.661 11791 DEBUG glance.api.middleware.version_negotiation [-] Determining version of request: HEAD /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 Accept:  process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:46
>2014-04-28 10:55:36.661 11791 DEBUG glance.api.middleware.version_negotiation [-] Using url versioning process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:59
>2014-04-28 10:55:36.661 11791 DEBUG glance.api.middleware.version_negotiation [-] Matched version: v1 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:71
>2014-04-28 10:55:36.662 11791 DEBUG glance.api.middleware.version_negotiation [-] new path /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:72
>2014-04-28 10:55:36.662 11791 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:569
>2014-04-28 10:55:36.662 11791 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:628
>2014-04-28 10:55:36.663 11791 INFO urllib3.connectionpool [-] Starting new HTTP connection (1): 10.35.160.133
>2014-04-28 10:55:36.689 11791 DEBUG urllib3.connectionpool [-] "GET /v2.0/tokens/1be99997670bea9e928ebab8e897217b HTTP/1.1" 200 7833 _make_request /usr/lib/python2.6/site-packages/urllib3/connectionpool.py:295
>2014-04-28 10:55:36.692 11791 DEBUG iso8601.iso8601 [-] Parsed 2014-04-29T07:44:18Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'07', 'tz_hour': None, 'month': u'04', 'timezone': u'Z', 'second': u'18', 'tz_minute': None, 'year': u'2014', 'separator': u'T', 'day': u'29', 'minute': u'44'} with default timezone <iso8601.iso8601.Utc object at 0x109a2d0> parse_date /usr/lib/python2.6/site-packages/iso8601/iso8601.py:166
>2014-04-28 10:55:36.693 11791 DEBUG iso8601.iso8601 [-] Got u'2014' for 'year' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:36.693 11791 DEBUG iso8601.iso8601 [-] Got u'04' for 'month' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:36.693 11791 DEBUG iso8601.iso8601 [-] Got u'29' for 'day' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:36.693 11791 DEBUG iso8601.iso8601 [-] Got u'07' for 'hour' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:36.694 11791 DEBUG iso8601.iso8601 [-] Got u'44' for 'minute' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:36.694 11791 DEBUG iso8601.iso8601 [-] Got u'18' for 'second' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:55:36.694 11791 DEBUG keystoneclient.middleware.auth_token [-] Storing token in cache _cache_put /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:1144
>2014-04-28 10:55:36.695 11791 DEBUG keystoneclient.middleware.auth_token [-] Received request from user: c5993ce8cd7a4fdaab75fb9934c6dd1b with project_id : 4c787be54dfd4d4c91c79bd3d7cc37d2 and roles: admin  _build_user_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:933
>2014-04-28 10:55:36.699 11791 DEBUG routes.middleware [-] Matched HEAD /images/38901f15-7e01-4cd4-9e9b-1239e514e148 __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:100
>2014-04-28 10:55:36.699 11791 DEBUG routes.middleware [-] Route path: '/images/{id}', defaults: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:102
>2014-04-28 10:55:36.699 11791 DEBUG routes.middleware [-] Match dict: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>, 'id': u'38901f15-7e01-4cd4-9e9b-1239e514e148'} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:103
>2014-04-28 10:55:36.699 11791 DEBUG glance.api.policy [2a4a2559-7067-453d-bf66-2454ee53194b c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Loaded policy rules: {u'context_is_admin': 'role:admin', u'default': '@', u'manage_image_cache': 'role:admin'} load_rules /usr/lib/python2.6/site-packages/glance/api/policy.py:75
>2014-04-28 10:55:36.700 11791 DEBUG glance.common.client [2a4a2559-7067-453d-bf66-2454ee53194b c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Constructed URL: http://0.0.0.0:9191/images/38901f15-7e01-4cd4-9e9b-1239e514e148 _construct_url /usr/lib/python2.6/site-packages/glance/common/client.py:408
>2014-04-28 10:55:36.721 11791 DEBUG glance.registry.client.v1.client [2a4a2559-7067-453d-bf66-2454ee53194b c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Registry request GET /images/38901f15-7e01-4cd4-9e9b-1239e514e148 HTTP 200 request id req-aa39d5db-bbe0-47fd-b013-7711ca641d8f do_request /usr/lib/python2.6/site-packages/glance/registry/client/v1/client.py:115
>2014-04-28 10:56:03.781 11793 DEBUG glance.api.middleware.version_negotiation [-] Determining version of request: HEAD /v1/images/ Accept:  process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:46
>2014-04-28 10:56:03.781 11793 DEBUG glance.api.middleware.version_negotiation [-] Using url versioning process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:59
>2014-04-28 10:56:03.782 11793 DEBUG glance.api.middleware.version_negotiation [-] Matched version: v1 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:71
>2014-04-28 10:56:03.782 11793 DEBUG glance.api.middleware.version_negotiation [-] new path /v1/images/ process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:72
>2014-04-28 10:56:03.782 11793 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:569
>2014-04-28 10:56:03.782 11793 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:628
>2014-04-28 10:56:03.784 11793 INFO urllib3.connectionpool [-] Starting new HTTP connection (1): 10.35.160.133
>2014-04-28 10:56:03.828 11793 DEBUG urllib3.connectionpool [-] "GET /v2.0/tokens/revoked HTTP/1.1" 200 686 _make_request /usr/lib/python2.6/site-packages/urllib3/connectionpool.py:295
>2014-04-28 10:56:03.852 11793 DEBUG iso8601.iso8601 [-] Parsed 2014-04-29T07:56:03Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'07', 'tz_hour': None, 'month': u'04', 'timezone': u'Z', 'second': u'03', 'tz_minute': None, 'year': u'2014', 'separator': u'T', 'day': u'29', 'minute': u'56'} with default timezone <iso8601.iso8601.Utc object at 0x109a2d0> parse_date /usr/lib/python2.6/site-packages/iso8601/iso8601.py:166
>2014-04-28 10:56:03.853 11793 DEBUG iso8601.iso8601 [-] Got u'2014' for 'year' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:03.853 11793 DEBUG iso8601.iso8601 [-] Got u'04' for 'month' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:03.853 11793 DEBUG iso8601.iso8601 [-] Got u'29' for 'day' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:03.853 11793 DEBUG iso8601.iso8601 [-] Got u'07' for 'hour' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:03.853 11793 DEBUG iso8601.iso8601 [-] Got u'56' for 'minute' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:03.853 11793 DEBUG iso8601.iso8601 [-] Got u'03' for 'second' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:03.854 11793 DEBUG keystoneclient.middleware.auth_token [-] Storing token in cache _cache_put /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:1144
>2014-04-28 10:56:03.855 11793 DEBUG keystoneclient.middleware.auth_token [-] Received request from user: c5993ce8cd7a4fdaab75fb9934c6dd1b with project_id : 4c787be54dfd4d4c91c79bd3d7cc37d2 and roles: admin  _build_user_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:933
>2014-04-28 10:56:03.859 11793 DEBUG routes.middleware [-] No route matched for HEAD /images/ __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:97
>2014-04-28 10:56:03.865 11791 DEBUG glance.api.middleware.version_negotiation [-] Determining version of request: POST /v1/images Accept:  process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:46
>2014-04-28 10:56:03.865 11791 DEBUG glance.api.middleware.version_negotiation [-] Using url versioning process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:59
>2014-04-28 10:56:03.865 11791 DEBUG glance.api.middleware.version_negotiation [-] Matched version: v1 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:71
>2014-04-28 10:56:03.865 11791 DEBUG glance.api.middleware.version_negotiation [-] new path /v1/images process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:72
>2014-04-28 10:56:03.866 11791 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:569
>2014-04-28 10:56:03.866 11791 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:628
>2014-04-28 10:56:03.882 11791 DEBUG iso8601.iso8601 [-] Parsed 2014-04-29T07:56:03Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'07', 'tz_hour': None, 'month': u'04', 'timezone': u'Z', 'second': u'03', 'tz_minute': None, 'year': u'2014', 'separator': u'T', 'day': u'29', 'minute': u'56'} with default timezone <iso8601.iso8601.Utc object at 0x109a2d0> parse_date /usr/lib/python2.6/site-packages/iso8601/iso8601.py:166
>2014-04-28 10:56:03.883 11791 DEBUG iso8601.iso8601 [-] Got u'2014' for 'year' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:03.883 11791 DEBUG iso8601.iso8601 [-] Got u'04' for 'month' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:03.883 11791 DEBUG iso8601.iso8601 [-] Got u'29' for 'day' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:03.883 11791 DEBUG iso8601.iso8601 [-] Got u'07' for 'hour' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:03.884 11791 DEBUG iso8601.iso8601 [-] Got u'56' for 'minute' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:03.884 11791 DEBUG iso8601.iso8601 [-] Got u'03' for 'second' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:03.884 11791 DEBUG keystoneclient.middleware.auth_token [-] Storing token in cache _cache_put /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:1144
>2014-04-28 10:56:03.885 11791 DEBUG keystoneclient.middleware.auth_token [-] Received request from user: c5993ce8cd7a4fdaab75fb9934c6dd1b with project_id : 4c787be54dfd4d4c91c79bd3d7cc37d2 and roles: admin  _build_user_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:933
>2014-04-28 10:56:03.889 11791 DEBUG routes.middleware [-] Matched POST /images __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:100
>2014-04-28 10:56:03.889 11791 DEBUG routes.middleware [-] Route path: '/images', defaults: {'action': u'create', 'controller': <glance.common.wsgi.Resource object at 0x274da50>} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:102
>2014-04-28 10:56:03.889 11791 DEBUG routes.middleware [-] Match dict: {'action': u'create', 'controller': <glance.common.wsgi.Resource object at 0x274da50>} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:103
>2014-04-28 10:56:03.890 11791 DEBUG glance.api.policy [a0c2f5e2-3804-4a31-8bcb-d867e2208d44 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Loaded policy rules: {u'context_is_admin': 'role:admin', u'default': '@', u'manage_image_cache': 'role:admin'} load_rules /usr/lib/python2.6/site-packages/glance/api/policy.py:75
>2014-04-28 10:56:03.891 11791 DEBUG glance.registry.client.v1.api [a0c2f5e2-3804-4a31-8bcb-d867e2208d44 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Adding image metadata... add_image_metadata /usr/lib/python2.6/site-packages/glance/registry/client/v1/api.py:161
>2014-04-28 10:56:03.891 11791 DEBUG glance.common.client [a0c2f5e2-3804-4a31-8bcb-d867e2208d44 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Constructed URL: http://0.0.0.0:9191/images _construct_url /usr/lib/python2.6/site-packages/glance/common/client.py:408
>2014-04-28 10:56:04.053 11791 DEBUG glance.registry.client.v1.client [a0c2f5e2-3804-4a31-8bcb-d867e2208d44 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Registry request POST /images HTTP 200 request id req-aa071544-7d43-4c91-90c2-39896ac88cfa do_request /usr/lib/python2.6/site-packages/glance/registry/client/v1/client.py:115
>2014-04-28 10:56:04.054 11791 DEBUG qpid.messaging.io.raw [-] OPEN[2c379e0]: 10.35.160.133:5672 connect /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:530
>2014-04-28 10:56:04.055 11791 DEBUG qpid.messaging.io.raw [-] SENT[2c379e0]: 'AMQP\x01\x01\x00\n' writeable /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:480
>2014-04-28 10:56:04.056 11791 DEBUG qpid.messaging.io.raw [-] READ[2c379e0]: 'AMQP\x01\x01\x00\n\x0f\x00\x00~\x00\x00\x00\x00\x00\x00\x00\x00\x01\x01\x07\x00\x00\x00\x00?\x00\x00\x00\x01\x13qpid.federation_tag\x95\x00$df473773-2f07-490c-8eb6-ead5f321af5f\x00\x00\x00\x17\x95\x00\x00\x00\x02\x00\tANONYMOUS\x00\x05PLAIN\x00\x00\x00\x0c\x95\x00\x00\x00\x01\x00\x05en_US' readable /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:416
>2014-04-28 10:56:04.056 11791 DEBUG qpid.messaging.io.ops [-] RCVD[2c379e0]: ConnectionStart(server_properties={u'qpid.federation_tag': u'df473773-2f07-490c-8eb6-ead5f321af5f'}, mechanisms=[u'ANONYMOUS', u'PLAIN'], locales=[u'en_US']) write /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:654
>2014-04-28 10:56:04.057 11791 DEBUG qpid.messaging.io.ops [-] SENT[2c379e0]: ConnectionStartOk(client_properties={'product': 'qpid python client', 'qpid.client_ppid': 11784, 'qpid.client_process': 'glance-api', 'platform': 'posix', 'qpid.client_pid': 11791, 'version': 'development'}, mechanism='ANONYMOUS', response='anonymous@cougar01.scl.lab.tlv.redhat.com') write_op /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:686
>2014-04-28 10:56:04.057 11791 DEBUG qpid.messaging.io.raw [-] SENT[2c379e0]: '\x0f\x00\x00\xe7\x00\x00\x00\x00\x00\x00\x00\x00\x01\x02\x07\x00\x00\x00\x00\x9c\x00\x00\x00\x06\x07product\x95\x00\x12qpid python client\x10qpid.client_ppid1\x00\x00\x00\x00\x00\x00.\x08\x13qpid.client_process\x95\x00\nglance-api\x08platform\x95\x00\x05posix\x0fqpid.client_pid1\x00\x00\x00\x00\x00\x00.\x0f\x07version\x95\x00\x0bdevelopment\tANONYMOUS\x00\x00\x00)anonymous@cougar01.scl.lab.tlv.redhat.com' writeable /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:480
>2014-04-28 10:56:04.058 11791 DEBUG qpid.messaging.io.raw [-] READ[2c379e0]: '\x0f\x00\x00\x18\x00\x00\x00\x00\x00\x00\x00\x00\x01\x05\x0f\x00\x7f\xff\xff\xff\x00\x00\x00x' readable /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:416
>2014-04-28 10:56:04.058 11791 DEBUG qpid.messaging.io.ops [-] RCVD[2c379e0]: ConnectionTune(channel_max=32767, max_frame_size=65535, heartbeat_min=0, heartbeat_max=120) write /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:654
>2014-04-28 10:56:04.058 11791 DEBUG qpid.messaging.io.ops [-] SENT[2c379e0]: ConnectionTuneOk(channel_max=32767, heartbeat=60) write_op /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:686
>2014-04-28 10:56:04.059 11791 DEBUG qpid.messaging.io.ops [-] SENT[2c379e0]: ConnectionOpen() write_op /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:686
>2014-04-28 10:56:04.059 11791 DEBUG qpid.messaging.io.raw [-] SENT[2c379e0]: '\x0f\x00\x00\x14\x00\x00\x00\x00\x00\x00\x00\x00\x01\x06\x05\x00\x7f\xff\x00<\x0f\x00\x00\x10\x00\x00\x00\x00\x00\x00\x00\x00\x01\x07\x00\x00' writeable /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:480
>2014-04-28 10:56:04.060 11791 DEBUG qpid.messaging.io.raw [-] READ[2c379e0]: '\x0f\x00\x006\x00\x00\x00\x00\x00\x00\x00\x00\x01\x08\x01\x00\x00\x00\x00"\x95\x00\x00\x00\x01\x00\x1bamqp:tcp:10.35.160.133:5672' readable /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:416
>2014-04-28 10:56:04.060 11791 DEBUG qpid.messaging.io.ops [-] RCVD[2c379e0]: ConnectionOpenOk(known_hosts=[u'amqp:tcp:10.35.160.133:5672']) write /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:654
>2014-04-28 10:56:04.060 11791 INFO glance.notifier.notify_qpid [a0c2f5e2-3804-4a31-8bcb-d867e2208d44 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Connected to AMQP server on 10.35.160.133:5672
>2014-04-28 10:56:04.061 11791 DEBUG qpid.messaging.io.ops [-] SENT[2c379e0]: SessionAttach(name='8a830718-1c14-44f5-b24b-2196458cc96d:0') write_op /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:686
>2014-04-28 10:56:04.061 11791 DEBUG qpid.messaging.io.ops [-] SENT[2c379e0]: SessionCommandPoint(command_id=serial(0), command_offset=0) write_op /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:686
>2014-04-28 10:56:04.062 11791 DEBUG qpid.messaging.io.ops [-] SENT[2c379e0]: ExchangeQuery(name='glance', id=serial(0), sync=True) write_op /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:686
>2014-04-28 10:56:04.062 11791 DEBUG qpid.messaging.io.ops [-] SENT[2c379e0]: QueueQuery(queue='glance', id=serial(1), sync=True) write_op /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:686
>2014-04-28 10:56:04.063 11791 DEBUG qpid.messaging.io.raw [-] SENT[2c379e0]: '\x0f\x00\x008\x00\x00\x00\x00\x00\x00\x00\x00\x02\x01\x01\x00\x00&8a830718-1c14-44f5-b24b-2196458cc96d:0\x0f\x00\x00\x1c\x00\x00\x00\x00\x00\x00\x00\x00\x02\x07\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0f\x01\x00\x19\x00\x01\x00\x00\x00\x00\x00\x00\x07\x03\x01\x01\x01\x00\x06glance\x0f\x01\x00\x19\x00\x01\x00\x00\x00\x00\x00\x00\x08\x04\x01\x01\x01\x00\x06glance' writeable /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:480
>2014-04-28 10:56:04.064 11791 DEBUG qpid.messaging.io.raw [-] READ[2c379e0]: '\x0f\x00\x008\x00\x00\x00\x00\x00\x00\x00\x00\x02\x02\x01\x00\x00&8a830718-1c14-44f5-b24b-2196458cc96d:0\x0f\x00\x00\x1c\x00\x00\x00\x00\x00\x00\x00\x00\x02\x07\x03\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0f\x01\x00,\x00\x01\x00\x00\x00\x00\x00\x00\x03\x02\x01\x00\x03\x00\x00\x00\x00\x00\x00\x00\x00\x12\x07\x01\t\x00\x05topic\x00\x00\x00\x04\x00\x00\x00\x00\x0f\x00\x00\x1a\x00\x00\x00\x00\x00\x00\x00\x00\x02\n\x01\x00\x00\x08\x00\x00\x00\x00\x00\x00\x00\x00\x0f\x01\x00\x1e\x00\x01\x00\x00\x00\x00\x00\x00\x03\x02\x01\x00\x03\x00\x00\x00\x00\x01\x00\x00\x00\x04\x08\x01\x00\x00\x0f\x00\x00\x1a\x00\x00\x00\x00\x00\x00\x00\x00\x02\n\x01\x00\x00\x08\x00\x00\x00\x00\x00\x00\x00\x01' readable /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:416
>2014-04-28 10:56:04.064 11791 DEBUG qpid.messaging.io.ops [-] RCVD[2c379e0]: SessionAttached(name='8a830718-1c14-44f5-b24b-2196458cc96d:0') write /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:654
>2014-04-28 10:56:04.065 11791 DEBUG qpid.messaging.io.ops [-] RCVD[2c379e0]: SessionCommandPoint(command_id=serial(0), command_offset=0) write /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:654
>2014-04-28 10:56:04.065 11791 DEBUG qpid.messaging.io.ops [-] RCVD[2c379e0]: ExecutionResult(command_id=serial(0), value=ExchangeQueryResult(type=u'topic', arguments={}), id=serial(0)) write /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:654
>2014-04-28 10:56:04.065 11791 DEBUG qpid.messaging.io.ops [-] RCVD[2c379e0]: SessionCompleted(commands=[0-0]) write /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:654
>2014-04-28 10:56:04.065 11791 DEBUG qpid.messaging.io.ops [-] RCVD[2c379e0]: ExecutionResult(command_id=serial(1), value=QueueQueryResult(), id=serial(1)) write /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:654
>2014-04-28 10:56:04.065 11791 DEBUG qpid.messaging.io.ops [-] RCVD[2c379e0]: SessionCompleted(commands=[0-1]) write /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:654
>2014-04-28 10:56:04.067 11791 DEBUG qpid.messaging.io.ops [-] SENT[2c379e0]: MessageTransfer(destination='glance', id=serial(2), sync=True, headers=(DeliveryProperties(routing_key='notifications.info'), MessageProperties(content_type='amqp/map', application_headers={'qpid.subject': 'notifications.info'})), payload='\x00\x00\x03\xa4\x00\x00\x00\x06\nevent_type\x95\x00\x0cimage.create\ttimestamp\x95\x00\x1a2014-04-28 07:56:04.054242\nmessage_id\x95\x00$212ea895-e80b-4bb1-bc60-4e18e87845b0\x08priority\x95\x00\x04INFO\x0cpublisher_id\x95\x00\x1fcougar01.scl.lab.tlv.redhat.com\x07payload\xa8\x00\x00\x02\xe1\x00\x00\x00\x11\x06status\x95\x00\x06queued\ncreated_at\x95\x00\x132014-04-28T07:56:03\x04name\x95\x00\x05backs\x07deleted\x08\x00\x10container_format\xf0\x07min_ram1\x00\x00\x00\x00\x00\x00\x00\x00\x0bdisk_format\xf0\nupdated_at\x95\x00\x132014-04-28T07:56:03\nproperties\xa8\x00\x00\x01\x93\x00\x00\x00\x0e\rinstance_uuid\x95\x00$5ec93890-7e61-49b9-950e-4d3fe65819ab\x12instance_type_swap\x95\x00\x010\x17instance_type_memory_mb\x95\x00\x042048\x07user_id\x95\x00 c5993ce8cd7a4fdaab75fb9934c6dd1b\nimage_type\x95\x00\x06backup\x12instance_type_name\x95\x00\x08m1.small\x10instance_type_id\x95\x00\x015\x1ainstance_type_ephemeral_gb\x95\x00\x010\x19instance_type_rxtx_factor\x95\x00\x011\x15instance_type_root_gb\x95\x00\x0220\x0bbackup_type\x95\x00\x08snapshot\x16instance_type_flavorid\x95\x00\x012\x13instance_type_vcpus\x95\x00\x011\x0ebase_image_ref\xf0\x08min_disk1\x00\x00\x00\x00\x00\x00\x00\x14\tprotected\x08\x00\x08checksum\xf0\x05owner\x95\x00 4c787be54dfd4d4c91c79bd3d7cc37d2\tis_public\x08\x00\ndeleted_at\xf0\x02id\x95\x00$206f7f58-f973-453c-bbf5-2051673c3e5b\x04size1\x00\x00\x00\x00\x00\x00\x00\x00') write_op /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:686
>2014-04-28 10:56:04.068 11791 DEBUG qpid.messaging [-] SENT[2b9e2d8]: Message(properties={'qpid.subject': 'notifications.info'}, content={'event_type': 'image.create', 'timestamp': '2014-04-28 07:56:04.054242', 'message_id': '212ea895-e80b-4bb1-bc60-4e18e87845b0', 'priority': 'INFO', 'publisher_id': 'cougar01.scl.lab.tlv.redhat.com', 'payload': {u'status': u'queued', u'created_at': u'2014-04-28T07:56:03', u'name': u'backs', u'deleted': False, u'container_format': None, u'min_ram': 0, u'disk_format': None, u'updated_at': u'2014-04-28T07:56:03', u'properties': {u'instance_uuid': u'5ec93890-7e61-49b9-950e-4d3fe65819ab', u'instance_type_swap': u'0', u'instance_type_memory_mb': u'2048', u'user_id': u'c5993ce8cd7a4fdaab75fb9934c6dd1b', u'image_type': u'backup', u'instance_type_name': u'm1.small', u'instance_type_id': u'5', u'instance_type_ephemeral_gb': u'0', u'instance_type_rxtx_factor': u'1', u'instance_type_root_gb': u'20', u'backup_type': u'snapshot', u'instance_type_flavorid': u'2', u'instance_type_vcpus': u'1', u'base_image_ref': None}, u'min_disk': 20, u'protected': False, u'checksum': None, u'owner': u'4c787be54dfd4d4c91c79bd3d7cc37d2', u'is_public': False, u'deleted_at': None, u'id': u'206f7f58-f973-453c-bbf5-2051673c3e5b', u'size': 0}}) send /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:1280
>2014-04-28 10:56:04.069 11791 DEBUG qpid.messaging.io.raw [-] SENT[2c379e0]: '\x0b\x01\x00\x19\x00\x01\x00\x00\x00\x00\x00\x00\x04\x01\x01\x01\x01\x00\x06glance\x03\x02\x00b\x00\x01\x00\x00\x00\x00\x00\x00\x00\x00\x00\x17\x04\x01\x00\x02\x12notifications.info\x00\x00\x007\x04\x03\x10\x01\x08amqp/map\x00\x00\x00&\x00\x00\x00\x01\x0cqpid.subject\x95\x00\x12notifications.info\x07\x03\x03\xb4\x00\x01\x00\x00\x00\x00\x00\x00\x00\x00\x03\xa4\x00\x00\x00\x06\nevent_type\x95\x00\x0cimage.create\ttimestamp\x95\x00\x1a2014-04-28 07:56:04.054242\nmessage_id\x95\x00$212ea895-e80b-4bb1-bc60-4e18e87845b0\x08priority\x95\x00\x04INFO\x0cpublisher_id\x95\x00\x1fcougar01.scl.lab.tlv.redhat.com\x07payload\xa8\x00\x00\x02\xe1\x00\x00\x00\x11\x06status\x95\x00\x06queued\ncreated_at\x95\x00\x132014-04-28T07:56:03\x04name\x95\x00\x05backs\x07deleted\x08\x00\x10container_format\xf0\x07min_ram1\x00\x00\x00\x00\x00\x00\x00\x00\x0bdisk_format\xf0\nupdated_at\x95\x00\x132014-04-28T07:56:03\nproperties\xa8\x00\x00\x01\x93\x00\x00\x00\x0e\rinstance_uuid\x95\x00$5ec93890-7e61-49b9-950e-4d3fe65819ab\x12instance_type_swap\x95\x00\x010\x17instance_type_memory_mb\x95\x00\x042048\x07user_id\x95\x00 c5993ce8cd7a4fdaab75fb9934c6dd1b\nimage_type\x95\x00\x06backup\x12instance_type_name\x95\x00\x08m1.small\x10instance_type_id\x95\x00\x015\x1ainstance_type_ephemeral_gb\x95\x00\x010\x19instance_type_rxtx_factor\x95\x00\x011\x15instance_type_root_gb\x95\x00\x0220\x0bbackup_type\x95\x00\x08snapshot\x16instance_type_flavorid\x95\x00\x012\x13instance_type_vcpus\x95\x00\x011\x0ebase_image_ref\xf0\x08min_disk1\x00\x00\x00\x00\x00\x00\x00\x14\tprotected\x08\x00\x08checksum\xf0\x05owner\x95\x00 4c787be54dfd4d4c91c79bd3d7cc37d2\tis_public\x08\x00\ndeleted_at\xf0\x02id\x95\x00$206f7f58-f973-453c-bbf5-2051673c3e5b\x04size1\x00\x00\x00\x00\x00\x00\x00\x00' writeable /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:480
>2014-04-28 10:56:04.069 11791 DEBUG qpid.messaging.io.raw [-] READ[2c379e0]: '\x0f\x00\x00\x1a\x00\x00\x00\x00\x00\x00\x00\x00\x02\n\x01\x00\x00\x08\x00\x00\x00\x00\x00\x00\x00\x02' readable /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:416
>2014-04-28 10:56:04.069 11791 DEBUG qpid.messaging.io.ops [-] RCVD[2c379e0]: SessionCompleted(commands=[0-2]) write /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:654
>2014-04-28 10:56:04.070 11791 DEBUG qpid.messaging [-] RACK[2b9e2d8]: Message(properties={'qpid.subject': 'notifications.info'}, content={'event_type': 'image.create', 'timestamp': '2014-04-28 07:56:04.054242', 'message_id': '212ea895-e80b-4bb1-bc60-4e18e87845b0', 'priority': 'INFO', 'publisher_id': 'cougar01.scl.lab.tlv.redhat.com', 'payload': {u'status': u'queued', u'created_at': u'2014-04-28T07:56:03', u'name': u'backs', u'deleted': False, u'container_format': None, u'min_ram': 0, u'disk_format': None, u'updated_at': u'2014-04-28T07:56:03', u'properties': {u'instance_uuid': u'5ec93890-7e61-49b9-950e-4d3fe65819ab', u'instance_type_swap': u'0', u'instance_type_memory_mb': u'2048', u'user_id': u'c5993ce8cd7a4fdaab75fb9934c6dd1b', u'image_type': u'backup', u'instance_type_name': u'm1.small', u'instance_type_id': u'5', u'instance_type_ephemeral_gb': u'0', u'instance_type_rxtx_factor': u'1', u'instance_type_root_gb': u'20', u'backup_type': u'snapshot', u'instance_type_flavorid': u'2', u'instance_type_vcpus': u'1', u'base_image_ref': None}, u'min_disk': 20, u'protected': False, u'checksum': None, u'owner': u'4c787be54dfd4d4c91c79bd3d7cc37d2', u'is_public': False, u'deleted_at': None, u'id': u'206f7f58-f973-453c-bbf5-2051673c3e5b', u'size': 0}}) msg_acked /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:1269
>2014-04-28 10:56:04.070 11791 DEBUG qpid.messaging.io.ops [-] SENT[2c379e0]: SessionDetach(name='8a830718-1c14-44f5-b24b-2196458cc96d:0') write_op /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:686
>2014-04-28 10:56:04.071 11791 DEBUG qpid.messaging.io.raw [-] SENT[2c379e0]: '\x0f\x00\x008\x00\x00\x00\x00\x00\x00\x00\x00\x02\x03\x01\x00\x00&8a830718-1c14-44f5-b24b-2196458cc96d:0' writeable /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:480
>2014-04-28 10:56:04.071 11791 DEBUG qpid.messaging.io.raw [-] READ[2c379e0]: '\x0f\x00\x009\x00\x00\x00\x00\x00\x00\x00\x00\x02\x04\x03\x00\x00&8a830718-1c14-44f5-b24b-2196458cc96d:0\x00' readable /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:416
>2014-04-28 10:56:04.072 11791 DEBUG qpid.messaging.io.ops [-] RCVD[2c379e0]: SessionDetached(name='8a830718-1c14-44f5-b24b-2196458cc96d:0', code=0) write /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:654
>2014-04-28 10:56:04.072 11791 DEBUG qpid.messaging.io.ops [-] SENT[2c379e0]: ConnectionClose(reply_code=200) write_op /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:686
>2014-04-28 10:56:04.073 11791 DEBUG qpid.messaging.io.raw [-] SENT[2c379e0]: '\x0f\x00\x00\x12\x00\x00\x00\x00\x00\x00\x00\x00\x01\x0b\x01\x00\x00\xc8' writeable /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:480
>2014-04-28 10:56:04.073 11791 DEBUG qpid.messaging.io.raw [-] READ[2c379e0]: '\x0f\x00\x00\x10\x00\x00\x00\x00\x00\x00\x00\x00\x01\x0c\x00\x00' readable /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:416
>2014-04-28 10:56:04.073 11791 DEBUG qpid.messaging.io.ops [-] RCVD[2c379e0]: ConnectionCloseOk() write /usr/lib/python2.6/site-packages/qpid/messaging/driver.py:654
>2014-04-28 10:56:04.476 11792 DEBUG glance.api.middleware.version_negotiation [-] Determining version of request: HEAD /v1/images/ Accept:  process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:46
>2014-04-28 10:56:04.476 11792 DEBUG glance.api.middleware.version_negotiation [-] Using url versioning process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:59
>2014-04-28 10:56:04.476 11792 DEBUG glance.api.middleware.version_negotiation [-] Matched version: v1 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:71
>2014-04-28 10:56:04.476 11792 DEBUG glance.api.middleware.version_negotiation [-] new path /v1/images/ process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:72
>2014-04-28 10:56:04.477 11792 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:569
>2014-04-28 10:56:04.477 11792 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:628
>2014-04-28 10:56:04.478 11792 INFO urllib3.connectionpool [-] Starting new HTTP connection (1): 10.35.160.133
>2014-04-28 10:56:04.519 11792 DEBUG urllib3.connectionpool [-] "GET /v2.0/tokens/revoked HTTP/1.1" 200 686 _make_request /usr/lib/python2.6/site-packages/urllib3/connectionpool.py:295
>2014-04-28 10:56:04.543 11792 DEBUG iso8601.iso8601 [-] Parsed 2014-04-29T07:56:03Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'07', 'tz_hour': None, 'month': u'04', 'timezone': u'Z', 'second': u'03', 'tz_minute': None, 'year': u'2014', 'separator': u'T', 'day': u'29', 'minute': u'56'} with default timezone <iso8601.iso8601.Utc object at 0x109a2d0> parse_date /usr/lib/python2.6/site-packages/iso8601/iso8601.py:166
>2014-04-28 10:56:04.544 11792 DEBUG iso8601.iso8601 [-] Got u'2014' for 'year' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:04.544 11792 DEBUG iso8601.iso8601 [-] Got u'04' for 'month' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:04.544 11792 DEBUG iso8601.iso8601 [-] Got u'29' for 'day' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:04.544 11792 DEBUG iso8601.iso8601 [-] Got u'07' for 'hour' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:04.544 11792 DEBUG iso8601.iso8601 [-] Got u'56' for 'minute' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:04.545 11792 DEBUG iso8601.iso8601 [-] Got u'03' for 'second' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:04.545 11792 DEBUG keystoneclient.middleware.auth_token [-] Storing token in cache _cache_put /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:1144
>2014-04-28 10:56:04.546 11792 DEBUG keystoneclient.middleware.auth_token [-] Received request from user: c5993ce8cd7a4fdaab75fb9934c6dd1b with project_id : 4c787be54dfd4d4c91c79bd3d7cc37d2 and roles: admin  _build_user_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:933
>2014-04-28 10:56:04.550 11792 DEBUG routes.middleware [-] No route matched for HEAD /images/ __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:97
>2014-04-28 10:56:04.556 11794 DEBUG glance.api.middleware.version_negotiation [-] Determining version of request: HEAD /v1/images/206f7f58-f973-453c-bbf5-2051673c3e5b Accept:  process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:46
>2014-04-28 10:56:04.556 11794 DEBUG glance.api.middleware.version_negotiation [-] Using url versioning process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:59
>2014-04-28 10:56:04.556 11794 DEBUG glance.api.middleware.version_negotiation [-] Matched version: v1 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:71
>2014-04-28 10:56:04.557 11794 DEBUG glance.api.middleware.version_negotiation [-] new path /v1/images/206f7f58-f973-453c-bbf5-2051673c3e5b process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:72
>2014-04-28 10:56:04.557 11794 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:569
>2014-04-28 10:56:04.557 11794 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:628
>2014-04-28 10:56:04.558 11794 INFO urllib3.connectionpool [-] Starting new HTTP connection (1): 10.35.160.133
>2014-04-28 10:56:04.603 11794 DEBUG urllib3.connectionpool [-] "GET /v2.0/tokens/revoked HTTP/1.1" 200 686 _make_request /usr/lib/python2.6/site-packages/urllib3/connectionpool.py:295
>2014-04-28 10:56:04.629 11794 DEBUG iso8601.iso8601 [-] Parsed 2014-04-29T07:56:03Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'07', 'tz_hour': None, 'month': u'04', 'timezone': u'Z', 'second': u'03', 'tz_minute': None, 'year': u'2014', 'separator': u'T', 'day': u'29', 'minute': u'56'} with default timezone <iso8601.iso8601.Utc object at 0x109a2d0> parse_date /usr/lib/python2.6/site-packages/iso8601/iso8601.py:166
>2014-04-28 10:56:04.629 11794 DEBUG iso8601.iso8601 [-] Got u'2014' for 'year' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:04.630 11794 DEBUG iso8601.iso8601 [-] Got u'04' for 'month' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:04.630 11794 DEBUG iso8601.iso8601 [-] Got u'29' for 'day' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:04.630 11794 DEBUG iso8601.iso8601 [-] Got u'07' for 'hour' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:04.630 11794 DEBUG iso8601.iso8601 [-] Got u'56' for 'minute' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:04.630 11794 DEBUG iso8601.iso8601 [-] Got u'03' for 'second' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:04.631 11794 DEBUG keystoneclient.middleware.auth_token [-] Storing token in cache _cache_put /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:1144
>2014-04-28 10:56:04.632 11794 DEBUG keystoneclient.middleware.auth_token [-] Received request from user: c5993ce8cd7a4fdaab75fb9934c6dd1b with project_id : 4c787be54dfd4d4c91c79bd3d7cc37d2 and roles: admin  _build_user_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:933
>2014-04-28 10:56:04.636 11794 DEBUG routes.middleware [-] Matched HEAD /images/206f7f58-f973-453c-bbf5-2051673c3e5b __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:100
>2014-04-28 10:56:04.636 11794 DEBUG routes.middleware [-] Route path: '/images/{id}', defaults: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:102
>2014-04-28 10:56:04.636 11794 DEBUG routes.middleware [-] Match dict: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>, 'id': u'206f7f58-f973-453c-bbf5-2051673c3e5b'} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:103
>2014-04-28 10:56:04.636 11794 DEBUG glance.api.policy [f2608513-695d-40fe-9c15-aa1649d72f85 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Loaded policy rules: {u'context_is_admin': 'role:admin', u'default': '@', u'manage_image_cache': 'role:admin'} load_rules /usr/lib/python2.6/site-packages/glance/api/policy.py:75
>2014-04-28 10:56:04.637 11794 DEBUG glance.common.client [f2608513-695d-40fe-9c15-aa1649d72f85 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Constructed URL: http://0.0.0.0:9191/images/206f7f58-f973-453c-bbf5-2051673c3e5b _construct_url /usr/lib/python2.6/site-packages/glance/common/client.py:408
>2014-04-28 10:56:04.659 11794 DEBUG glance.registry.client.v1.client [f2608513-695d-40fe-9c15-aa1649d72f85 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Registry request GET /images/206f7f58-f973-453c-bbf5-2051673c3e5b HTTP 200 request id req-e42fb296-daf8-4533-a85e-d08e3f9fa5a7 do_request /usr/lib/python2.6/site-packages/glance/registry/client/v1/client.py:115
>2014-04-28 10:56:04.668 11792 DEBUG glance.api.middleware.version_negotiation [-] Determining version of request: HEAD /v1/images/206f7f58-f973-453c-bbf5-2051673c3e5b Accept:  process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:46
>2014-04-28 10:56:04.668 11792 DEBUG glance.api.middleware.version_negotiation [-] Using url versioning process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:59
>2014-04-28 10:56:04.669 11792 DEBUG glance.api.middleware.version_negotiation [-] Matched version: v1 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:71
>2014-04-28 10:56:04.669 11792 DEBUG glance.api.middleware.version_negotiation [-] new path /v1/images/206f7f58-f973-453c-bbf5-2051673c3e5b process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:72
>2014-04-28 10:56:04.669 11792 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:569
>2014-04-28 10:56:04.669 11792 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:628
>2014-04-28 10:56:04.672 11792 DEBUG iso8601.iso8601 [-] Parsed 2014-04-29T07:56:03.000000Z into {'tz_sign': None, 'second_fraction': u'000000', 'hour': u'07', 'tz_hour': None, 'month': u'04', 'timezone': u'Z', 'second': u'03', 'tz_minute': None, 'year': u'2014', 'separator': u'T', 'day': u'29', 'minute': u'56'} with default timezone <iso8601.iso8601.Utc object at 0x109a2d0> parse_date /usr/lib/python2.6/site-packages/iso8601/iso8601.py:166
>2014-04-28 10:56:04.672 11792 DEBUG iso8601.iso8601 [-] Got u'2014' for 'year' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:04.672 11792 DEBUG iso8601.iso8601 [-] Got u'04' for 'month' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:04.672 11792 DEBUG iso8601.iso8601 [-] Got u'29' for 'day' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:04.673 11792 DEBUG iso8601.iso8601 [-] Got u'07' for 'hour' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:04.673 11792 DEBUG iso8601.iso8601 [-] Got u'56' for 'minute' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:04.673 11792 DEBUG iso8601.iso8601 [-] Got u'03' for 'second' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:04.673 11792 DEBUG keystoneclient.middleware.auth_token [-] Returning cached token _cache_get /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:1034
>2014-04-28 10:56:04.673 11792 DEBUG keystoneclient.middleware.auth_token [-] Received request from user: c5993ce8cd7a4fdaab75fb9934c6dd1b with project_id : 4c787be54dfd4d4c91c79bd3d7cc37d2 and roles: admin  _build_user_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:933
>2014-04-28 10:56:04.677 11792 DEBUG routes.middleware [-] Matched HEAD /images/206f7f58-f973-453c-bbf5-2051673c3e5b __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:100
>2014-04-28 10:56:04.677 11792 DEBUG routes.middleware [-] Route path: '/images/{id}', defaults: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:102
>2014-04-28 10:56:04.677 11792 DEBUG routes.middleware [-] Match dict: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>, 'id': u'206f7f58-f973-453c-bbf5-2051673c3e5b'} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:103
>2014-04-28 10:56:04.677 11792 DEBUG glance.api.policy [81329136-93ab-42a6-804b-77d08bb2fe21 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Loaded policy rules: {u'context_is_admin': 'role:admin', u'default': '@', u'manage_image_cache': 'role:admin'} load_rules /usr/lib/python2.6/site-packages/glance/api/policy.py:75
>2014-04-28 10:56:04.678 11792 DEBUG glance.common.client [81329136-93ab-42a6-804b-77d08bb2fe21 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Constructed URL: http://0.0.0.0:9191/images/206f7f58-f973-453c-bbf5-2051673c3e5b _construct_url /usr/lib/python2.6/site-packages/glance/common/client.py:408
>2014-04-28 10:56:04.704 11792 DEBUG glance.registry.client.v1.client [81329136-93ab-42a6-804b-77d08bb2fe21 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Registry request GET /images/206f7f58-f973-453c-bbf5-2051673c3e5b HTTP 200 request id req-c04c8744-1c34-4ea8-ae40-b32acd2128c6 do_request /usr/lib/python2.6/site-packages/glance/registry/client/v1/client.py:115
>2014-04-28 10:56:06.135 11791 DEBUG glance.api.middleware.version_negotiation [-] Determining version of request: HEAD /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 Accept:  process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:46
>2014-04-28 10:56:06.135 11791 DEBUG glance.api.middleware.version_negotiation [-] Using url versioning process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:59
>2014-04-28 10:56:06.136 11791 DEBUG glance.api.middleware.version_negotiation [-] Matched version: v1 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:71
>2014-04-28 10:56:06.136 11791 DEBUG glance.api.middleware.version_negotiation [-] new path /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:72
>2014-04-28 10:56:06.136 11791 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:569
>2014-04-28 10:56:06.137 11791 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:628
>2014-04-28 10:56:06.139 11791 DEBUG iso8601.iso8601 [-] Parsed 2014-04-29T07:44:18.000000Z into {'tz_sign': None, 'second_fraction': u'000000', 'hour': u'07', 'tz_hour': None, 'month': u'04', 'timezone': u'Z', 'second': u'18', 'tz_minute': None, 'year': u'2014', 'separator': u'T', 'day': u'29', 'minute': u'44'} with default timezone <iso8601.iso8601.Utc object at 0x109a2d0> parse_date /usr/lib/python2.6/site-packages/iso8601/iso8601.py:166
>2014-04-28 10:56:06.140 11791 DEBUG iso8601.iso8601 [-] Got u'2014' for 'year' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:06.140 11791 DEBUG iso8601.iso8601 [-] Got u'04' for 'month' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:06.140 11791 DEBUG iso8601.iso8601 [-] Got u'29' for 'day' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:06.140 11791 DEBUG iso8601.iso8601 [-] Got u'07' for 'hour' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:06.140 11791 DEBUG iso8601.iso8601 [-] Got u'44' for 'minute' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:06.140 11791 DEBUG iso8601.iso8601 [-] Got u'18' for 'second' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:06.141 11791 DEBUG keystoneclient.middleware.auth_token [-] Returning cached token _cache_get /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:1034
>2014-04-28 10:56:06.141 11791 DEBUG keystoneclient.middleware.auth_token [-] Received request from user: c5993ce8cd7a4fdaab75fb9934c6dd1b with project_id : 4c787be54dfd4d4c91c79bd3d7cc37d2 and roles: admin  _build_user_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:933
>2014-04-28 10:56:06.144 11791 DEBUG routes.middleware [-] Matched HEAD /images/38901f15-7e01-4cd4-9e9b-1239e514e148 __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:100
>2014-04-28 10:56:06.144 11791 DEBUG routes.middleware [-] Route path: '/images/{id}', defaults: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:102
>2014-04-28 10:56:06.144 11791 DEBUG routes.middleware [-] Match dict: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>, 'id': u'38901f15-7e01-4cd4-9e9b-1239e514e148'} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:103
>2014-04-28 10:56:06.145 11791 DEBUG glance.api.policy [661d04fc-2e5a-43d8-99f1-e453426ded26 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Loaded policy rules: {u'context_is_admin': 'role:admin', u'default': '@', u'manage_image_cache': 'role:admin'} load_rules /usr/lib/python2.6/site-packages/glance/api/policy.py:75
>2014-04-28 10:56:06.145 11791 DEBUG glance.common.client [661d04fc-2e5a-43d8-99f1-e453426ded26 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Constructed URL: http://0.0.0.0:9191/images/38901f15-7e01-4cd4-9e9b-1239e514e148 _construct_url /usr/lib/python2.6/site-packages/glance/common/client.py:408
>2014-04-28 10:56:06.166 11791 DEBUG glance.registry.client.v1.client [661d04fc-2e5a-43d8-99f1-e453426ded26 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Registry request GET /images/38901f15-7e01-4cd4-9e9b-1239e514e148 HTTP 200 request id req-04c7234c-413a-4d37-92ac-5fa663a6ef0f do_request /usr/lib/python2.6/site-packages/glance/registry/client/v1/client.py:115
>2014-04-28 10:56:06.174 11792 DEBUG glance.api.middleware.version_negotiation [-] Determining version of request: HEAD /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 Accept:  process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:46
>2014-04-28 10:56:06.174 11792 DEBUG glance.api.middleware.version_negotiation [-] Using url versioning process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:59
>2014-04-28 10:56:06.175 11792 DEBUG glance.api.middleware.version_negotiation [-] Matched version: v1 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:71
>2014-04-28 10:56:06.175 11792 DEBUG glance.api.middleware.version_negotiation [-] new path /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:72
>2014-04-28 10:56:06.175 11792 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:569
>2014-04-28 10:56:06.175 11792 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:628
>2014-04-28 10:56:06.178 11792 DEBUG iso8601.iso8601 [-] Parsed 2014-04-29T07:44:18.000000Z into {'tz_sign': None, 'second_fraction': u'000000', 'hour': u'07', 'tz_hour': None, 'month': u'04', 'timezone': u'Z', 'second': u'18', 'tz_minute': None, 'year': u'2014', 'separator': u'T', 'day': u'29', 'minute': u'44'} with default timezone <iso8601.iso8601.Utc object at 0x109a2d0> parse_date /usr/lib/python2.6/site-packages/iso8601/iso8601.py:166
>2014-04-28 10:56:06.178 11792 DEBUG iso8601.iso8601 [-] Got u'2014' for 'year' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:06.178 11792 DEBUG iso8601.iso8601 [-] Got u'04' for 'month' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:06.179 11792 DEBUG iso8601.iso8601 [-] Got u'29' for 'day' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:06.179 11792 DEBUG iso8601.iso8601 [-] Got u'07' for 'hour' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:06.179 11792 DEBUG iso8601.iso8601 [-] Got u'44' for 'minute' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:06.179 11792 DEBUG iso8601.iso8601 [-] Got u'18' for 'second' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:06.179 11792 DEBUG keystoneclient.middleware.auth_token [-] Returning cached token _cache_get /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:1034
>2014-04-28 10:56:06.179 11792 DEBUG keystoneclient.middleware.auth_token [-] Received request from user: c5993ce8cd7a4fdaab75fb9934c6dd1b with project_id : 4c787be54dfd4d4c91c79bd3d7cc37d2 and roles: admin  _build_user_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:933
>2014-04-28 10:56:06.183 11792 DEBUG routes.middleware [-] Matched HEAD /images/38901f15-7e01-4cd4-9e9b-1239e514e148 __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:100
>2014-04-28 10:56:06.183 11792 DEBUG routes.middleware [-] Route path: '/images/{id}', defaults: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:102
>2014-04-28 10:56:06.183 11792 DEBUG routes.middleware [-] Match dict: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>, 'id': u'38901f15-7e01-4cd4-9e9b-1239e514e148'} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:103
>2014-04-28 10:56:06.183 11792 DEBUG glance.api.policy [330ab98a-3b41-41b6-9590-8391dec4ccd9 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Loaded policy rules: {u'context_is_admin': 'role:admin', u'default': '@', u'manage_image_cache': 'role:admin'} load_rules /usr/lib/python2.6/site-packages/glance/api/policy.py:75
>2014-04-28 10:56:06.184 11792 DEBUG glance.common.client [330ab98a-3b41-41b6-9590-8391dec4ccd9 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Constructed URL: http://0.0.0.0:9191/images/38901f15-7e01-4cd4-9e9b-1239e514e148 _construct_url /usr/lib/python2.6/site-packages/glance/common/client.py:408
>2014-04-28 10:56:06.205 11792 DEBUG glance.registry.client.v1.client [330ab98a-3b41-41b6-9590-8391dec4ccd9 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Registry request GET /images/38901f15-7e01-4cd4-9e9b-1239e514e148 HTTP 200 request id req-e19f5e5e-d8b2-4248-9f91-ba31502ab10a do_request /usr/lib/python2.6/site-packages/glance/registry/client/v1/client.py:115
>2014-04-28 10:56:36.136 11794 DEBUG glance.api.middleware.version_negotiation [-] Determining version of request: HEAD /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 Accept:  process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:46
>2014-04-28 10:56:36.136 11794 DEBUG glance.api.middleware.version_negotiation [-] Using url versioning process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:59
>2014-04-28 10:56:36.136 11794 DEBUG glance.api.middleware.version_negotiation [-] Matched version: v1 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:71
>2014-04-28 10:56:36.137 11794 DEBUG glance.api.middleware.version_negotiation [-] new path /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:72
>2014-04-28 10:56:36.137 11794 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:569
>2014-04-28 10:56:36.137 11794 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:628
>2014-04-28 10:56:36.140 11794 DEBUG iso8601.iso8601 [-] Parsed 2014-04-29T07:44:18.000000Z into {'tz_sign': None, 'second_fraction': u'000000', 'hour': u'07', 'tz_hour': None, 'month': u'04', 'timezone': u'Z', 'second': u'18', 'tz_minute': None, 'year': u'2014', 'separator': u'T', 'day': u'29', 'minute': u'44'} with default timezone <iso8601.iso8601.Utc object at 0x109a2d0> parse_date /usr/lib/python2.6/site-packages/iso8601/iso8601.py:166
>2014-04-28 10:56:36.140 11794 DEBUG iso8601.iso8601 [-] Got u'2014' for 'year' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:36.140 11794 DEBUG iso8601.iso8601 [-] Got u'04' for 'month' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:36.140 11794 DEBUG iso8601.iso8601 [-] Got u'29' for 'day' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:36.141 11794 DEBUG iso8601.iso8601 [-] Got u'07' for 'hour' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:36.141 11794 DEBUG iso8601.iso8601 [-] Got u'44' for 'minute' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:36.141 11794 DEBUG iso8601.iso8601 [-] Got u'18' for 'second' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:36.141 11794 DEBUG keystoneclient.middleware.auth_token [-] Returning cached token _cache_get /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:1034
>2014-04-28 10:56:36.141 11794 DEBUG keystoneclient.middleware.auth_token [-] Received request from user: c5993ce8cd7a4fdaab75fb9934c6dd1b with project_id : 4c787be54dfd4d4c91c79bd3d7cc37d2 and roles: admin  _build_user_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:933
>2014-04-28 10:56:36.145 11794 DEBUG routes.middleware [-] Matched HEAD /images/38901f15-7e01-4cd4-9e9b-1239e514e148 __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:100
>2014-04-28 10:56:36.145 11794 DEBUG routes.middleware [-] Route path: '/images/{id}', defaults: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:102
>2014-04-28 10:56:36.145 11794 DEBUG routes.middleware [-] Match dict: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>, 'id': u'38901f15-7e01-4cd4-9e9b-1239e514e148'} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:103
>2014-04-28 10:56:36.146 11794 DEBUG glance.api.policy [2b02e63e-473e-44bc-b42a-4e4f46d5d30a c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Loaded policy rules: {u'context_is_admin': 'role:admin', u'default': '@', u'manage_image_cache': 'role:admin'} load_rules /usr/lib/python2.6/site-packages/glance/api/policy.py:75
>2014-04-28 10:56:36.146 11794 DEBUG glance.common.client [2b02e63e-473e-44bc-b42a-4e4f46d5d30a c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Constructed URL: http://0.0.0.0:9191/images/38901f15-7e01-4cd4-9e9b-1239e514e148 _construct_url /usr/lib/python2.6/site-packages/glance/common/client.py:408
>2014-04-28 10:56:36.168 11794 DEBUG glance.registry.client.v1.client [2b02e63e-473e-44bc-b42a-4e4f46d5d30a c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Registry request GET /images/38901f15-7e01-4cd4-9e9b-1239e514e148 HTTP 200 request id req-4a342673-821a-4195-8c1a-733236eb056f do_request /usr/lib/python2.6/site-packages/glance/registry/client/v1/client.py:115
>2014-04-28 10:56:36.176 11793 DEBUG glance.api.middleware.version_negotiation [-] Determining version of request: HEAD /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 Accept:  process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:46
>2014-04-28 10:56:36.176 11793 DEBUG glance.api.middleware.version_negotiation [-] Using url versioning process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:59
>2014-04-28 10:56:36.176 11793 DEBUG glance.api.middleware.version_negotiation [-] Matched version: v1 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:71
>2014-04-28 10:56:36.177 11793 DEBUG glance.api.middleware.version_negotiation [-] new path /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:72
>2014-04-28 10:56:36.177 11793 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:569
>2014-04-28 10:56:36.177 11793 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:628
>2014-04-28 10:56:36.179 11793 INFO urllib3.connectionpool [-] Starting new HTTP connection (1): 10.35.160.133
>2014-04-28 10:56:36.207 11793 DEBUG urllib3.connectionpool [-] "GET /v2.0/tokens/1be99997670bea9e928ebab8e897217b HTTP/1.1" 200 7833 _make_request /usr/lib/python2.6/site-packages/urllib3/connectionpool.py:295
>2014-04-28 10:56:36.210 11793 DEBUG iso8601.iso8601 [-] Parsed 2014-04-29T07:44:18Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'07', 'tz_hour': None, 'month': u'04', 'timezone': u'Z', 'second': u'18', 'tz_minute': None, 'year': u'2014', 'separator': u'T', 'day': u'29', 'minute': u'44'} with default timezone <iso8601.iso8601.Utc object at 0x109a2d0> parse_date /usr/lib/python2.6/site-packages/iso8601/iso8601.py:166
>2014-04-28 10:56:36.210 11793 DEBUG iso8601.iso8601 [-] Got u'2014' for 'year' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:36.211 11793 DEBUG iso8601.iso8601 [-] Got u'04' for 'month' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:36.211 11793 DEBUG iso8601.iso8601 [-] Got u'29' for 'day' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:36.211 11793 DEBUG iso8601.iso8601 [-] Got u'07' for 'hour' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:36.211 11793 DEBUG iso8601.iso8601 [-] Got u'44' for 'minute' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:36.211 11793 DEBUG iso8601.iso8601 [-] Got u'18' for 'second' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:56:36.212 11793 DEBUG keystoneclient.middleware.auth_token [-] Storing token in cache _cache_put /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:1144
>2014-04-28 10:56:36.213 11793 DEBUG keystoneclient.middleware.auth_token [-] Received request from user: c5993ce8cd7a4fdaab75fb9934c6dd1b with project_id : 4c787be54dfd4d4c91c79bd3d7cc37d2 and roles: admin  _build_user_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:933
>2014-04-28 10:56:36.216 11793 DEBUG routes.middleware [-] Matched HEAD /images/38901f15-7e01-4cd4-9e9b-1239e514e148 __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:100
>2014-04-28 10:56:36.216 11793 DEBUG routes.middleware [-] Route path: '/images/{id}', defaults: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:102
>2014-04-28 10:56:36.217 11793 DEBUG routes.middleware [-] Match dict: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>, 'id': u'38901f15-7e01-4cd4-9e9b-1239e514e148'} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:103
>2014-04-28 10:56:36.217 11793 DEBUG glance.api.policy [df680b26-c559-4d45-9be6-796867aca161 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Loaded policy rules: {u'context_is_admin': 'role:admin', u'default': '@', u'manage_image_cache': 'role:admin'} load_rules /usr/lib/python2.6/site-packages/glance/api/policy.py:75
>2014-04-28 10:56:36.217 11793 DEBUG glance.common.client [df680b26-c559-4d45-9be6-796867aca161 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Constructed URL: http://0.0.0.0:9191/images/38901f15-7e01-4cd4-9e9b-1239e514e148 _construct_url /usr/lib/python2.6/site-packages/glance/common/client.py:408
>2014-04-28 10:56:36.239 11793 DEBUG glance.registry.client.v1.client [df680b26-c559-4d45-9be6-796867aca161 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Registry request GET /images/38901f15-7e01-4cd4-9e9b-1239e514e148 HTTP 200 request id req-40e9481f-5f6c-484e-91af-258926c35b48 do_request /usr/lib/python2.6/site-packages/glance/registry/client/v1/client.py:115
>2014-04-28 10:57:06.138 11796 DEBUG glance.api.middleware.version_negotiation [-] Determining version of request: HEAD /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 Accept:  process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:46
>2014-04-28 10:57:06.139 11796 DEBUG glance.api.middleware.version_negotiation [-] Using url versioning process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:59
>2014-04-28 10:57:06.139 11796 DEBUG glance.api.middleware.version_negotiation [-] Matched version: v1 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:71
>2014-04-28 10:57:06.139 11796 DEBUG glance.api.middleware.version_negotiation [-] new path /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:72
>2014-04-28 10:57:06.139 11796 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:569
>2014-04-28 10:57:06.139 11796 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:628
>2014-04-28 10:57:06.141 11796 INFO urllib3.connectionpool [-] Starting new HTTP connection (1): 10.35.160.133
>2014-04-28 10:57:06.168 11796 DEBUG urllib3.connectionpool [-] "GET /v2.0/tokens/1be99997670bea9e928ebab8e897217b HTTP/1.1" 200 7833 _make_request /usr/lib/python2.6/site-packages/urllib3/connectionpool.py:295
>2014-04-28 10:57:06.171 11796 DEBUG iso8601.iso8601 [-] Parsed 2014-04-29T07:44:18Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'07', 'tz_hour': None, 'month': u'04', 'timezone': u'Z', 'second': u'18', 'tz_minute': None, 'year': u'2014', 'separator': u'T', 'day': u'29', 'minute': u'44'} with default timezone <iso8601.iso8601.Utc object at 0x109a2d0> parse_date /usr/lib/python2.6/site-packages/iso8601/iso8601.py:166
>2014-04-28 10:57:06.172 11796 DEBUG iso8601.iso8601 [-] Got u'2014' for 'year' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:06.172 11796 DEBUG iso8601.iso8601 [-] Got u'04' for 'month' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:06.172 11796 DEBUG iso8601.iso8601 [-] Got u'29' for 'day' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:06.172 11796 DEBUG iso8601.iso8601 [-] Got u'07' for 'hour' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:06.172 11796 DEBUG iso8601.iso8601 [-] Got u'44' for 'minute' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:06.173 11796 DEBUG iso8601.iso8601 [-] Got u'18' for 'second' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:06.173 11796 DEBUG keystoneclient.middleware.auth_token [-] Storing token in cache _cache_put /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:1144
>2014-04-28 10:57:06.174 11796 DEBUG keystoneclient.middleware.auth_token [-] Received request from user: c5993ce8cd7a4fdaab75fb9934c6dd1b with project_id : 4c787be54dfd4d4c91c79bd3d7cc37d2 and roles: admin  _build_user_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:933
>2014-04-28 10:57:06.177 11796 DEBUG routes.middleware [-] Matched HEAD /images/38901f15-7e01-4cd4-9e9b-1239e514e148 __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:100
>2014-04-28 10:57:06.178 11796 DEBUG routes.middleware [-] Route path: '/images/{id}', defaults: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:102
>2014-04-28 10:57:06.178 11796 DEBUG routes.middleware [-] Match dict: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>, 'id': u'38901f15-7e01-4cd4-9e9b-1239e514e148'} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:103
>2014-04-28 10:57:06.178 11796 DEBUG glance.api.policy [5cd37bc4-fa03-42f9-8336-ae4c635657ed c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Loaded policy rules: {u'context_is_admin': 'role:admin', u'default': '@', u'manage_image_cache': 'role:admin'} load_rules /usr/lib/python2.6/site-packages/glance/api/policy.py:75
>2014-04-28 10:57:06.179 11796 DEBUG glance.common.client [5cd37bc4-fa03-42f9-8336-ae4c635657ed c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Constructed URL: http://0.0.0.0:9191/images/38901f15-7e01-4cd4-9e9b-1239e514e148 _construct_url /usr/lib/python2.6/site-packages/glance/common/client.py:408
>2014-04-28 10:57:06.200 11796 DEBUG glance.registry.client.v1.client [5cd37bc4-fa03-42f9-8336-ae4c635657ed c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Registry request GET /images/38901f15-7e01-4cd4-9e9b-1239e514e148 HTTP 200 request id req-dc2829c9-863a-4c98-94f4-397d9bafa921 do_request /usr/lib/python2.6/site-packages/glance/registry/client/v1/client.py:115
>2014-04-28 10:57:06.208 11792 DEBUG glance.api.middleware.version_negotiation [-] Determining version of request: HEAD /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 Accept:  process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:46
>2014-04-28 10:57:06.208 11792 DEBUG glance.api.middleware.version_negotiation [-] Using url versioning process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:59
>2014-04-28 10:57:06.208 11792 DEBUG glance.api.middleware.version_negotiation [-] Matched version: v1 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:71
>2014-04-28 10:57:06.208 11792 DEBUG glance.api.middleware.version_negotiation [-] new path /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:72
>2014-04-28 10:57:06.209 11792 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:569
>2014-04-28 10:57:06.209 11792 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:628
>2014-04-28 10:57:06.210 11792 INFO urllib3.connectionpool [-] Starting new HTTP connection (1): 10.35.160.133
>2014-04-28 10:57:06.236 11792 DEBUG urllib3.connectionpool [-] "GET /v2.0/tokens/1be99997670bea9e928ebab8e897217b HTTP/1.1" 200 7833 _make_request /usr/lib/python2.6/site-packages/urllib3/connectionpool.py:295
>2014-04-28 10:57:06.239 11792 DEBUG iso8601.iso8601 [-] Parsed 2014-04-29T07:44:18Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'07', 'tz_hour': None, 'month': u'04', 'timezone': u'Z', 'second': u'18', 'tz_minute': None, 'year': u'2014', 'separator': u'T', 'day': u'29', 'minute': u'44'} with default timezone <iso8601.iso8601.Utc object at 0x109a2d0> parse_date /usr/lib/python2.6/site-packages/iso8601/iso8601.py:166
>2014-04-28 10:57:06.240 11792 DEBUG iso8601.iso8601 [-] Got u'2014' for 'year' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:06.240 11792 DEBUG iso8601.iso8601 [-] Got u'04' for 'month' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:06.240 11792 DEBUG iso8601.iso8601 [-] Got u'29' for 'day' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:06.240 11792 DEBUG iso8601.iso8601 [-] Got u'07' for 'hour' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:06.240 11792 DEBUG iso8601.iso8601 [-] Got u'44' for 'minute' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:06.240 11792 DEBUG iso8601.iso8601 [-] Got u'18' for 'second' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:06.241 11792 DEBUG keystoneclient.middleware.auth_token [-] Storing token in cache _cache_put /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:1144
>2014-04-28 10:57:06.242 11792 DEBUG keystoneclient.middleware.auth_token [-] Received request from user: c5993ce8cd7a4fdaab75fb9934c6dd1b with project_id : 4c787be54dfd4d4c91c79bd3d7cc37d2 and roles: admin  _build_user_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:933
>2014-04-28 10:57:06.245 11792 DEBUG routes.middleware [-] Matched HEAD /images/38901f15-7e01-4cd4-9e9b-1239e514e148 __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:100
>2014-04-28 10:57:06.246 11792 DEBUG routes.middleware [-] Route path: '/images/{id}', defaults: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:102
>2014-04-28 10:57:06.246 11792 DEBUG routes.middleware [-] Match dict: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>, 'id': u'38901f15-7e01-4cd4-9e9b-1239e514e148'} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:103
>2014-04-28 10:57:06.246 11792 DEBUG glance.api.policy [f0db29ed-fe94-4790-9bdc-a2fe352bc639 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Loaded policy rules: {u'context_is_admin': 'role:admin', u'default': '@', u'manage_image_cache': 'role:admin'} load_rules /usr/lib/python2.6/site-packages/glance/api/policy.py:75
>2014-04-28 10:57:06.247 11792 DEBUG glance.common.client [f0db29ed-fe94-4790-9bdc-a2fe352bc639 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Constructed URL: http://0.0.0.0:9191/images/38901f15-7e01-4cd4-9e9b-1239e514e148 _construct_url /usr/lib/python2.6/site-packages/glance/common/client.py:408
>2014-04-28 10:57:06.268 11792 DEBUG glance.registry.client.v1.client [f0db29ed-fe94-4790-9bdc-a2fe352bc639 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Registry request GET /images/38901f15-7e01-4cd4-9e9b-1239e514e148 HTTP 200 request id req-63a3773e-df7e-4286-b325-b885b8ddcc27 do_request /usr/lib/python2.6/site-packages/glance/registry/client/v1/client.py:115
>2014-04-28 10:57:36.627 11794 DEBUG glance.api.middleware.version_negotiation [-] Determining version of request: HEAD /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 Accept:  process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:46
>2014-04-28 10:57:36.628 11794 DEBUG glance.api.middleware.version_negotiation [-] Using url versioning process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:59
>2014-04-28 10:57:36.628 11794 DEBUG glance.api.middleware.version_negotiation [-] Matched version: v1 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:71
>2014-04-28 10:57:36.628 11794 DEBUG glance.api.middleware.version_negotiation [-] new path /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:72
>2014-04-28 10:57:36.628 11794 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:569
>2014-04-28 10:57:36.628 11794 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:628
>2014-04-28 10:57:36.631 11794 DEBUG iso8601.iso8601 [-] Parsed 2014-04-29T07:44:18.000000Z into {'tz_sign': None, 'second_fraction': u'000000', 'hour': u'07', 'tz_hour': None, 'month': u'04', 'timezone': u'Z', 'second': u'18', 'tz_minute': None, 'year': u'2014', 'separator': u'T', 'day': u'29', 'minute': u'44'} with default timezone <iso8601.iso8601.Utc object at 0x109a2d0> parse_date /usr/lib/python2.6/site-packages/iso8601/iso8601.py:166
>2014-04-28 10:57:36.631 11794 DEBUG iso8601.iso8601 [-] Got u'2014' for 'year' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:36.632 11794 DEBUG iso8601.iso8601 [-] Got u'04' for 'month' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:36.632 11794 DEBUG iso8601.iso8601 [-] Got u'29' for 'day' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:36.632 11794 DEBUG iso8601.iso8601 [-] Got u'07' for 'hour' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:36.632 11794 DEBUG iso8601.iso8601 [-] Got u'44' for 'minute' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:36.632 11794 DEBUG iso8601.iso8601 [-] Got u'18' for 'second' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:36.632 11794 DEBUG keystoneclient.middleware.auth_token [-] Returning cached token _cache_get /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:1034
>2014-04-28 10:57:36.633 11794 DEBUG keystoneclient.middleware.auth_token [-] Received request from user: c5993ce8cd7a4fdaab75fb9934c6dd1b with project_id : 4c787be54dfd4d4c91c79bd3d7cc37d2 and roles: admin  _build_user_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:933
>2014-04-28 10:57:36.636 11794 DEBUG routes.middleware [-] Matched HEAD /images/38901f15-7e01-4cd4-9e9b-1239e514e148 __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:100
>2014-04-28 10:57:36.636 11794 DEBUG routes.middleware [-] Route path: '/images/{id}', defaults: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:102
>2014-04-28 10:57:36.636 11794 DEBUG routes.middleware [-] Match dict: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>, 'id': u'38901f15-7e01-4cd4-9e9b-1239e514e148'} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:103
>2014-04-28 10:57:36.637 11794 DEBUG glance.api.policy [74d6e97a-6097-4159-838d-0ef9b1859f8e c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Loaded policy rules: {u'context_is_admin': 'role:admin', u'default': '@', u'manage_image_cache': 'role:admin'} load_rules /usr/lib/python2.6/site-packages/glance/api/policy.py:75
>2014-04-28 10:57:36.637 11794 DEBUG glance.common.client [74d6e97a-6097-4159-838d-0ef9b1859f8e c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Constructed URL: http://0.0.0.0:9191/images/38901f15-7e01-4cd4-9e9b-1239e514e148 _construct_url /usr/lib/python2.6/site-packages/glance/common/client.py:408
>2014-04-28 10:57:36.658 11794 DEBUG glance.registry.client.v1.client [74d6e97a-6097-4159-838d-0ef9b1859f8e c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Registry request GET /images/38901f15-7e01-4cd4-9e9b-1239e514e148 HTTP 200 request id req-943d8238-0f9b-4ae9-9cc8-22e886d6cdfc do_request /usr/lib/python2.6/site-packages/glance/registry/client/v1/client.py:115
>2014-04-28 10:57:36.666 11795 DEBUG glance.api.middleware.version_negotiation [-] Determining version of request: HEAD /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 Accept:  process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:46
>2014-04-28 10:57:36.667 11795 DEBUG glance.api.middleware.version_negotiation [-] Using url versioning process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:59
>2014-04-28 10:57:36.667 11795 DEBUG glance.api.middleware.version_negotiation [-] Matched version: v1 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:71
>2014-04-28 10:57:36.667 11795 DEBUG glance.api.middleware.version_negotiation [-] new path /v1/images/38901f15-7e01-4cd4-9e9b-1239e514e148 process_request /usr/lib/python2.6/site-packages/glance/api/middleware/version_negotiation.py:72
>2014-04-28 10:57:36.667 11795 DEBUG keystoneclient.middleware.auth_token [-] Authenticating user token __call__ /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:569
>2014-04-28 10:57:36.667 11795 DEBUG keystoneclient.middleware.auth_token [-] Removing headers from request environment: X-Identity-Status,X-Domain-Id,X-Domain-Name,X-Project-Id,X-Project-Name,X-Project-Domain-Id,X-Project-Domain-Name,X-User-Id,X-User-Name,X-User-Domain-Id,X-User-Domain-Name,X-Roles,X-Service-Catalog,X-User,X-Tenant-Id,X-Tenant-Name,X-Tenant,X-Role _remove_auth_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:628
>2014-04-28 10:57:36.669 11795 INFO urllib3.connectionpool [-] Starting new HTTP connection (1): 10.35.160.133
>2014-04-28 10:57:36.695 11795 DEBUG urllib3.connectionpool [-] "GET /v2.0/tokens/1be99997670bea9e928ebab8e897217b HTTP/1.1" 200 7833 _make_request /usr/lib/python2.6/site-packages/urllib3/connectionpool.py:295
>2014-04-28 10:57:36.698 11795 DEBUG iso8601.iso8601 [-] Parsed 2014-04-29T07:44:18Z into {'tz_sign': None, 'second_fraction': None, 'hour': u'07', 'tz_hour': None, 'month': u'04', 'timezone': u'Z', 'second': u'18', 'tz_minute': None, 'year': u'2014', 'separator': u'T', 'day': u'29', 'minute': u'44'} with default timezone <iso8601.iso8601.Utc object at 0x109a2d0> parse_date /usr/lib/python2.6/site-packages/iso8601/iso8601.py:166
>2014-04-28 10:57:36.699 11795 DEBUG iso8601.iso8601 [-] Got u'2014' for 'year' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:36.699 11795 DEBUG iso8601.iso8601 [-] Got u'04' for 'month' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:36.699 11795 DEBUG iso8601.iso8601 [-] Got u'29' for 'day' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:36.699 11795 DEBUG iso8601.iso8601 [-] Got u'07' for 'hour' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:36.699 11795 DEBUG iso8601.iso8601 [-] Got u'44' for 'minute' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:36.700 11795 DEBUG iso8601.iso8601 [-] Got u'18' for 'second' with default None to_int /usr/lib/python2.6/site-packages/iso8601/iso8601.py:124
>2014-04-28 10:57:36.700 11795 DEBUG keystoneclient.middleware.auth_token [-] Storing token in cache _cache_put /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:1144
>2014-04-28 10:57:36.701 11795 DEBUG keystoneclient.middleware.auth_token [-] Received request from user: c5993ce8cd7a4fdaab75fb9934c6dd1b with project_id : 4c787be54dfd4d4c91c79bd3d7cc37d2 and roles: admin  _build_user_headers /usr/lib/python2.6/site-packages/keystoneclient/middleware/auth_token.py:933
>2014-04-28 10:57:36.705 11795 DEBUG routes.middleware [-] Matched HEAD /images/38901f15-7e01-4cd4-9e9b-1239e514e148 __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:100
>2014-04-28 10:57:36.705 11795 DEBUG routes.middleware [-] Route path: '/images/{id}', defaults: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:102
>2014-04-28 10:57:36.705 11795 DEBUG routes.middleware [-] Match dict: {'action': u'meta', 'controller': <glance.common.wsgi.Resource object at 0x274da50>, 'id': u'38901f15-7e01-4cd4-9e9b-1239e514e148'} __call__ /usr/lib/python2.6/site-packages/Routes-1.12.3-py2.6.egg/routes/middleware.py:103
>2014-04-28 10:57:36.705 11795 DEBUG glance.api.policy [d4d1a583-386b-469a-a104-8ea522f90b25 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Loaded policy rules: {u'context_is_admin': 'role:admin', u'default': '@', u'manage_image_cache': 'role:admin'} load_rules /usr/lib/python2.6/site-packages/glance/api/policy.py:75
>2014-04-28 10:57:36.706 11795 DEBUG glance.common.client [d4d1a583-386b-469a-a104-8ea522f90b25 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Constructed URL: http://0.0.0.0:9191/images/38901f15-7e01-4cd4-9e9b-1239e514e148 _construct_url /usr/lib/python2.6/site-packages/glance/common/client.py:408
>2014-04-28 10:57:36.727 11795 DEBUG glance.registry.client.v1.client [d4d1a583-386b-469a-a104-8ea522f90b25 c5993ce8cd7a4fdaab75fb9934c6dd1b 4c787be54dfd4d4c91c79bd3d7cc37d2] Registry request GET /images/38901f15-7e01-4cd4-9e9b-1239e514e148 HTTP 200 request id req-7cc8a884-c2d7-490d-9885-1017f19592c1 do_request /usr/lib/python2.6/site-packages/glance/registry/client/v1/client.py:115

Actions: View

Attachments on bug 1091864: 890372 | 890373 | 890374