[openstack-dev] [Barbican] Keystone Listener error when processing delete project event
Hello, The Keystone Listener outputs the below error, over and over again, when processing a delete project event. Do you have any idea why this happens? Happens the same with Ocata and Pike versions. Thank you, Mihaela Balas 2018-02-16 15:36:02.673 1 DEBUG amqp [-] heartbeat_tick : for connection ef42486446c34306bd10921b264da26b heartbeat_tick /opt/barbican/lib/python2.7/site-packages/amqp/connection.py:678 2018-02-16 15:36:02.673 1 DEBUG amqp [-] heartbeat_tick : Prev sent/recv: 111624/334860, now - 111625/334860, monotonic - 895085.445269, last_heartbeat_sent - 895085.445263, heartbeat int. - 60 for connection ef42486446c34306bd10921b264da26b heartbeat_tick /opt/barbican/lib/python2.7/site-packages/amqp/connection.py:700 2018-02-16 15:36:02.675 1 DEBUG oslo_messaging._drivers.amqpdriver [-] received message with unique_id: 0a407a9a71b641c888c49c0d4674b607 __call__ /opt/barbican/lib/python2.7/site-packages/oslo_messaging/_drivers/amqpdriver.py:257 2018-02-16 15:36:02.675 1 DEBUG amqp [-] heartbeat_tick : for connection ef42486446c34306bd10921b264da26b heartbeat_tick /opt/barbican/lib/python2.7/site-packages/amqp/connection.py:678 2018-02-16 15:36:02.675 1 DEBUG amqp [-] heartbeat_tick : Prev sent/recv: 111625/334860, now - 111625/334863, monotonic - 895085.447218, last_heartbeat_sent - 895085.445263, heartbeat int. - 60 for connection ef42486446c34306bd10921b264da26b heartbeat_tick /opt/barbican/lib/python2.7/site-packages/amqp/connection.py:700 2018-02-16 15:36:02.676 1 DEBUG barbican.queue.keystone_listener [-] Input keystone event publisher_id = identity.keystone-admin-api-2903979735-fsj57 process_event /opt/barbican/lib/python2.7/site-packages/barbican/queue/keystone_listener.py:72 2018-02-16 15:36:02.676 1 DEBUG barbican.queue.keystone_listener [-] Input keystone event payload = {u'resource_info': u'79d3491d58e542ada54776d2bd68ef7e'} process_event /opt/barbican/lib/python2.7/site-packages/barbican/queue/keystone_listener.py:73 2018-02-16 15:36:02.676 1 DEBUG barbican.queue.keystone_listener [-] Input keystone event type = identity.project.deleted process_event /opt/barbican/lib/python2.7/site-packages/barbican/queue/keystone_listener.py:74 2018-02-16 15:36:02.677 1 DEBUG barbican.queue.keystone_listener [-] Input keystone event metadata = {'timestamp': u'2018-02-16 15:35:48.506374', 'message_id': u'5cc2ef82-75a7-4ce9-a9eb-573ae008f4e4'} process_event /opt/barbican/lib/python2.7/site-packages/barbican/queue/keystone_listener.py:75 2018-02-16 15:36:02.677 1 DEBUG barbican.queue.keystone_listener [-] Keystone Event: resource type=project, operation type=deleted, keystone id=79d3491d58e542ada54776d2bd68ef7e process_event /opt/barbican/lib/python2.7/site-packages/barbican/queue/keystone_listener.py:80 2018-02-16 15:36:02.677 1 DEBUG barbican.tasks.keystone_consumer [-] Creating KeystoneEventConsumer task processor __init__ /opt/barbican/lib/python2.7/site-packages/barbican/tasks/keystone_consumer.py:40 2018-02-16 15:36:02.677 1 DEBUG barbican.model.repositories [-] Getting session... get_session /opt/barbican/lib/python2.7/site-packages/barbican/model/repositories.py:353 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources [-] Could not retrieve information needed to process task 'Project cleanup via Keystone notifications'.: TypeError: 'NoneType' object is not callable 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources Traceback (most recent call last): 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources File "/opt/barbican/lib/python2.7/site-packages/barbican/tasks/resources.py", line 91, in process 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources entity = self.retrieve_entity(*args, **kwargs) 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources File "/opt/barbican/lib/python2.7/site-packages/barbican/tasks/keystone_consumer.py", line 67, in retrieve_entity 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources suppress_exception=True) 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources File "/opt/barbican/lib/python2.7/site-packages/barbican/model/repositories.py", line 586, in find_by_external_project_id 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources session = self.get_session(session) 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources File "/opt/barbican/lib/python2.7/site-packages/barbican/model/repositories.py", line 354, in get_session 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources return session or get_session() 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources File "/opt/barbican/lib/python2.7/site-packages/barbican/model/repositories.py", line 161, in get_session 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources return _SESSION_FACTORY() 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources TypeError: 'NoneType' object is not callable 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources 2018-02-16 15:36:02.678 1 DEBUG oslo.messa
Re: [openstack-dev] [Barbican] Keystone Listener error when processing delete project event
Taking a quick look at the barbican code, it might be that something isn't setting up the _SESSION_FACTORY [0], but I'm certainly not a barbican expert. Might be worth while to open a bug [1]. [0] https://github.com/openstack/barbican/blob/5b525f6b0a7cf5342a9ffa3ca3618028d6d53649/barbican/model/repositories.py#L95-L113 [1] https://bugs.launchpad.net/barbican On Fri, Feb 16, 2018 at 9:40 AM, wrote: > Hello, > > > > > > The Keystone Listener outputs the below error, over and over again, when > processing a delete project event. Do you have any idea why this happens? > Happens the same with Ocata and Pike versions. > > > > Thank you, > > Mihaela Balas > > > > 2018-02-16 15:36:02.673 1 DEBUG amqp [-] heartbeat_tick : for connection > ef42486446c34306bd10921b264da26b heartbeat_tick > /opt/barbican/lib/python2.7/site-packages/amqp/connection.py:678 > > 2018-02-16 15:36:02.673 1 DEBUG amqp [-] heartbeat_tick : Prev sent/recv: > 111624/334860, now - 111625/334860, monotonic - 895085.445269, > last_heartbeat_sent - 895085.445263, heartbeat int. - 60 for connection > ef42486446c34306bd10921b264da26b heartbeat_tick > /opt/barbican/lib/python2.7/site-packages/amqp/connection.py:700 > > 2018-02-16 15:36:02.675 1 DEBUG oslo_messaging._drivers.amqpdriver [-] > received message with unique_id: 0a407a9a71b641c888c49c0d4674b607 > __call__ /opt/barbican/lib/python2.7/site-packages/oslo_messaging/_ > drivers/amqpdriver.py:257 > > 2018-02-16 15:36:02.675 1 DEBUG amqp [-] heartbeat_tick : for connection > ef42486446c34306bd10921b264da26b heartbeat_tick > /opt/barbican/lib/python2.7/site-packages/amqp/connection.py:678 > > 2018-02-16 15:36:02.675 1 DEBUG amqp [-] heartbeat_tick : Prev sent/recv: > 111625/334860, now - 111625/334863, monotonic - 895085.447218, > last_heartbeat_sent - 895085.445263, heartbeat int. - 60 for connection > ef42486446c34306bd10921b264da26b heartbeat_tick > /opt/barbican/lib/python2.7/site-packages/amqp/connection.py:700 > > 2018-02-16 15:36:02.676 1 DEBUG barbican.queue.keystone_listener [-] > Input keystone event publisher_id = > identity.keystone-admin-api-2903979735-fsj57 > process_event /opt/barbican/lib/python2.7/site-packages/barbican/queue/ > keystone_listener.py:72 > > 2018-02-16 15:36:02.676 1 DEBUG barbican.queue.keystone_listener [-] > Input keystone event payload = {u'resource_info': u' > 79d3491d58e542ada54776d2bd68ef7e'} process_event > /opt/barbican/lib/python2.7/site-packages/barbican/queue/ > keystone_listener.py:73 > > 2018-02-16 15:36:02.676 1 DEBUG barbican.queue.keystone_listener [-] > Input keystone event type = identity.project.deleted process_event > /opt/barbican/lib/python2.7/site-packages/barbican/queue/ > keystone_listener.py:74 > > 2018-02-16 15:36:02.677 1 DEBUG barbican.queue.keystone_listener [-] > Input keystone event metadata = {'timestamp': u'2018-02-16 > 15:35:48.506374', 'message_id': u'5cc2ef82-75a7-4ce9-a9eb-573ae008f4e4'} > process_event /opt/barbican/lib/python2.7/site-packages/barbican/queue/ > keystone_listener.py:75 > > 2018-02-16 15:36:02.677 1 DEBUG barbican.queue.keystone_listener [-] > Keystone Event: resource type=project, operation type=deleted, keystone id= > 79d3491d58e542ada54776d2bd68ef7e process_event > /opt/barbican/lib/python2.7/site-packages/barbican/queue/ > keystone_listener.py:80 > > 2018-02-16 15:36:02.677 1 DEBUG barbican.tasks.keystone_consumer [-] > Creating KeystoneEventConsumer task processor __init__ > /opt/barbican/lib/python2.7/site-packages/barbican/tasks/ > keystone_consumer.py:40 > > 2018-02-16 15:36:02.677 1 DEBUG barbican.model.repositories [-] Getting > session... get_session /opt/barbican/lib/python2.7/ > site-packages/barbican/model/repositories.py:353 > > 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources [-] Could not > retrieve information needed to process task 'Project cleanup via Keystone > notifications'.: TypeError: 'NoneType' object is not callable > > 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources Traceback (most > recent call last): > > 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources File > "/opt/barbican/lib/python2.7/site-packages/barbican/tasks/resources.py", > line 91, in process > > 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources entity = > self.retrieve_entity(*args, **kwargs) > > 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources File > "/opt/barbican/lib/python2.7/site-packages/barbican/tasks/keystone_consumer.py", > line 67, in retrieve_entity > > 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources > suppress_exception=True) > > 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources File > "/opt/barbican/lib/python2.7/site-packages/barbican/model/repositories.py", > line 586, in find_by_external_project_id > > 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources session = > self.get_session(session) > > 2018-02-16 15:36:02.677 1 ERROR barbican.tasks.resources File > "/opt/barbican/lib/python2.7/site-packages/barbican/model/repositories