test_v3_os_revoke.OSRevokeTests: invalid event issued_before time; Too early

Bug #1295261 reported by Dolph Mathews
6
This bug affects 1 person
Affects Status Importance Assigned to Milestone
OpenStack Identity (keystone)
Fix Released
Critical
Adam Young

Bug Description

This occurred in a gate run (lost the link for the moment):

FAIL: keystone.tests.test_v3_os_revoke.OSRevokeTests.test_disabled_project_in_list
tags: worker-1
----------------------------------------------------------------------
pythonlogging:'': {{{
Adding cache-proxy 'keystone.tests.test_cache.CacheIsolatingProxy' to backend.
Callback: `keystone.contrib.revoke.core.Manager._trust_callback` subscribed to event `identity.OS-TRUST:trust.deleted`.
Callback: `keystone.contrib.revoke.core.Manager._consumer_callback` subscribed to event `identity.OS-OAUTH1:consumer.deleted`.
Callback: `keystone.contrib.revoke.core.Manager._access_token_callback` subscribed to event `identity.OS-OAUTH1:access_token.deleted`.
Callback: `keystone.contrib.revoke.core.Manager._role_callback` subscribed to event `identity.role.deleted`.
Callback: `keystone.contrib.revoke.core.Manager._user_callback` subscribed to event `identity.user.deleted`.
Callback: `keystone.contrib.revoke.core.Manager._user_callback` subscribed to event `identity.user.disabled`.
Callback: `keystone.contrib.revoke.core.Manager._project_callback` subscribed to event `identity.project.deleted`.
Callback: `keystone.contrib.revoke.core.Manager._project_callback` subscribed to event `identity.project.disabled`.
Callback: `keystone.contrib.revoke.core.Manager._domain_callback` subscribed to event `identity.domain.disabled`.
CACHE_SET: Key: "'bed0a2f296a94d3598098bca72c3c2e3bef836b9'" Value: "({'enabled': True, 'id': 'a1c7e9c7c24e4ed0992b6c1c71a715df', 'name': 'e0c8292cff164d0cba7eb93692fa65fc', 'description': '46be6831b7934ae086f4e2237b00e73c'}, {'v': 1, 'ct': 1395322845.632957})"
CACHE_SET: Key: "'60cee77d1bb7192469a3c40b86e5b33e2fd7ac19'" Value: "({'enabled': True, 'id': 'a1c7e9c7c24e4ed0992b6c1c71a715df', 'name': 'e0c8292cff164d0cba7eb93692fa65fc', 'description': '46be6831b7934ae086f4e2237b00e73c'}, {'v': 1, 'ct': 1395322845.633679})"
found extension EntryPoint.parse('qpid = oslo.messaging._drivers.impl_qpid:QpidDriver')
found extension EntryPoint.parse('zmq = oslo.messaging._drivers.impl_zmq:ZmqDriver')
found extension EntryPoint.parse('kombu = oslo.messaging._drivers.impl_rabbit:RabbitDriver')
found extension EntryPoint.parse('rabbit = oslo.messaging._drivers.impl_rabbit:RabbitDriver')
found extension EntryPoint.parse('fake = oslo.messaging._drivers.impl_fake:FakeDriver')
found extension EntryPoint.parse('log = oslo.messaging.notify._impl_log:LogDriver')
found extension EntryPoint.parse('messagingv2 = oslo.messaging.notify._impl_messaging:MessagingV2Driver')
found extension EntryPoint.parse('noop = oslo.messaging.notify._impl_noop:NoOpDriver')
found extension EntryPoint.parse('routing = oslo.messaging.notify._impl_routing:RoutingDriver')
found extension EntryPoint.parse('test = oslo.messaging.notify._impl_test:TestDriver')
found extension EntryPoint.parse('messaging = oslo.messaging.notify._impl_messaging:MessagingDriver')
CACHE_SET: Key: "'5ec78fa245b6d4094510876ae4afc7435c60cbf4'" Value: "({'description': '8c27b380e06d4af8be1f3b3fa8916a13', 'enabled': True, 'id': '07746a4e1979445182c96eba082d593b', 'name': '7fc4100921d84f05ac2738e7d25c3574', 'domain_id': 'a1c7e9c7c24e4ed0992b6c1c71a715df'}, {'v': 1, 'ct': 1395322845.641914})"
CACHE_SET: Key: "'e35808a841485cda6d2b42cb870bfb11261b0e46'" Value: "({'description': '8c27b380e06d4af8be1f3b3fa8916a13', 'enabled': True, 'id': '07746a4e1979445182c96eba082d593b', 'name': '7fc4100921d84f05ac2738e7d25c3574', 'domain_id': 'a1c7e9c7c24e4ed0992b6c1c71a715df'}, {'v': 1, 'ct': 1395322845.642477})"
CACHE_GET: Key: "'bed0a2f296a94d3598098bca72c3c2e3bef836b9'" Value: "({'enabled': True, 'id': 'a1c7e9c7c24e4ed0992b6c1c71a715df', 'name': 'e0c8292cff164d0cba7eb93692fa65fc', 'description': '46be6831b7934ae086f4e2237b00e73c'}, {'v': 1, 'ct': 1395322845.632957})"
CACHE_SET: Key: "'935a80a8b7b81bc94c1c17864dd103a9fb93a015'" Value: "({'description': '7bc81fd73cf04d6a82760ebcbc3ffaa2', 'enabled': True, 'id': '678d7e87fb794c3e941adf5294b13ea6', 'name': 'd8debaadff324df9870055e2ea07ea4b', 'domain_id': 'default'}, {'v': 1, 'ct': 1395322845.717836})"
CACHE_SET: Key: "'2ecc52c74d45fbd9344d1d5453f7669bccafbf3a'" Value: "({'description': '7bc81fd73cf04d6a82760ebcbc3ffaa2', 'enabled': True, 'id': '678d7e87fb794c3e941adf5294b13ea6', 'name': 'd8debaadff324df9870055e2ea07ea4b', 'domain_id': 'default'}, {'v': 1, 'ct': 1395322845.718511})"
CACHE_GET: Key: "'e45f4dc1a9bd1a59610ed5aa0db40470f719a2c3'" Value: "<dogpile.cache.api.NoValue object at 0x647be10>"
NeedRegenerationException
no value, waiting for create lock
value creation lock <dogpile.cache.region._LockWrapper object at 0x89c2e10> acquired
CACHE_GET: Key: "'e45f4dc1a9bd1a59610ed5aa0db40470f719a2c3'" Value: "<dogpile.cache.api.NoValue object at 0x7cda9d0>"
Calling creation function
CACHE_SET: Key: "'e45f4dc1a9bd1a59610ed5aa0db40470f719a2c3'" Value: "({'enabled': True, 'id': u'default', 'name': u'Default', u'description': u'Owns users and tenants (i.e. projects) available on Identity API v2.'}, {'v': 1, 'ct': 1395322845.72301})"
Released creation lock
CACHE_SET: Key: "'b31f39a4eba2a50f067cccd54869ea3c6da6208e'" Value: "({'enabled': True, 'id': 'b3069cefc23349138cb40e9603a9eaa2', 'name': 'admin', 'description': '88f6aac804be4be3919e3f579b9bfcf4'}, {'v': 1, 'ct': 1395322845.796364})"
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
Initialized with method overriding = True, and path info altering = True
CACHE_DELETE: "'3f4cc1d6671a506df885f18c02b15f199371946d'"
Auth token not in the request header. Will not build auth context.
Matched POST /auth/tokens
Route path: '{path_info:.*}', defaults: {'controller': <keystone.contrib.s3.core.S3Extension object at 0x7580b90>}
Match dict: {'controller': <keystone.contrib.s3.core.S3Extension object at 0x7580b90>, 'path_info': '/auth/tokens'}
Matched POST /auth/tokens
Route path: '{path_info:.*}', defaults: {'controller': <keystone.contrib.simple_cert.routers.SimpleCertExtension object at 0x7e6f990>}
Match dict: {'controller': <keystone.contrib.simple_cert.routers.SimpleCertExtension object at 0x7e6f990>, 'path_info': '/auth/tokens'}
Matched POST /auth/tokens
Route path: '{path_info:.*}', defaults: {'controller': <keystone.contrib.revoke.routers.RevokeExtension object at 0x86c7510>}
Match dict: {'controller': <keystone.contrib.revoke.routers.RevokeExtension object at 0x86c7510>, 'path_info': '/auth/tokens'}
Matched POST /auth/tokens
Route path: '{path_info:.*}', defaults: {'controller': <keystone.common.wsgi.ComposingRouter object at 0x7e6f910>}
Match dict: {'controller': <keystone.common.wsgi.ComposingRouter object at 0x7e6f910>, 'path_info': '/auth/tokens'}
Matched POST /auth/tokens
Route path: '/auth/tokens', defaults: {'action': u'authenticate_for_token', 'controller': <keystone.auth.controllers.Auth object at 0x6665a50>}
Match dict: {'action': u'authenticate_for_token', 'controller': <keystone.auth.controllers.Auth object at 0x6665a50>}
arg_dict: {}
CACHE_GET: Key: "'5ec78fa245b6d4094510876ae4afc7435c60cbf4'" Value: "({'description': '8c27b380e06d4af8be1f3b3fa8916a13', 'enabled': True, 'id': '07746a4e1979445182c96eba082d593b', 'name': '7fc4100921d84f05ac2738e7d25c3574', 'domain_id': 'a1c7e9c7c24e4ed0992b6c1c71a715df'}, {'v': 1, 'ct': 1395322845.641914})"
CACHE_GET: Key: "'bed0a2f296a94d3598098bca72c3c2e3bef836b9'" Value: "({'enabled': True, 'id': 'a1c7e9c7c24e4ed0992b6c1c71a715df', 'name': 'e0c8292cff164d0cba7eb93692fa65fc', 'description': '46be6831b7934ae086f4e2237b00e73c'}, {'v': 1, 'ct': 1395322845.632957})"
CACHE_GET: Key: "'bed0a2f296a94d3598098bca72c3c2e3bef836b9'" Value: "({'enabled': True, 'id': 'a1c7e9c7c24e4ed0992b6c1c71a715df', 'name': 'e0c8292cff164d0cba7eb93692fa65fc', 'description': '46be6831b7934ae086f4e2237b00e73c'}, {'v': 1, 'ct': 1395322845.632957})"
CADF Event: {'typeURI': 'http://schemas.dmtf.org/cloud/audit/1.0/event', 'initiator': {'typeURI': 'service/security/account/user', 'host': {'address': 'localhost'}, 'id': 'openstack:1a56ee06-2bc7-4aa2-9978-ff23378b74fa', 'name': u'4e6567e9fde24d9ea389cad9b6a6cff7'}, 'target': {'typeURI': 'service/security/account/user', 'id': 'openstack:bbbb135f-d33a-4173-9d7a-34a827aa2b76'}, 'observer': {'typeURI': 'service/security', 'id': 'openstack:654512f1-ea75-4881-98ef-ead1eab852b9'}, 'eventType': 'activity', 'eventTime': '2014-03-20T13:40:45.916711+0000', 'action': 'authenticate', 'outcome': 'pending', 'id': 'openstack:06ee56b9-b2f8-4698-b99a-ca52d024eb3d'}
CACHE_GET: Key: "'bed0a2f296a94d3598098bca72c3c2e3bef836b9'" Value: "({'enabled': True, 'id': 'a1c7e9c7c24e4ed0992b6c1c71a715df', 'name': 'e0c8292cff164d0cba7eb93692fa65fc', 'description': '46be6831b7934ae086f4e2237b00e73c'}, {'v': 1, 'ct': 1395322845.632957})"
CADF Event: {'typeURI': 'http://schemas.dmtf.org/cloud/audit/1.0/event', 'initiator': {'typeURI': 'service/security/account/user', 'host': {'address': 'localhost'}, 'id': 'openstack:1a56ee06-2bc7-4aa2-9978-ff23378b74fa', 'name': u'4e6567e9fde24d9ea389cad9b6a6cff7'}, 'target': {'typeURI': 'service/security/account/user', 'id': 'openstack:24c73eb6-e53e-44fb-9028-0d6063633c66'}, 'observer': {'typeURI': 'service/security', 'id': 'openstack:2f9d79d5-6020-4531-8e1c-6f88c82e5920'}, 'eventType': 'activity', 'eventTime': '2014-03-20T13:40:45.985498+0000', 'action': 'authenticate', 'outcome': 'success', 'id': 'openstack:fba6c446-6fb9-40e0-8f14-0915e88c7d06'}
CACHE_GET: Key: "'5ec78fa245b6d4094510876ae4afc7435c60cbf4'" Value: "({'description': '8c27b380e06d4af8be1f3b3fa8916a13', 'enabled': True, 'id': '07746a4e1979445182c96eba082d593b', 'name': '7fc4100921d84f05ac2738e7d25c3574', 'domain_id': 'a1c7e9c7c24e4ed0992b6c1c71a715df'}, {'v': 1, 'ct': 1395322845.641914})"
CACHE_GET: Key: "'bed0a2f296a94d3598098bca72c3c2e3bef836b9'" Value: "({'enabled': True, 'id': 'a1c7e9c7c24e4ed0992b6c1c71a715df', 'name': 'e0c8292cff164d0cba7eb93692fa65fc', 'description': '46be6831b7934ae086f4e2237b00e73c'}, {'v': 1, 'ct': 1395322845.632957})"
CACHE_GET: Key: "'e45f4dc1a9bd1a59610ed5aa0db40470f719a2c3'" Value: "({'enabled': True, 'id': u'default', 'name': u'Default', u'description': u'Owns users and tenants (i.e. projects) available on Identity API v2.'}, {'v': 1, 'ct': 1395322845.72301})"
CACHE_GET: Key: "'bed0a2f296a94d3598098bca72c3c2e3bef836b9'" Value: "({'enabled': True, 'id': 'a1c7e9c7c24e4ed0992b6c1c71a715df', 'name': 'e0c8292cff164d0cba7eb93692fa65fc', 'description': '46be6831b7934ae086f4e2237b00e73c'}, {'v': 1, 'ct': 1395322845.632957})"
CACHE_GET: Key: "'5ec78fa245b6d4094510876ae4afc7435c60cbf4'" Value: "({'description': '8c27b380e06d4af8be1f3b3fa8916a13', 'enabled': True, 'id': '07746a4e1979445182c96eba082d593b', 'name': '7fc4100921d84f05ac2738e7d25c3574', 'domain_id': 'a1c7e9c7c24e4ed0992b6c1c71a715df'}, {'v': 1, 'ct': 1395322845.641914})"
CACHE_GET: Key: "'e45f4dc1a9bd1a59610ed5aa0db40470f719a2c3'" Value: "({'enabled': True, 'id': u'default', 'name': u'Default', u'description': u'Owns users and tenants (i.e. projects) available on Identity API v2.'}, {'v': 1, 'ct': 1395322845.72301})"
CACHE_GET: Key: "'b31f39a4eba2a50f067cccd54869ea3c6da6208e'" Value: "({'enabled': True, 'id': 'b3069cefc23349138cb40e9603a9eaa2', 'name': 'admin', 'description': '88f6aac804be4be3919e3f579b9bfcf4'}, {'v': 1, 'ct': 1395322845.796364})"
Parsed 2014-03-20T14:40:46.007105Z into {'tz_sign': None, 'second_fraction': '007105', 'hour': '14', 'daydash': '20', 'tz_hour': None, 'month': None, 'timezone': 'Z', 'second': '46', 'tz_minute': None, 'year': '2014', 'separator': 'T', 'monthdash': '03', 'day': None, 'minute': '40'} with default timezone <iso8601.iso8601.Utc object at 0x2cac110>
Got '2014' for 'year' with default None
Got '03' for 'monthdash' with default 1
Got 3 for 'month' with default 3
Got '20' for 'daydash' with default 1
Got 20 for 'day' with default 20
Got '14' for 'hour' with default None
Got '40' for 'minute' with default None
Got '46' for 'second' with default None
CACHE_SET: Key: "'d3805be8c549ce1f9ea3e4d67efbeacba7e9fd89'" Value: "({'user_id': u'4e6567e9fde24d9ea389cad9b6a6cff7', 'expires': datetime.datetime(2014, 3, 20, 14, 40, 46, 7105), 'token_data': {'token': {'methods': [u'password'], 'roles': [{'id': 'b3069cefc23349138cb40e9603a9eaa2', 'name': 'admin'}], 'expires_at': '2014-03-20T14:40:46.007105Z', 'project': {'domain': {'id': 'a1c7e9c7c24e4ed0992b6c1c71a715df', 'name': 'e0c8292cff164d0cba7eb93692fa65fc'}, 'id': '07746a4e1979445182c96eba082d593b', 'name': '7fc4100921d84f05ac2738e7d25c3574'}, 'catalog': [{'endpoints': [{u'description': u'a8cf4af6c5a040908ee6efeb69b08d7d', 'url': u'944562ad32784239a73107f9a14d5f62', 'region': u'e0f4c75f5bb74972a06ac59465675996', 'enabled': True, 'legacy_endpoint_id': None, 'interface': u'f9b25d4f', 'id': u'541fa6e354044c7890d408a0fe915524', u'name': u'8d1ee34b03a042ffaafd10b8ecbfed6a'}], 'type': u'b3f350d383d54346ba0f494701b4a50c', 'id': u'0c8a61ed94d44f28a5ada551bb18d3d3'}], 'extras': {}, 'user': {'domain': {'id': 'a1c7e9c7c24e4ed0992b6c1c71a715df', 'name': 'e0c8292cff164d0cba7eb93692fa65fc'}, 'id': u'4e6567e9fde24d9ea389cad9b6a6cff7', 'name': u'8c601e71d874433185eea5c4f8704e41'}, 'issued_at': '2014-03-20T13:40:46.007168Z'}}, 'trust_id': None, 'user': {'domain': {'id': 'a1c7e9c7c24e4ed0992b6c1c71a715df', 'name': 'e0c8292cff164d0cba7eb93692fa65fc'}, 'id': u'4e6567e9fde24d9ea389cad9b6a6cff7', 'name': u'8c601e71d874433185eea5c4f8704e41'}, 'key': 'MIIF-gYJKoZIhvcNAQcCoIIF7zCCBesCAQExCTAHBgUrDgMCGjCCBAsGCSqGSIb3DQEHAaCCA-wEggP4eyJ0b2tlbiI6IHsibWV0aG9kcyI6IFsicGFzc3dvcmQiXSwgInJvbGVzIjogW3siaWQiOiAiYjMwNjljZWZjMjMzNDkxMzhjYjQwZTk2MDNhOWVhYTIiLCAibmFtZSI6ICJhZG1pbiJ9XSwgImV4cGlyZXNfYXQiOiAiMjAxNC0wMy0yMFQxNDo0MDo0Ni4wMDcxMDVaIiwgInByb2plY3QiOiB7ImRvbWFpbiI6IHsiaWQiOiAiYTFjN2U5YzdjMjRlNGVkMDk5MmI2YzFjNzFhNzE1ZGYiLCAibmFtZSI6ICJlMGM4MjkyY2ZmMTY0ZDBjYmE3ZWI5MzY5MmZhNjVmYyJ9LCAiaWQiOiAiMDc3NDZhNGUxOTc5NDQ1MTgyYzk2ZWJhMDgyZDU5M2IiLCAibmFtZSI6ICI3ZmM0MTAwOTIxZDg0ZjA1YWMyNzM4ZTdkMjVjMzU3NCJ9LCAiY2F0YWxvZyI6IFt7ImVuZHBvaW50cyI6IFt7ImRlc2NyaXB0aW9uIjogImE4Y2Y0YWY2YzVhMDQwOTA4ZWU2ZWZlYjY5YjA4ZDdkIiwgInVybCI6ICI5NDQ1NjJhZDMyNzg0MjM5YTczMTA3ZjlhMTRkNWY2MiIsICJyZWdpb24iOiAiZTBmNGM3NWY1YmI3NDk3MmEwNmFjNTk0NjU2NzU5OTYiLCAiZW5hYmxlZCI6IHRydWUsICJsZWdhY3lfZW5kcG9pbnRfaWQiOiBudWxsLCAiaW50ZXJmYWNlIjogImY5YjI1ZDRmIiwgImlkIjogIjU0MWZhNmUzNTQwNDRjNzg5MGQ0MDhhMGZlOTE1NTI0IiwgIm5hbWUiOiAiOGQxZWUzNGIwM2EwNDJmZmFhZmQxMGI4ZWNiZmVkNmEifV0sICJ0eXBlIjogImIzZjM1MGQzODNkNTQzNDZiYTBmNDk0NzAxYjRhNTBjIiwgImlkIjogIjBjOGE2MWVkOTRkNDRmMjhhNWFkYTU1MWJiMThkM2QzIn1dLCAiZXh0cmFzIjoge30sICJ1c2VyIjogeyJkb21haW4iOiB7ImlkIjogImExYzdlOWM3YzI0ZTRlZDA5OTJiNmMxYzcxYTcxNWRmIiwgIm5hbWUiOiAiZTBjODI5MmNmZjE2NGQwY2JhN2ViOTM2OTJmYTY1ZmMifSwgImlkIjogIjRlNjU2N2U5ZmRlMjRkOWVhMzg5Y2FkOWI2YTZjZmY3IiwgIm5hbWUiOiAiOGM2MDFlNzFkODc0NDMzMTg1ZWVhNWM0Zjg3MDRlNDEifSwgImlzc3VlZF9hdCI6ICIyMDE0LTAzLTIwVDEzOjQwOjQ2LjAwNzE2OFoifX0xggHKMIIBxgIBATCBpDCBnjEKMAgGA1UEBRMBNTELMAkGA1UEBhMCVVMxCzAJBgNVBAgTAkNBMRIwEAYDVQQHEwlTdW5ueXZhbGUxEjAQBgNVBAoTCU9wZW5TdGFjazERMA8GA1UECxMIS2V5c3RvbmUxJTAjBgkqhkiG9w0BCQEWFmtleXN0b25lQG9wZW5zdGFjay5vcmcxFDASBgNVBAMTC1NlbGYgU2lnbmVkAgERMAcGBSsOAwIaMA0GCSqGSIb3DQEBAQUABIIBALpvxqHQ72rFAlU+vm5wOtViqBdEpSKpia7z+I3ecXciYq-ttj3dvCwBxZJ4s6mRRoKtPE-Cr4fsYmYjXTnYx5wCtIfVp0vxOOeCbtENVKFj-DOTjWHmS8X2ysj6hlgoV7kRkwMOVlfKBU9kLfjf-wpwL2rSNFnO98NNzuykFHf5zNd8p6sG4VczlkbYjMtY9PKjme8C3UJnYydyxoPCMaxkXEmt4N2NBPIC4BsaBccijpMVKn-fRCYEgaGqEmdc3ljGp3A4wxOfA2kUN7RAPSII82YbBEkjLJm-QhyzhCpVjavCmNd5swz2kDkCwY783bTVntYwABylvMhWGLDpo14=', 'token_version': 'v3.0', 'id': '7a4c5203e07ab9e544e4a500d8f82470', 'tenant': {'domain': {'id': 'a1c7e9c7c24e4ed0992b6c1c71a715df', 'name': 'e0c8292cff164d0cba7eb93692fa65fc'}, 'id': '07746a4e1979445182c96eba082d593b', 'name': '7fc4100921d84f05ac2738e7d25c3574'}, 'metadata': {'roles': ['b3069cefc23349138cb40e9603a9eaa2']}}, {'v': 1, 'ct': 1395322846.044313})"
CACHE_GET: Key: "'d3805be8c549ce1f9ea3e4d67efbeacba7e9fd89'" Value: "({'user_id': u'4e6567e9fde24d9ea389cad9b6a6cff7', 'expires': datetime.datetime(2014, 3, 20, 14, 40, 46, 7105), 'token_data': {'token': {'methods': [u'password'], 'roles': [{'id': 'b3069cefc23349138cb40e9603a9eaa2', 'name': 'admin'}], 'expires_at': '2014-03-20T14:40:46.007105Z', 'project': {'domain': {'id': 'a1c7e9c7c24e4ed0992b6c1c71a715df', 'name': 'e0c8292cff164d0cba7eb93692fa65fc'}, 'id': '07746a4e1979445182c96eba082d593b', 'name': '7fc4100921d84f05ac2738e7d25c3574'}, 'catalog': [{'endpoints': [{u'description': u'a8cf4af6c5a040908ee6efeb69b08d7d', 'url': u'944562ad32784239a73107f9a14d5f62', 'region': u'e0f4c75f5bb74972a06ac59465675996', 'enabled': True, 'legacy_endpoint_id': None, 'interface': u'f9b25d4f', 'id': u'541fa6e354044c7890d408a0fe915524', u'name': u'8d1ee34b03a042ffaafd10b8ecbfed6a'}], 'type': u'b3f350d383d54346ba0f494701b4a50c', 'id': u'0c8a61ed94d44f28a5ada551bb18d3d3'}], 'extras': {}, 'user': {'domain': {'id': 'a1c7e9c7c24e4ed0992b6c1c71a715df', 'name': 'e0c8292cff164d0cba7eb93692fa65fc'}, 'id': u'4e6567e9fde24d9ea389cad9b6a6cff7', 'name': u'8c601e71d874433185eea5c4f8704e41'}, 'issued_at': '2014-03-20T13:40:46.007168Z'}}, 'trust_id': None, 'user': {'domain': {'id': 'a1c7e9c7c24e4ed0992b6c1c71a715df', 'name': 'e0c8292cff164d0cba7eb93692fa65fc'}, 'id': u'4e6567e9fde24d9ea389cad9b6a6cff7', 'name': u'8c601e71d874433185eea5c4f8704e41'}, 'key': 'MIIF-gYJKoZIhvcNAQcCoIIF7zCCBesCAQExCTAHBgUrDgMCGjCCBAsGCSqGSIb3DQEHAaCCA-wEggP4eyJ0b2tlbiI6IHsibWV0aG9kcyI6IFsicGFzc3dvcmQiXSwgInJvbGVzIjogW3siaWQiOiAiYjMwNjljZWZjMjMzNDkxMzhjYjQwZTk2MDNhOWVhYTIiLCAibmFtZSI6ICJhZG1pbiJ9XSwgImV4cGlyZXNfYXQiOiAiMjAxNC0wMy0yMFQxNDo0MDo0Ni4wMDcxMDVaIiwgInByb2plY3QiOiB7ImRvbWFpbiI6IHsiaWQiOiAiYTFjN2U5YzdjMjRlNGVkMDk5MmI2YzFjNzFhNzE1ZGYiLCAibmFtZSI6ICJlMGM4MjkyY2ZmMTY0ZDBjYmE3ZWI5MzY5MmZhNjVmYyJ9LCAiaWQiOiAiMDc3NDZhNGUxOTc5NDQ1MTgyYzk2ZWJhMDgyZDU5M2IiLCAibmFtZSI6ICI3ZmM0MTAwOTIxZDg0ZjA1YWMyNzM4ZTdkMjVjMzU3NCJ9LCAiY2F0YWxvZyI6IFt7ImVuZHBvaW50cyI6IFt7ImRlc2NyaXB0aW9uIjogImE4Y2Y0YWY2YzVhMDQwOTA4ZWU2ZWZlYjY5YjA4ZDdkIiwgInVybCI6ICI5NDQ1NjJhZDMyNzg0MjM5YTczMTA3ZjlhMTRkNWY2MiIsICJyZWdpb24iOiAiZTBmNGM3NWY1YmI3NDk3MmEwNmFjNTk0NjU2NzU5OTYiLCAiZW5hYmxlZCI6IHRydWUsICJsZWdhY3lfZW5kcG9pbnRfaWQiOiBudWxsLCAiaW50ZXJmYWNlIjogImY5YjI1ZDRmIiwgImlkIjogIjU0MWZhNmUzNTQwNDRjNzg5MGQ0MDhhMGZlOTE1NTI0IiwgIm5hbWUiOiAiOGQxZWUzNGIwM2EwNDJmZmFhZmQxMGI4ZWNiZmVkNmEifV0sICJ0eXBlIjogImIzZjM1MGQzODNkNTQzNDZiYTBmNDk0NzAxYjRhNTBjIiwgImlkIjogIjBjOGE2MWVkOTRkNDRmMjhhNWFkYTU1MWJiMThkM2QzIn1dLCAiZXh0cmFzIjoge30sICJ1c2VyIjogeyJkb21haW4iOiB7ImlkIjogImExYzdlOWM3YzI0ZTRlZDA5OTJiNmMxYzcxYTcxNWRmIiwgIm5hbWUiOiAiZTBjODI5MmNmZjE2NGQwY2JhN2ViOTM2OTJmYTY1ZmMifSwgImlkIjogIjRlNjU2N2U5ZmRlMjRkOWVhMzg5Y2FkOWI2YTZjZmY3IiwgIm5hbWUiOiAiOGM2MDFlNzFkODc0NDMzMTg1ZWVhNWM0Zjg3MDRlNDEifSwgImlzc3VlZF9hdCI6ICIyMDE0LTAzLTIwVDEzOjQwOjQ2LjAwNzE2OFoifX0xggHKMIIBxgIBATCBpDCBnjEKMAgGA1UEBRMBNTELMAkGA1UEBhMCVVMxCzAJBgNVBAgTAkNBMRIwEAYDVQQHEwlTdW5ueXZhbGUxEjAQBgNVBAoTCU9wZW5TdGFjazERMA8GA1UECxMIS2V5c3RvbmUxJTAjBgkqhkiG9w0BCQEWFmtleXN0b25lQG9wZW5zdGFjay5vcmcxFDASBgNVBAMTC1NlbGYgU2lnbmVkAgERMAcGBSsOAwIaMA0GCSqGSIb3DQEBAQUABIIBALpvxqHQ72rFAlU+vm5wOtViqBdEpSKpia7z+I3ecXciYq-ttj3dvCwBxZJ4s6mRRoKtPE-Cr4fsYmYjXTnYx5wCtIfVp0vxOOeCbtENVKFj-DOTjWHmS8X2ysj6hlgoV7kRkwMOVlfKBU9kLfjf-wpwL2rSNFnO98NNzuykFHf5zNd8p6sG4VczlkbYjMtY9PKjme8C3UJnYydyxoPCMaxkXEmt4N2NBPIC4BsaBccijpMVKn-fRCYEgaGqEmdc3ljGp3A4wxOfA2kUN7RAPSII82YbBEkjLJm-QhyzhCpVjavCmNd5swz2kDkCwY783bTVntYwABylvMhWGLDpo14=', 'token_version': 'v3.0', 'id': '7a4c5203e07ab9e544e4a500d8f82470', 'tenant': {'domain': {'id': 'a1c7e9c7c24e4ed0992b6c1c71a715df', 'name': 'e0c8292cff164d0cba7eb93692fa65fc'}, 'id': '07746a4e1979445182c96eba082d593b', 'name': '7fc4100921d84f05ac2738e7d25c3574'}, 'metadata': {'roles': ['b3069cefc23349138cb40e9603a9eaa2']}}, {'v': 1, 'ct': 1395322846.044313})"
RBAC: auth_context: {'project_id': '07746a4e1979445182c96eba082d593b', 'group_ids': [], 'user_id': u'4e6567e9fde24d9ea389cad9b6a6cff7', 'roles': ['admin']}
Matched GET /OS-REVOKE/events
Route path: '{path_info:.*}', defaults: {'controller': <keystone.contrib.s3.core.S3Extension object at 0x7580b90>}
Match dict: {'controller': <keystone.contrib.s3.core.S3Extension object at 0x7580b90>, 'path_info': '/OS-REVOKE/events'}
Matched GET /OS-REVOKE/events
Route path: '{path_info:.*}', defaults: {'controller': <keystone.contrib.simple_cert.routers.SimpleCertExtension object at 0x7e6f990>}
Match dict: {'controller': <keystone.contrib.simple_cert.routers.SimpleCertExtension object at 0x7e6f990>, 'path_info': '/OS-REVOKE/events'}
Matched GET /OS-REVOKE/events
Route path: '{path_info:.*}', defaults: {'controller': <keystone.contrib.revoke.routers.RevokeExtension object at 0x86c7510>}
Match dict: {'controller': <keystone.contrib.revoke.routers.RevokeExtension object at 0x86c7510>, 'path_info': '/OS-REVOKE/events'}
Matched GET /OS-REVOKE/events
Route path: '/OS-REVOKE/events', defaults: {'action': u'list_revoke_events', 'controller': <keystone.contrib.revoke.controllers.RevokeController object at 0x6665390>}
Match dict: {'action': u'list_revoke_events', 'controller': <keystone.contrib.revoke.controllers.RevokeController object at 0x6665390>}
arg_dict: {}
RBAC: Authorizing identity:list_revoke_events()
RBAC: using auth context from the request environment
enforce identity:list_revoke_events: {'project_id': '07746a4e1979445182c96eba082d593b', 'group_ids': [], 'user_id': u'4e6567e9fde24d9ea389cad9b6a6cff7', 'roles': ['admin']}
Rule identity:list_revoke_events will be now enforced
RBAC: Authorization granted
Parsed 2014-03-20T13:40:45.898038Z into {'tz_sign': None, 'second_fraction': u'898038', 'hour': u'13', 'daydash': u'20', 'tz_hour': None, 'month': None, 'timezone': u'Z', 'second': u'45', 'tz_minute': None, 'year': u'2014', 'separator': u'T', 'monthdash': u'03', 'day': None, 'minute': u'40'} with default timezone <iso8601.iso8601.Utc object at 0x2cac110>
Got u'2014' for 'year' with default None
Got u'03' for 'monthdash' with default 1
Got 3 for 'month' with default 3
Got u'20' for 'daydash' with default 1
Got 20 for 'day' with default 20
Got u'13' for 'hour' with default None
Got u'40' for 'minute' with default None
Got u'45' for 'second' with default None
}}}

Traceback (most recent call last):
  File "keystone/tests/test_v3_os_revoke.py", line 79, in test_disabled_project_in_list
    self.assertReporteEventMatchesRecorded(events[0], sample, before_time)
  File "keystone/tests/test_v3_os_revoke.py", line 48, in assertReporteEventMatchesRecorded
    'invalid event issued_before time; Too early')
  File "/usr/lib/python2.7/unittest/caseNo handlers could be found for logger "keystone.identity.core"
.py", line 420, in assertTrue
    raise self.failureException(msg)
AssertionError: invalid event issued_before time; Too early

Revision history for this message
Dolph Mathews (dolph) wrote :
Changed in keystone:
importance: Undecided → Critical
milestone: none → icehouse-rc1
Revision history for this message
Dolph Mathews (dolph) wrote :
Revision history for this message
Dolph Mathews (dolph) wrote :

Logstash shows just 5 hits in the last 2 weeks, and this transient should only affect keystone.

Changed in keystone:
importance: Critical → Undecided
status: New → Triaged
Revision history for this message
OpenStack Infra (hudson-openstack) wrote : Fix proposed to keystone (master)

Fix proposed to branch: master
Review: https://review.openstack.org/81879

Changed in keystone:
assignee: nobody → Adam Young (ayoung)
status: Triaged → In Progress
Dolph Mathews (dolph)
Changed in keystone:
importance: Undecided → Critical
Revision history for this message
OpenStack Infra (hudson-openstack) wrote : Fix merged to keystone (master)

Reviewed: https://review.openstack.org/81879
Committed: https://git.openstack.org/cgit/openstack/keystone/commit/?id=60e88a0dd03f6eb2b7b4e8f6a5f2b532a56dee1a
Submitter: Jenkins
Branch: master

commit 60e88a0dd03f6eb2b7b4e8f6a5f2b532a56dee1a
Author: Adam Young <email address hidden>
Date: Thu Mar 20 14:25:27 2014 -0400

    Comparisons should account for instantaneous test execution

    The comparisons for the Revoke tests were greater than or less than, but if the test
    did not take a full clock tick, the values could be equal.

    Closes-Bug: 1295261

    Change-Id: I7526640c283f13a4e4ed4f0b0ebd059bbe38ad9b

Changed in keystone:
status: In Progress → Fix Committed
Thierry Carrez (ttx)
Changed in keystone:
status: Fix Committed → Fix Released
Thierry Carrez (ttx)
Changed in keystone:
milestone: icehouse-rc1 → 2014.1
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.