# Pastebin fr0dwLWS Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-910e3757-2b0b-423f-8ebb-71c1c93910e6 None None] Authenticating user token {{(pid=11429) process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:401}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-d2b4e48d-25a9-4630-a08e-c81564b894a2 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': '6a8d16c9be9a455aaee4634cc7eb8b51', 'roles': [], 'system_scope': None, 'trustee_id': None, 'domain_name': None, 'is_admin_project': True, 'token': , 'project_id': None} {{(pid=11429) fill_context /opt/stack/keystone/keystone/server/flask/request_processing/middleware/auth_context.py:445}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-d2b4e48d-25a9-4630-a08e-c81564b894a2 None vishakha] REQUEST_METHOD: `POST` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-d2b4e48d-25a9-4630-a08e-c81564b894a2 None vishakha] SCRIPT_NAME: `/identity` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-d2b4e48d-25a9-4630-a08e-c81564b894a2 None vishakha] PATH_INFO: `/v3/auth/tokens` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.auth.core [None req-d2b4e48d-25a9-4630-a08e-c81564b894a2 None vishakha] MFA Rules not processed for user `6a8d16c9be9a455aaee4634cc7eb8b51`. Rule list: `[]` (Enabled: `True`). {{(pid=11429) check_auth_methods_against_rules /opt/stack/keystone/keystone/auth/core.py:446}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.common.fernet_utils [None req-d2b4e48d-25a9-4630-a08e-c81564b894a2 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=11429) load_keys /opt/stack/keystone/keystone/common/fernet_utils.py:307}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: [pid: 11429|app: 0|req: 51/101] 127.0.0.1 () {64 vars in 1250 bytes} [Thu Nov 8 15:09:40 2018] POST /identity/v3/auth/tokens => generated 572 bytes in 178 msecs (HTTP/1.1 201) 6 headers in 384 bytes (1 switches on core 0) Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-0df82f49-b25e-4f31-8062-65c83fe66ef2 None vishakha] Authenticating user token {{(pid=11430) process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:401}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-47902b6b-3367-4998-97fb-281d240a9f24 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': '6a8d16c9be9a455aaee4634cc7eb8b51', 'roles': [], 'system_scope': None, 'trustee_id': None, 'domain_name': None, 'is_admin_project': True, 'token': , 'project_id': None} {{(pid=11430) fill_context /opt/stack/keystone/keystone/server/flask/request_processing/middleware/auth_context.py:445}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-47902b6b-3367-4998-97fb-281d240a9f24 None vishakha] REQUEST_METHOD: `GET` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-47902b6b-3367-4998-97fb-281d240a9f24 None vishakha] SCRIPT_NAME: `/identity` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-47902b6b-3367-4998-97fb-281d240a9f24 None vishakha] PATH_INFO: `/v3/users/6a8d16c9be9a455aaee4634cc7eb8b51/projects` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.common.rbac_enforcer.enforcer [None req-47902b6b-3367-4998-97fb-281d240a9f24 None vishakha] RBAC: Authorizing `identity:list_user_projects(user_id=6a8d16c9be9a455aaee4634cc7eb8b51)` {{(pid=11430) enforce_call /opt/stack/keystone/keystone/common/rbac_enforcer/enforcer.py:410}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.common.rbac_enforcer.enforcer [None req-47902b6b-3367-4998-97fb-281d240a9f24 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=asMH4uKLS2arf_S3zZGfNA, audit_chain_id=[u'asMH4uKLS2arf_S3zZGfNA', u'ynKnaffhRryf4Q_aFqMqxw']) at 0x7f7bcc1c0c90>, project_id=None)` {{(pid=11430) enforce_call /opt/stack/keystone/keystone/common/rbac_enforcer/enforcer.py:418}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.common.rbac_enforcer.enforcer [None req-47902b6b-3367-4998-97fb-281d240a9f24 None vishakha] RBAC: Policy Enforcement Target Data `identity:list_user_projects => target(user_id=6a8d16c9be9a455aaee4634cc7eb8b51)` {{(pid=11430) enforce_call /opt/stack/keystone/keystone/common/rbac_enforcer/enforcer.py:426}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.common.rbac_enforcer.enforcer [None req-47902b6b-3367-4998-97fb-281d240a9f24 None vishakha] RBAC: Authorization granted {{(pid=11430) enforce_call /opt/stack/keystone/keystone/common/rbac_enforcer/enforcer.py:432}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: [pid: 11430|app: 0|req: 51/102] 127.0.0.1 () {60 vars in 1288 bytes} [Thu Nov 8 15:09:40 2018] GET /identity/v3/users/6a8d16c9be9a455aaee4634cc7eb8b51/projects => generated 461 bytes in 108 msecs (HTTP/1.1 200) 5 headers in 177 bytes (1 switches on core 0) Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-d2b4e48d-25a9-4630-a08e-c81564b894a2 None vishakha] Authenticating user token {{(pid=11429) process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:401}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-89c3a76c-56c7-40f5-8528-831fc465625b 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': '6a8d16c9be9a455aaee4634cc7eb8b51', 'roles': [], 'system_scope': None, 'trustee_id': None, 'domain_name': None, 'is_admin_project': True, 'token': , 'project_id': None} {{(pid=11429) fill_context /opt/stack/keystone/keystone/server/flask/request_processing/middleware/auth_context.py:445}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-89c3a76c-56c7-40f5-8528-831fc465625b None vishakha] REQUEST_METHOD: `POST` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-89c3a76c-56c7-40f5-8528-831fc465625b None vishakha] SCRIPT_NAME: `/identity` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-89c3a76c-56c7-40f5-8528-831fc465625b None vishakha] PATH_INFO: `/v3/auth/tokens` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.auth.core [None req-89c3a76c-56c7-40f5-8528-831fc465625b None vishakha] MFA Rules not processed for user `6a8d16c9be9a455aaee4634cc7eb8b51`. Rule list: `[]` (Enabled: `True`). {{(pid=11429) check_auth_methods_against_rules /opt/stack/keystone/keystone/auth/core.py:446}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: DEBUG keystone.common.fernet_utils [None req-89c3a76c-56c7-40f5-8528-831fc465625b 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=11429) load_keys /opt/stack/keystone/keystone/common/fernet_utils.py:307}} Nov 08 15:09:40 ubuntu devstack@keystone.service[11426]: [pid: 11429|app: 0|req: 52/103] 127.0.0.1 () {64 vars in 1271 bytes} [Thu Nov 8 15:09:40 2018] POST /identity/v3/auth/tokens => generated 3750 bytes in 148 msecs (HTTP/1.1 201) 6 headers in 406 bytes (1 switches on core 0) Nov 08 15:09:41 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-8fc260cd-a319-4a1b-b34a-3f179ce1df09 None None] REQUEST_METHOD: `POST` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 15:09:41 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-8fc260cd-a319-4a1b-b34a-3f179ce1df09 None None] SCRIPT_NAME: `/identity` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 15:09:41 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-8fc260cd-a319-4a1b-b34a-3f179ce1df09 None None] PATH_INFO: `/v3/auth/OS-FEDERATION/saml2/ecp` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 15:09:41 ubuntu devstack@keystone.service[11426]: [pid: 11430|app: 0|req: 52/104] 127.0.0.1 () {62 vars in 1118 bytes} [Thu Nov 8 15:09:41 2018] POST /identity/v3/auth/OS-FEDERATION/saml2/ecp => generated 6987 bytes in 261 msecs (HTTP/1.1 200) 7 headers in 343 bytes (2 switches on core 0) Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-06203d5d-8d8d-4ae6-80e6-8934949a5349 None None] REQUEST_METHOD: `GET` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-06203d5d-8d8d-4ae6-80e6-8934949a5349 None None] SCRIPT_NAME: `/identity` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-06203d5d-8d8d-4ae6-80e6-8934949a5349 None None] PATH_INFO: `/` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: [pid: 11429|app: 0|req: 53/105] 127.0.0.1 () {58 vars in 987 bytes} [Thu Nov 8 15:09:44 2018] GET /identity => generated 268 bytes in 13 msecs (HTTP/1.1 300) 5 headers in 191 bytes (1 switches on core 0) Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-8fc260cd-a319-4a1b-b34a-3f179ce1df09 None None] Authenticating user token {{(pid=11430) process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:401}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.common.fernet_utils [None req-8fc260cd-a319-4a1b-b34a-3f179ce1df09 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=11430) load_keys /opt/stack/keystone/keystone/common/fernet_utils.py:307}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: INFO keystone.server.flask.request_processing.middleware.auth_context [None req-8fc260cd-a319-4a1b-b34a-3f179ce1df09 None None] Invalid user token Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-042e72fa-7d01-47a6-a526-c33d6181cc2f None None] REQUEST_METHOD: `POST` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-042e72fa-7d01-47a6-a526-c33d6181cc2f None None] SCRIPT_NAME: `/identity` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-042e72fa-7d01-47a6-a526-c33d6181cc2f None None] PATH_INFO: `/v3/auth/tokens` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.common.fernet_utils [None req-042e72fa-7d01-47a6-a526-c33d6181cc2f 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=11430) load_keys /opt/stack/keystone/keystone/common/fernet_utils.py:307}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: WARNING keystone.server.flask.application [None req-042e72fa-7d01-47a6-a526-c33d6181cc2f None None] Could not recognize Fernet token: TokenNotFound: Could not recognize Fernet token Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: [pid: 11430|app: 0|req: 53/106] 127.0.0.1 () {64 vars in 1332 bytes} [Thu Nov 8 15:09:44 2018] POST /identity/v3/auth/tokens => generated 88 bytes in 66 msecs (HTTP/1.1 404) 5 headers in 183 bytes (1 switches on core 0) Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-06203d5d-8d8d-4ae6-80e6-8934949a5349 None None] Authenticating user token {{(pid=11429) process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:401}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-947c49e0-3582-485b-8d0c-784afebea18a 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=11429) fill_context /opt/stack/keystone/keystone/server/flask/request_processing/middleware/auth_context.py:445}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-947c49e0-3582-485b-8d0c-784afebea18a None neutron] REQUEST_METHOD: `GET` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-947c49e0-3582-485b-8d0c-784afebea18a None neutron] SCRIPT_NAME: `/identity` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-947c49e0-3582-485b-8d0c-784afebea18a None neutron] PATH_INFO: `/v3/auth/tokens` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.common.fernet_utils [None req-947c49e0-3582-485b-8d0c-784afebea18a 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=11429) load_keys /opt/stack/keystone/keystone/common/fernet_utils.py:307}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: WARNING keystone.server.flask.application [None req-947c49e0-3582-485b-8d0c-784afebea18a None neutron] Could not recognize Fernet token: TokenNotFound: Could not recognize Fernet token Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: [pid: 11429|app: 0|req: 54/107] 127.0.0.1 () {62 vars in 1430 bytes} [Thu Nov 8 15:09:44 2018] GET /identity/v3/auth/tokens => generated 88 bytes in 147 msecs (HTTP/1.1 404) 5 headers in 183 bytes (1 switches on core 0) Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-c1682a01-0f33-4bbb-9c9a-3146824f0c8e None None] REQUEST_METHOD: `POST` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-c1682a01-0f33-4bbb-9c9a-3146824f0c8e None None] SCRIPT_NAME: `/identity` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-c1682a01-0f33-4bbb-9c9a-3146824f0c8e None None] PATH_INFO: `/tokens` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: [pid: 11430|app: 0|req: 54/108] 127.0.0.1 () {62 vars in 1004 bytes} [Thu Nov 8 15:09:44 2018] POST /identity/tokens => generated 233 bytes in 41 msecs (HTTP/1.1 404) 5 headers in 177 bytes (1 switches on core 0) Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-947c49e0-3582-485b-8d0c-784afebea18a None neutron] Authenticating user token {{(pid=11429) process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:401}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.common.fernet_utils [None req-947c49e0-3582-485b-8d0c-784afebea18a 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=11429) load_keys /opt/stack/keystone/keystone/common/fernet_utils.py:307}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: INFO keystone.server.flask.request_processing.middleware.auth_context [None req-947c49e0-3582-485b-8d0c-784afebea18a None neutron] Invalid user token Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-d3e78639-3a88-40b9-a3ec-a5328db6166f None None] REQUEST_METHOD: `GET` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-d3e78639-3a88-40b9-a3ec-a5328db6166f None None] SCRIPT_NAME: `/identity` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-d3e78639-3a88-40b9-a3ec-a5328db6166f None None] PATH_INFO: `/v3/auth/projects` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: WARNING keystone.server.flask.application [None req-d3e78639-3a88-40b9-a3ec-a5328db6166f None None] Authorization failed. auth_context did not decode anything useful (Disable insecure_debug mode to suppress these details.) from 127.0.0.1: Unauthorized: auth_context did not decode anything useful (Disable insecure_debug mode to suppress these details.) Nov 08 15:09:44 ubuntu devstack@keystone.service[11426]: [pid: 11429|app: 0|req: 55/109] 127.0.0.1 () {60 vars in 1207 bytes} [Thu Nov 8 15:09:44 2018] GET /identity/v3/auth/projects => generated 159 bytes in 57 msecs (HTTP/1.1 401) 6 headers in 250 bytes (1 switches on core 0) Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-acbd1d76-7728-4167-859a-ece9ef32919a None None] REQUEST_METHOD: `POST` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-acbd1d76-7728-4167-859a-ece9ef32919a None None] SCRIPT_NAME: `/identity` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-acbd1d76-7728-4167-859a-ece9ef32919a None None] PATH_INFO: `/tokens` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: [pid: 11430|app: 0|req: 55/110] 127.0.0.1 () {62 vars in 1004 bytes} [Thu Nov 8 15:09:45 2018] POST /identity/tokens => generated 233 bytes in 41 msecs (HTTP/1.1 404) 5 headers in 177 bytes (3 switches on core 0) Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-d3e78639-3a88-40b9-a3ec-a5328db6166f None None] Authenticating user token {{(pid=11429) process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:401}} Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: DEBUG keystone.common.fernet_utils [None req-d3e78639-3a88-40b9-a3ec-a5328db6166f 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=11429) load_keys /opt/stack/keystone/keystone/common/fernet_utils.py:307}} Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: INFO keystone.server.flask.request_processing.middleware.auth_context [None req-d3e78639-3a88-40b9-a3ec-a5328db6166f None None] Invalid user token Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-80509cc2-a6a0-4580-bb09-31baed89db46 None None] REQUEST_METHOD: `POST` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-80509cc2-a6a0-4580-bb09-31baed89db46 None None] SCRIPT_NAME: `/identity` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-80509cc2-a6a0-4580-bb09-31baed89db46 None None] PATH_INFO: `/v3/auth/tokens` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: DEBUG keystone.common.fernet_utils [None req-80509cc2-a6a0-4580-bb09-31baed89db46 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=11429) load_keys /opt/stack/keystone/keystone/common/fernet_utils.py:307}} Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: WARNING keystone.server.flask.application [None req-80509cc2-a6a0-4580-bb09-31baed89db46 None None] Could not recognize Fernet token: TokenNotFound: Could not recognize Fernet token Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: [pid: 11429|app: 0|req: 56/111] 127.0.0.1 () {64 vars in 1332 bytes} [Thu Nov 8 15:09:45 2018] POST /identity/v3/auth/tokens => generated 88 bytes in 50 msecs (HTTP/1.1 404) 5 headers in 183 bytes (2 switches on core 0) Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-94fd9fb0-c567-4e73-8ceb-da16979be6ec None None] REQUEST_METHOD: `POST` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-94fd9fb0-c567-4e73-8ceb-da16979be6ec None None] SCRIPT_NAME: `/identity` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-94fd9fb0-c567-4e73-8ceb-da16979be6ec None None] PATH_INFO: `/tokens` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: [pid: 11430|app: 0|req: 56/112] 127.0.0.1 () {62 vars in 1004 bytes} [Thu Nov 8 15:09:45 2018] POST /identity/tokens => generated 233 bytes in 79 msecs (HTTP/1.1 404) 5 headers in 177 bytes (1 switches on core 0) Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-7ca81792-fbb0-4374-851c-6a9cbafcd7af None None] REQUEST_METHOD: `GET` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-7ca81792-fbb0-4374-851c-6a9cbafcd7af None None] SCRIPT_NAME: `/identity` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-7ca81792-fbb0-4374-851c-6a9cbafcd7af None None] PATH_INFO: `/v3` {{(pid=11429) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 15:09:45 ubuntu devstack@keystone.service[11426]: [pid: 11429|app: 0|req: 57/113] 127.0.0.1 () {60 vars in 1104 bytes} [Thu Nov 8 15:09:45 2018] GET /identity/v3 => generated 253 bytes in 13 msecs (HTTP/1.1 200) 5 headers in 177 bytes (1 switches on core 0) Nov 08 15:09:47 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.middleware.auth_context [None req-94fd9fb0-c567-4e73-8ceb-da16979be6ec None None] Authenticating user token {{(pid=11430) process_request /usr/local/lib/python2.7/dist-packages/keystonemiddleware/auth_token/__init__.py:401}} Nov 08 15:09:47 ubuntu devstack@keystone.service[11426]: DEBUG keystone.common.fernet_utils [None req-94fd9fb0-c567-4e73-8ceb-da16979be6ec 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=11430) load_keys /opt/stack/keystone/keystone/common/fernet_utils.py:307}} Nov 08 15:09:47 ubuntu devstack@keystone.service[11426]: INFO keystone.server.flask.request_processing.middleware.auth_context [None req-94fd9fb0-c567-4e73-8ceb-da16979be6ec None None] Invalid user token Nov 08 15:09:47 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-92ce306a-fc62-4887-873b-6124c5d3d976 None None] REQUEST_METHOD: `GET` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:27}} Nov 08 15:09:47 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-92ce306a-fc62-4887-873b-6124c5d3d976 None None] SCRIPT_NAME: `/identity` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:28}} Nov 08 15:09:47 ubuntu devstack@keystone.service[11426]: DEBUG keystone.server.flask.request_processing.req_logging [None req-92ce306a-fc62-4887-873b-6124c5d3d976 None None] PATH_INFO: `/v3/auth/projects` {{(pid=11430) log_request_info /opt/stack/keystone/keystone/server/flask/request_processing/req_logging.py:29}} Nov 08 15:09:47 ubuntu devstack@keystone.service[11426]: WARNING keystone.server.flask.application [None req-92ce306a-fc62-4887-873b-6124c5d3d976 None None] Authorization failed. auth_context did not decode anything useful (Disable insecure_debug mode to suppress these details.) from 127.0.0.1: Unauthorized: auth_context did not decode anything useful (Disable insecure_debug mode to suppress these details.) Nov 08 15:09:47 ubuntu devstack@keystone.service[11426]: [pid: 11430|app: 0|req: 57/114] 127.0.0.1 () {60 vars in 1207 bytes} [Thu Nov 8 15:09:47 2018] GET /identity/v3/auth/projects => generated 159 bytes in 67 msecs (HTTP/1.1 401) 6 headers in 250 bytes (1 switches on core 0)