Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 890372 Details for
Bug 1091864
nova backup fails to backup an instance with attached volume
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
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:
glance-api log
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
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 1091864
: 890372 |
890373
|
890374