# Pastebin m9MqcoB0 Nov 08 14:09:36 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-3f8f0424-e374-468c-9ff0-2152a0770875 None None] Authenticating user token {{(pid=18500) process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:401}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-5688d6fc-30f5-47e5-9208-8fa62314b903 None vishakha] RBAC: auth_context: {'service_project_id': None, 'service_user_id': None, 'service_user_domain_id': None, 'service_project_domain_id': None, 'trustor_id': None, 'user_domain_id': u'default', 'domain_id': None, 'trust_id': None, 'project_domain_id': None, 'service_roles': [], 'group_ids': [], 'user_id': u'6a8d16c9be9a455aaee4634cc7eb8b51', 'roles': [], 'system_scope': None, 'trustee_id': None, 'domain_name': None, 'is_admin_project': True, 'token': , 'project_id': None} {{(pid=18500) fill_context /opt/stack/keystone/keystone/server/flask/request_processing/middleware/auth_context.py:445}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-5688d6fc-30f5-47e5-9208-8fa62314b903 None vishakha] REQUEST_METHOD: `POST` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-5688d6fc-30f5-47e5-9208-8fa62314b903 None vishakha] SCRIPT_NAME: `/identity` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-5688d6fc-30f5-47e5-9208-8fa62314b903 None vishakha] PATH_INFO: `/v3/auth/tokens` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.auth.core [None req-5688d6fc-30f5-47e5-9208-8fa62314b903 None vishakha] MFA Rules not processed for user `6a8d16c9be9a455aaee4634cc7eb8b51`. Rule list: `[]` (Enabled: `True`). {{(pid=18500) check_auth_methods_against_rules /opt/stack/keystone/keystone/auth/core.py:446}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.common.fernet_utils [None req-5688d6fc-30f5-47e5-9208-8fa62314b903 None vishakha] Loaded 2 Fernet keys from /etc/keystone/fernet-keys/, but `[fernet_tokens] max_active_keys = 3`; perhaps there have not been enough key rotations to reach `max_active_keys` yet? {{(pid=18500) load_keys /opt/stack/keystone/keystone/common/fernet_utils.py:307}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: [pid: 18500|app: 0|req: 321/640] 127.0.0.1 () {64 vars in 1250 bytes} [Thu Nov 8 14:09:36 2018] POST /identity/v3/auth/tokens => generated 572 bytes in 152 msecs (HTTP/1.1 201) 6 headers in 384 bytes (2 switches on core 0) Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-96bcc811-c296-43bf-9e6b-09e21c60b711 None vishakha] Authenticating user token {{(pid=18499) process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:401}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-398de9be-c9ba-4466-b730-4df78d5c8f53 None vishakha] RBAC: auth_context: {'service_project_id': None, 'service_user_id': None, 'service_user_domain_id': None, 'service_project_domain_id': None, 'trustor_id': None, 'user_domain_id': u'default', 'domain_id': None, 'trust_id': None, 'project_domain_id': None, 'service_roles': [], 'group_ids': [], 'user_id': u'6a8d16c9be9a455aaee4634cc7eb8b51', 'roles': [], 'system_scope': None, 'trustee_id': None, 'domain_name': None, 'is_admin_project': True, 'token': , 'project_id': None} {{(pid=18499) fill_context /opt/stack/keystone/keystone/server/flask/request_processing/middleware/auth_context.py:445}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-398de9be-c9ba-4466-b730-4df78d5c8f53 None vishakha] REQUEST_METHOD: `GET` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-398de9be-c9ba-4466-b730-4df78d5c8f53 None vishakha] SCRIPT_NAME: `/identity` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-398de9be-c9ba-4466-b730-4df78d5c8f53 None vishakha] PATH_INFO: `/v3/users/6a8d16c9be9a455aaee4634cc7eb8b51/projects` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.common.rbac_enforcer.enforcer [None req-398de9be-c9ba-4466-b730-4df78d5c8f53 None vishakha] RBAC: Authorizing `identity:list_user_projects(user_id=6a8d16c9be9a455aaee4634cc7eb8b51)` {{(pid=18499) enforce_call /opt/stack/keystone/keystone/common/rbac_enforcer/enforcer.py:410}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.common.rbac_enforcer.enforcer [None req-398de9be-c9ba-4466-b730-4df78d5c8f53 None vishakha] RBAC: Policy Enforcement Cred Data `identity:list_user_projects creds(service_project_id=None, service_user_id=None, service_user_domain_id=None, service_project_domain_id=None, trustor_id=None, user_domain_id=default, domain_id=None, trust_id=None, project_domain_id=None, service_roles=[], group_ids=[], user_id=6a8d16c9be9a455aaee4634cc7eb8b51, roles=[], system_scope=None, trustee_id=None, domain_name=None, is_admin_project=True, token=*** (audit_id=5kgmJFw_SGqUNZXT5jGl8A, audit_chain_id=[u'5kgmJFw_SGqUNZXT5jGl8A', u'ZQ_-zg2kQLOo1XJnUI58zQ']) at 0x7fb19efa7fd0>, project_id=None)` {{(pid=18499) enforce_call /opt/stack/keystone/keystone/common/rbac_enforcer/enforcer.py:418}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.common.rbac_enforcer.enforcer [None req-398de9be-c9ba-4466-b730-4df78d5c8f53 None vishakha] RBAC: Policy Enforcement Target Data `identity:list_user_projects => target(user_id=6a8d16c9be9a455aaee4634cc7eb8b51)` {{(pid=18499) enforce_call /opt/stack/keystone/keystone/common/rbac_enforcer/enforcer.py:426}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.common.rbac_enforcer.enforcer [None req-398de9be-c9ba-4466-b730-4df78d5c8f53 None vishakha] RBAC: Authorization granted {{(pid=18499) enforce_call /opt/stack/keystone/keystone/common/rbac_enforcer/enforcer.py:432}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: [pid: 18499|app: 0|req: 320/641] 127.0.0.1 () {60 vars in 1288 bytes} [Thu Nov 8 14:09:37 2018] GET /identity/v3/users/6a8d16c9be9a455aaee4634cc7eb8b51/projects => generated 461 bytes in 121 msecs (HTTP/1.1 200) 5 headers in 177 bytes (1 switches on core 0) Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-5688d6fc-30f5-47e5-9208-8fa62314b903 None vishakha] Authenticating user token {{(pid=18500) process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:401}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-13056e81-74fb-4d94-be1a-bff1a2fd63ee None vishakha] RBAC: auth_context: {'service_project_id': None, 'service_user_id': None, 'service_user_domain_id': None, 'service_project_domain_id': None, 'trustor_id': None, 'user_domain_id': u'default', 'domain_id': None, 'trust_id': None, 'project_domain_id': None, 'service_roles': [], 'group_ids': [], 'user_id': u'6a8d16c9be9a455aaee4634cc7eb8b51', 'roles': [], 'system_scope': None, 'trustee_id': None, 'domain_name': None, 'is_admin_project': True, 'token': , 'project_id': None} {{(pid=18500) fill_context /opt/stack/keystone/keystone/server/flask/request_processing/middleware/auth_context.py:445}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-13056e81-74fb-4d94-be1a-bff1a2fd63ee None vishakha] REQUEST_METHOD: `POST` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-13056e81-74fb-4d94-be1a-bff1a2fd63ee None vishakha] SCRIPT_NAME: `/identity` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-13056e81-74fb-4d94-be1a-bff1a2fd63ee None vishakha] PATH_INFO: `/v3/auth/tokens` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.auth.core [None req-13056e81-74fb-4d94-be1a-bff1a2fd63ee None vishakha] MFA Rules not processed for user `6a8d16c9be9a455aaee4634cc7eb8b51`. Rule list: `[]` (Enabled: `True`). {{(pid=18500) check_auth_methods_against_rules /opt/stack/keystone/keystone/auth/core.py:446}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.common.fernet_utils [None req-13056e81-74fb-4d94-be1a-bff1a2fd63ee None vishakha] Loaded 2 Fernet keys from /etc/keystone/fernet-keys/, but `[fernet_tokens] max_active_keys = 3`; perhaps there have not been enough key rotations to reach `max_active_keys` yet? {{(pid=18500) load_keys /opt/stack/keystone/keystone/common/fernet_utils.py:307}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: [pid: 18500|app: 0|req: 322/642] 127.0.0.1 () {64 vars in 1271 bytes} [Thu Nov 8 14:09:37 2018] POST /identity/v3/auth/tokens => generated 3750 bytes in 166 msecs (HTTP/1.1 201) 6 headers in 406 bytes (2 switches on core 0) Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-caf7844d-a103-459e-a58e-52b109d037c6 None None] REQUEST_METHOD: `POST` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-caf7844d-a103-459e-a58e-52b109d037c6 None None] SCRIPT_NAME: `/identity` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-caf7844d-a103-459e-a58e-52b109d037c6 None None] PATH_INFO: `/v3/auth/OS-FEDERATION/saml2/ecp` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 14:09:37 ubuntu devstack@keystone.service[18494]: [pid: 18499|app: 0|req: 321/643] 127.0.0.1 () {62 vars in 1118 bytes} [Thu Nov 8 14:09:37 2018] POST /identity/v3/auth/OS-FEDERATION/saml2/ecp => generated 6987 bytes in 339 msecs (HTTP/1.1 200) 7 headers in 343 bytes (1 switches on core 0) Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-34f7d456-2328-486a-8825-7bdb56fcff9b None None] REQUEST_METHOD: `GET` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-34f7d456-2328-486a-8825-7bdb56fcff9b None None] SCRIPT_NAME: `/identity` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-34f7d456-2328-486a-8825-7bdb56fcff9b None None] PATH_INFO: `/` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: [pid: 18500|app: 0|req: 323/644] 127.0.0.1 () {58 vars in 987 bytes} [Thu Nov 8 14:09:41 2018] GET /identity => generated 268 bytes in 17 msecs (HTTP/1.1 300) 5 headers in 191 bytes (2 switches on core 0) Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-caf7844d-a103-459e-a58e-52b109d037c6 None None] Authenticating user token {{(pid=18499) process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:401}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.common.fernet_utils [None req-caf7844d-a103-459e-a58e-52b109d037c6 None None] Loaded 2 Fernet keys from /etc/keystone/fernet-keys/, but `[fernet_tokens] max_active_keys = 3`; perhaps there have not been enough key rotations to reach `max_active_keys` yet? {{(pid=18499) load_keys /opt/stack/keystone/keystone/common/fernet_utils.py:307}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: INFO keystone.server.flask.request_processing.middleware.auth_context [None req-caf7844d-a103-459e-a58e-52b109d037c6 None None] Invalid user token Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-1e70ada3-23fd-4fc3-86b9-6fdddbf9f16c None None] REQUEST_METHOD: `POST` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-1e70ada3-23fd-4fc3-86b9-6fdddbf9f16c None None] SCRIPT_NAME: `/identity` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-1e70ada3-23fd-4fc3-86b9-6fdddbf9f16c None None] PATH_INFO: `/v3/auth/tokens` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.common.fernet_utils [None req-1e70ada3-23fd-4fc3-86b9-6fdddbf9f16c None None] Loaded 2 Fernet keys from /etc/keystone/fernet-keys/, but `[fernet_tokens] max_active_keys = 3`; perhaps there have not been enough key rotations to reach `max_active_keys` yet? {{(pid=18499) load_keys /opt/stack/keystone/keystone/common/fernet_utils.py:307}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: WARNING keystone.server.flask.application [None req-1e70ada3-23fd-4fc3-86b9-6fdddbf9f16c None None] Could not recognize Fernet token: TokenNotFound: Could not recognize Fernet token Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: [pid: 18499|app: 0|req: 322/645] 127.0.0.1 () {64 vars in 1332 bytes} [Thu Nov 8 14:09:41 2018] POST /identity/v3/auth/tokens => generated 88 bytes in 72 msecs (HTTP/1.1 404) 5 headers in 183 bytes (1 switches on core 0) Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-34f7d456-2328-486a-8825-7bdb56fcff9b None None] Authenticating user token {{(pid=18500) process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:401}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-5c7b5e51-9692-49f8-9905-85b08814848c None neutron] RBAC: auth_context: {'service_project_id': None, 'service_user_id': None, 'service_user_domain_id': None, 'service_project_domain_id': None, 'trustor_id': None, 'user_domain_id': u'default', 'domain_id': None, 'trust_id': None, 'project_domain_id': u'default', 'service_roles': [], 'group_ids': [], 'user_id': u'313885f6a6fb45cf9d31cac8fad79a69', 'roles': [u'service'], 'system_scope': None, 'trustee_id': None, 'domain_name': None, 'is_admin_project': True, 'token': , 'project_id': u'8f1fc3d7899f4697a44c1af21fe33f33'} {{(pid=18500) fill_context /opt/stack/keystone/keystone/server/flask/request_processing/middleware/auth_context.py:445}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-5c7b5e51-9692-49f8-9905-85b08814848c None neutron] REQUEST_METHOD: `GET` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-5c7b5e51-9692-49f8-9905-85b08814848c None neutron] SCRIPT_NAME: `/identity` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-5c7b5e51-9692-49f8-9905-85b08814848c None neutron] PATH_INFO: `/v3/auth/tokens` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.common.fernet_utils [None req-5c7b5e51-9692-49f8-9905-85b08814848c None neutron] Loaded 2 Fernet keys from /etc/keystone/fernet-keys/, but `[fernet_tokens] max_active_keys = 3`; perhaps there have not been enough key rotations to reach `max_active_keys` yet? {{(pid=18500) load_keys /opt/stack/keystone/keystone/common/fernet_utils.py:307}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: WARNING keystone.server.flask.application [None req-5c7b5e51-9692-49f8-9905-85b08814848c None neutron] Could not recognize Fernet token: TokenNotFound: Could not recognize Fernet token Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: [pid: 18500|app: 0|req: 324/646] 127.0.0.1 () {62 vars in 1430 bytes} [Thu Nov 8 14:09:41 2018] GET /identity/v3/auth/tokens => generated 88 bytes in 142 msecs (HTTP/1.1 404) 5 headers in 183 bytes (1 switches on core 0) Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-6ca54b98-1710-492d-b239-5afe58810ddf None None] REQUEST_METHOD: `POST` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-6ca54b98-1710-492d-b239-5afe58810ddf None None] SCRIPT_NAME: `/identity` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-6ca54b98-1710-492d-b239-5afe58810ddf None None] PATH_INFO: `/tokens` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: [pid: 18499|app: 0|req: 323/647] 127.0.0.1 () {62 vars in 1004 bytes} [Thu Nov 8 14:09:41 2018] POST /identity/tokens => generated 233 bytes in 49 msecs (HTTP/1.1 404) 5 headers in 177 bytes (1 switches on core 0) Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-5c7b5e51-9692-49f8-9905-85b08814848c None neutron] Authenticating user token {{(pid=18500) process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:401}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.common.fernet_utils [None req-5c7b5e51-9692-49f8-9905-85b08814848c None neutron] Loaded 2 Fernet keys from /etc/keystone/fernet-keys/, but `[fernet_tokens] max_active_keys = 3`; perhaps there have not been enough key rotations to reach `max_active_keys` yet? {{(pid=18500) load_keys /opt/stack/keystone/keystone/common/fernet_utils.py:307}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: INFO keystone.server.flask.request_processing.middleware.auth_context [None req-5c7b5e51-9692-49f8-9905-85b08814848c None neutron] Invalid user token Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-a9bd0d9d-1d3a-47b3-a25b-f4e7cdb5abc8 None None] REQUEST_METHOD: `GET` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-a9bd0d9d-1d3a-47b3-a25b-f4e7cdb5abc8 None None] SCRIPT_NAME: `/identity` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-a9bd0d9d-1d3a-47b3-a25b-f4e7cdb5abc8 None None] PATH_INFO: `/v3/auth/projects` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: WARNING keystone.server.flask.application [None req-a9bd0d9d-1d3a-47b3-a25b-f4e7cdb5abc8 None None] Authorization failed. The request you have made requires authentication. from 127.0.0.1: Unauthorized: The request you have made requires authentication. Nov 08 14:09:41 ubuntu devstack@keystone.service[18494]: [pid: 18500|app: 0|req: 325/648] 127.0.0.1 () {60 vars in 1207 bytes} [Thu Nov 8 14:09:41 2018] GET /identity/v3/auth/projects => generated 109 bytes in 47 msecs (HTTP/1.1 401) 6 headers in 250 bytes (1 switches on core 0) Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-a7ff636a-e738-40f0-b4b1-c82ea63a4df9 None None] REQUEST_METHOD: `POST` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-a7ff636a-e738-40f0-b4b1-c82ea63a4df9 None None] SCRIPT_NAME: `/identity` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-a7ff636a-e738-40f0-b4b1-c82ea63a4df9 None None] PATH_INFO: `/tokens` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: [pid: 18499|app: 0|req: 324/649] 127.0.0.1 () {62 vars in 1004 bytes} [Thu Nov 8 14:09:42 2018] POST /identity/tokens => generated 233 bytes in 63 msecs (HTTP/1.1 404) 5 headers in 177 bytes (1 switches on core 0) Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-a9bd0d9d-1d3a-47b3-a25b-f4e7cdb5abc8 None None] Authenticating user token {{(pid=18500) process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:401}} Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: DEBUG keystone.common.fernet_utils [None req-a9bd0d9d-1d3a-47b3-a25b-f4e7cdb5abc8 None None] Loaded 2 Fernet keys from /etc/keystone/fernet-keys/, but `[fernet_tokens] max_active_keys = 3`; perhaps there have not been enough key rotations to reach `max_active_keys` yet? {{(pid=18500) load_keys /opt/stack/keystone/keystone/common/fernet_utils.py:307}} Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: INFO keystone.server.flask.request_processing.middleware.auth_context [None req-a9bd0d9d-1d3a-47b3-a25b-f4e7cdb5abc8 None None] Invalid user token Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-7181a322-93a1-4911-bf4b-ae9ab530676b None None] REQUEST_METHOD: `POST` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-7181a322-93a1-4911-bf4b-ae9ab530676b None None] SCRIPT_NAME: `/identity` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-7181a322-93a1-4911-bf4b-ae9ab530676b None None] PATH_INFO: `/v3/auth/tokens` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: DEBUG keystone.common.fernet_utils [None req-7181a322-93a1-4911-bf4b-ae9ab530676b None None] Loaded 2 Fernet keys from /etc/keystone/fernet-keys/, but `[fernet_tokens] max_active_keys = 3`; perhaps there have not been enough key rotations to reach `max_active_keys` yet? {{(pid=18500) load_keys /opt/stack/keystone/keystone/common/fernet_utils.py:307}} Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: WARNING keystone.server.flask.application [None req-7181a322-93a1-4911-bf4b-ae9ab530676b None None] Could not recognize Fernet token: TokenNotFound: Could not recognize Fernet token Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: [pid: 18500|app: 0|req: 326/650] 127.0.0.1 () {64 vars in 1332 bytes} [Thu Nov 8 14:09:42 2018] POST /identity/v3/auth/tokens => generated 88 bytes in 49 msecs (HTTP/1.1 404) 5 headers in 183 bytes (1 switches on core 0) Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-f3ee0b55-45df-4b89-9ed8-a3d87a13cd22 None None] REQUEST_METHOD: `POST` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-f3ee0b55-45df-4b89-9ed8-a3d87a13cd22 None None] SCRIPT_NAME: `/identity` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-f3ee0b55-45df-4b89-9ed8-a3d87a13cd22 None None] PATH_INFO: `/tokens` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: [pid: 18499|app: 0|req: 325/651] 127.0.0.1 () {62 vars in 1004 bytes} [Thu Nov 8 14:09:42 2018] POST /identity/tokens => generated 233 bytes in 86 msecs (HTTP/1.1 404) 5 headers in 177 bytes (1 switches on core 0) Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-8be1d167-25c3-479a-aefd-57ecfa02a053 None None] REQUEST_METHOD: `GET` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-8be1d167-25c3-479a-aefd-57ecfa02a053 None None] SCRIPT_NAME: `/identity` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-8be1d167-25c3-479a-aefd-57ecfa02a053 None None] PATH_INFO: `/v3` {{(pid=18500) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 14:09:42 ubuntu devstack@keystone.service[18494]: [pid: 18500|app: 0|req: 327/652] 127.0.0.1 () {60 vars in 1064 bytes} [Thu Nov 8 14:09:42 2018] GET /identity/v3 => generated 253 bytes in 12 msecs (HTTP/1.1 200) 5 headers in 177 bytes (1 switches on core 0) Nov 08 14:09:45 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-f3ee0b55-45df-4b89-9ed8-a3d87a13cd22 None None] Authenticating user token {{(pid=18499) process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:401}} Nov 08 14:09:45 ubuntu devstack@keystone.service[18494]: DEBUG keystone.common.fernet_utils [None req-f3ee0b55-45df-4b89-9ed8-a3d87a13cd22 None None] Loaded 2 Fernet keys from /etc/keystone/fernet-keys/, but `[fernet_tokens] max_active_keys = 3`; perhaps there have not been enough key rotations to reach `max_active_keys` yet? {{(pid=18499) load_keys /opt/stack/keystone/keystone/common/fernet_utils.py:307}} Nov 08 14:09:45 ubuntu devstack@keystone.service[18494]: INFO keystone.server.flask.request_processing.middleware.auth_context [None req-f3ee0b55-45df-4b89-9ed8-a3d87a13cd22 None None] Invalid user token Nov 08 14:09:45 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-c1b393b6-f481-443c-b154-d5057aa512a9 None None] REQUEST_METHOD: `GET` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 14:09:45 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-c1b393b6-f481-443c-b154-d5057aa512a9 None None] SCRIPT_NAME: `/identity` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 14:09:45 ubuntu devstack@keystone.service[18494]: DEBUG keystone.server.flask.request_processing.req_logging [None req-c1b393b6-f481-443c-b154-d5057aa512a9 None None] PATH_INFO: `/v3/auth/projects` {{(pid=18499) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 14:09:45 ubuntu devstack@keystone.service[18494]: WARNING keystone.server.flask.application [None req-c1b393b6-f481-443c-b154-d5057aa512a9 None None] Authorization failed. The request you have made requires authentication. from 127.0.0.1: Unauthorized: The request you have made requires authentication. Nov 08 14:09:45 ubuntu devstack@keystone.service[18494]: [pid: 18499|app: 0|req: 326/653] 127.0.0.1 () {60 vars in 1207 bytes} [Thu Nov 8 14:09:45 2018] GET /identity/v3/auth/projects => generated 109 bytes in 99 msecs (HTTP/1.1 401) 6 headers in 250 bytes (1 switches on core 0) ^C stack@ubuntu:~/devstack$