yahoo-eng-team team mailing list archive

Thread
Date
[Bug 1295261] [NEW] test_v3_os_revoke.OSRevokeTests: invalid event issued_before time; Too early

To: yahoo-eng-team@xxxxxxxxxxxxxxxxxxx
From: Dolph Mathews <1295261@xxxxxxxxxxxxxxxxxx>
Date: Thu, 20 Mar 2014 17:24:01 -0000
Reply-to: Bug 1295261 <1295261@xxxxxxxxxxxxxxxxxx>
Sender: bounces@xxxxxxxxxxxxx
Public bug reported:

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

** Affects: keystone
     Importance: Undecided
         Status: Triaged

** Changed in: keystone
   Importance: Undecided => Critical

** Changed in: keystone
    Milestone: None => icehouse-rc1

-- 
You received this bug notification because you are a member of Yahoo!
Engineering Team, which is subscribed to Keystone.
https://bugs.launchpad.net/bugs/1295261

Title:
  test_v3_os_revoke.OSRevokeTests: invalid event issued_before time; Too
  early

Status in OpenStack Identity (Keystone):
  Triaged

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

To manage notifications about this bug go to:
https://bugs.launchpad.net/keystone/+bug/1295261/+subscriptions
Follow ups

[Bug 1295261] Re: test_v3_os_revoke.OSRevokeTests: invalid event issued_before time; Too early
From: Thierry Carrez, 2014-03-26
[Bug 1295261] [NEW] test_v3_os_revoke.OSRevokeTests: invalid event issued_before time; Too early
From: Dolph Mathews, 2014-03-20
References

[Bug 1295261] [NEW] test_v3_os_revoke.OSRevokeTests: invalid event issued_before time; Too early
From: Dolph Mathews, 2014-03-20