2015-01-27 08:50:28 -07:00
|
|
|
# -*- coding: utf-8 -*-
|
2016-01-06 21:26:29 -07:00
|
|
|
# Copyright 2015, 2016 OpenMarket Ltd
|
2015-01-27 08:50:28 -07:00
|
|
|
#
|
|
|
|
# Licensed under the Apache License, Version 2.0 (the "License");
|
|
|
|
# you may not use this file except in compliance with the License.
|
|
|
|
# You may obtain a copy of the License at
|
|
|
|
#
|
|
|
|
# http://www.apache.org/licenses/LICENSE-2.0
|
|
|
|
#
|
|
|
|
# Unless required by applicable law or agreed to in writing, software
|
|
|
|
# distributed under the License is distributed on an "AS IS" BASIS,
|
|
|
|
# WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
|
|
|
|
# See the License for the specific language governing permissions and
|
|
|
|
# limitations under the License.
|
|
|
|
|
2018-07-09 00:09:20 -06:00
|
|
|
import logging
|
2015-01-27 08:50:28 -07:00
|
|
|
|
2018-07-09 00:09:20 -06:00
|
|
|
from prometheus_client import Counter
|
|
|
|
|
|
|
|
from twisted.internet import defer
|
|
|
|
|
2018-01-15 11:20:30 -07:00
|
|
|
import synapse
|
2015-05-22 07:45:46 -06:00
|
|
|
from synapse.api.constants import EventTypes
|
2019-07-03 08:07:04 -06:00
|
|
|
from synapse.logging.context import make_deferred_yieldable, run_in_background
|
2018-08-07 12:09:48 -06:00
|
|
|
from synapse.metrics import (
|
|
|
|
event_processing_loop_counter,
|
|
|
|
event_processing_loop_room_count,
|
|
|
|
)
|
2018-07-23 17:57:48 -06:00
|
|
|
from synapse.metrics.background_process_metrics import run_as_background_process
|
2018-10-08 07:06:19 -06:00
|
|
|
from synapse.util import log_failure
|
2016-08-17 04:12:29 -06:00
|
|
|
from synapse.util.metrics import Measure
|
2015-01-27 08:50:28 -07:00
|
|
|
|
|
|
|
logger = logging.getLogger(__name__)
|
|
|
|
|
2018-05-21 18:47:37 -06:00
|
|
|
events_processed_counter = Counter("synapse_handlers_appservice_events_processed", "")
|
2018-01-15 11:20:30 -07:00
|
|
|
|
2015-01-27 08:50:28 -07:00
|
|
|
|
2015-02-04 05:24:20 -07:00
|
|
|
class ApplicationServicesHandler(object):
|
2016-05-31 06:53:48 -06:00
|
|
|
def __init__(self, hs):
|
2015-02-04 05:24:20 -07:00
|
|
|
self.store = hs.get_datastore()
|
2016-05-31 06:53:48 -06:00
|
|
|
self.is_mine_id = hs.is_mine_id
|
|
|
|
self.appservice_api = hs.get_application_service_api()
|
|
|
|
self.scheduler = hs.get_application_service_scheduler()
|
2015-03-09 11:01:19 -06:00
|
|
|
self.started_scheduler = False
|
2016-08-17 04:12:29 -06:00
|
|
|
self.clock = hs.get_clock()
|
2016-08-18 07:59:55 -06:00
|
|
|
self.notify_appservices = hs.config.notify_appservices
|
|
|
|
|
|
|
|
self.current_max = 0
|
|
|
|
self.is_processing = False
|
2015-01-27 08:50:28 -07:00
|
|
|
|
2020-07-06 05:40:35 -06:00
|
|
|
async def notify_interested_services(self, current_id):
|
2015-01-27 09:53:59 -07:00
|
|
|
"""Notifies (pushes) all application services interested in this event.
|
|
|
|
|
|
|
|
Pushing is done asynchronously, so this method won't block for any
|
|
|
|
prolonged length of time.
|
|
|
|
|
|
|
|
Args:
|
2016-08-18 04:54:41 -06:00
|
|
|
current_id(int): The current maximum ID.
|
2015-01-27 09:53:59 -07:00
|
|
|
"""
|
2016-10-06 02:43:32 -06:00
|
|
|
services = self.store.get_app_services()
|
2016-08-18 07:59:55 -06:00
|
|
|
if not services or not self.notify_appservices:
|
|
|
|
return
|
|
|
|
|
|
|
|
self.current_max = max(self.current_max, current_id)
|
|
|
|
if self.is_processing:
|
2016-08-18 04:54:41 -06:00
|
|
|
return
|
|
|
|
|
2016-08-17 04:12:29 -06:00
|
|
|
with Measure(self.clock, "notify_interested_services"):
|
2016-08-18 07:59:55 -06:00
|
|
|
self.is_processing = True
|
|
|
|
try:
|
|
|
|
limit = 100
|
|
|
|
while True:
|
2019-10-31 09:43:24 -06:00
|
|
|
(
|
|
|
|
upper_bound,
|
|
|
|
events,
|
2020-07-06 05:40:35 -06:00
|
|
|
) = await self.store.get_new_events_for_appservice(
|
2017-11-02 13:49:43 -06:00
|
|
|
self.current_max, limit
|
2016-08-18 07:59:55 -06:00
|
|
|
)
|
|
|
|
|
|
|
|
if not events:
|
|
|
|
break
|
|
|
|
|
2018-04-11 02:56:00 -06:00
|
|
|
events_by_room = {}
|
2016-08-18 07:59:55 -06:00
|
|
|
for event in events:
|
2018-04-11 02:56:00 -06:00
|
|
|
events_by_room.setdefault(event.room_id, []).append(event)
|
|
|
|
|
2020-07-06 05:40:35 -06:00
|
|
|
async def handle_event(event):
|
2016-08-18 07:59:55 -06:00
|
|
|
# Gather interested services
|
2020-07-06 05:40:35 -06:00
|
|
|
services = await self._get_services_for_event(event)
|
2016-08-18 07:59:55 -06:00
|
|
|
if len(services) == 0:
|
2018-04-11 02:56:00 -06:00
|
|
|
return # no services need notifying
|
2016-08-18 07:59:55 -06:00
|
|
|
|
|
|
|
# Do we know this user exists? If not, poke the user
|
|
|
|
# query API for all services which match that user regex.
|
|
|
|
# This needs to block as these user queries need to be
|
|
|
|
# made BEFORE pushing the event.
|
2020-07-06 05:40:35 -06:00
|
|
|
await self._check_user_exists(event.sender)
|
2016-08-18 07:59:55 -06:00
|
|
|
if event.type == EventTypes.Member:
|
2020-07-06 05:40:35 -06:00
|
|
|
await self._check_user_exists(event.state_key)
|
2016-08-18 07:59:55 -06:00
|
|
|
|
|
|
|
if not self.started_scheduler:
|
2019-06-20 03:32:02 -06:00
|
|
|
|
2018-07-23 17:57:48 -06:00
|
|
|
def start_scheduler():
|
2018-10-08 06:26:54 -06:00
|
|
|
return self.scheduler.start().addErrback(
|
2019-06-20 03:32:02 -06:00
|
|
|
log_failure, "Application Services Failure"
|
2018-10-08 06:26:54 -06:00
|
|
|
)
|
2018-10-08 07:06:19 -06:00
|
|
|
|
2018-07-23 17:57:48 -06:00
|
|
|
run_as_background_process("as_scheduler", start_scheduler)
|
2016-08-18 07:59:55 -06:00
|
|
|
self.started_scheduler = True
|
|
|
|
|
|
|
|
# Fork off pushes to these services
|
|
|
|
for service in services:
|
2018-04-27 05:09:47 -06:00
|
|
|
self.scheduler.submit_event_for_as(service, event)
|
2016-08-18 07:59:55 -06:00
|
|
|
|
2020-06-30 09:58:06 -06:00
|
|
|
now = self.clock.time_msec()
|
2020-07-06 05:40:35 -06:00
|
|
|
ts = await self.store.get_received_ts(event.event_id)
|
2020-06-30 09:58:06 -06:00
|
|
|
synapse.metrics.event_processing_lag_by_event.labels(
|
|
|
|
"appservice_sender"
|
2020-07-01 08:23:58 -06:00
|
|
|
).observe((now - ts) / 1000)
|
2020-06-30 09:58:06 -06:00
|
|
|
|
2020-07-06 05:40:35 -06:00
|
|
|
async def handle_room_events(events):
|
2018-04-11 02:56:00 -06:00
|
|
|
for event in events:
|
2020-07-06 05:40:35 -06:00
|
|
|
await handle_event(event)
|
2018-04-11 02:56:00 -06:00
|
|
|
|
2020-07-06 05:40:35 -06:00
|
|
|
await make_deferred_yieldable(
|
2019-06-20 03:32:02 -06:00
|
|
|
defer.gatherResults(
|
|
|
|
[
|
|
|
|
run_in_background(handle_room_events, evs)
|
2020-06-15 05:03:36 -06:00
|
|
|
for evs in events_by_room.values()
|
2019-06-20 03:32:02 -06:00
|
|
|
],
|
|
|
|
consumeErrors=True,
|
|
|
|
)
|
|
|
|
)
|
2018-04-11 02:56:00 -06:00
|
|
|
|
2020-07-06 05:40:35 -06:00
|
|
|
await self.store.set_appservice_last_pos(upper_bound)
|
2018-04-11 04:07:51 -06:00
|
|
|
|
2018-04-12 04:18:19 -06:00
|
|
|
now = self.clock.time_msec()
|
2020-07-06 05:40:35 -06:00
|
|
|
ts = await self.store.get_received_ts(events[-1].event_id)
|
2018-04-12 04:18:19 -06:00
|
|
|
|
2018-05-22 16:32:57 -06:00
|
|
|
synapse.metrics.event_processing_positions.labels(
|
2019-06-20 03:32:02 -06:00
|
|
|
"appservice_sender"
|
|
|
|
).set(upper_bound)
|
2018-04-11 04:52:19 -06:00
|
|
|
|
2018-05-21 18:47:37 -06:00
|
|
|
events_processed_counter.inc(len(events))
|
2018-04-11 04:52:19 -06:00
|
|
|
|
2019-06-20 03:32:02 -06:00
|
|
|
event_processing_loop_room_count.labels("appservice_sender").inc(
|
|
|
|
len(events_by_room)
|
|
|
|
)
|
2018-08-07 12:09:48 -06:00
|
|
|
|
|
|
|
event_processing_loop_counter.labels("appservice_sender").inc()
|
|
|
|
|
2018-05-22 16:32:57 -06:00
|
|
|
synapse.metrics.event_processing_lag.labels(
|
2019-06-20 03:32:02 -06:00
|
|
|
"appservice_sender"
|
|
|
|
).set(now - ts)
|
2018-05-22 16:32:57 -06:00
|
|
|
synapse.metrics.event_processing_last_ts.labels(
|
2019-06-20 03:32:02 -06:00
|
|
|
"appservice_sender"
|
|
|
|
).set(ts)
|
2016-08-18 07:59:55 -06:00
|
|
|
finally:
|
|
|
|
self.is_processing = False
|
2015-02-05 03:08:12 -07:00
|
|
|
|
2020-07-06 05:40:35 -06:00
|
|
|
async def query_user_exists(self, user_id):
|
2015-02-05 07:17:08 -07:00
|
|
|
"""Check if any application service knows this user_id exists.
|
2015-02-05 06:19:46 -07:00
|
|
|
|
|
|
|
Args:
|
2015-02-05 07:17:08 -07:00
|
|
|
user_id(str): The user to query if they exist on any AS.
|
2015-02-05 06:19:46 -07:00
|
|
|
Returns:
|
2015-02-05 07:17:08 -07:00
|
|
|
True if this user exists on at least one application service.
|
2015-02-05 06:19:46 -07:00
|
|
|
"""
|
2020-07-06 05:40:35 -06:00
|
|
|
user_query_services = self._get_services_for_user(user_id=user_id)
|
2015-02-05 06:19:46 -07:00
|
|
|
for user_service in user_query_services:
|
2020-07-06 05:40:35 -06:00
|
|
|
is_known_user = await self.appservice_api.query_user(user_service, user_id)
|
2015-02-05 06:19:46 -07:00
|
|
|
if is_known_user:
|
2019-07-23 07:00:55 -06:00
|
|
|
return True
|
|
|
|
return False
|
2015-02-05 04:47:11 -07:00
|
|
|
|
2020-07-06 05:40:35 -06:00
|
|
|
async def query_room_alias_exists(self, room_alias):
|
2015-02-05 04:47:11 -07:00
|
|
|
"""Check if an application service knows this room alias exists.
|
|
|
|
|
|
|
|
Args:
|
2015-02-05 06:19:46 -07:00
|
|
|
room_alias(RoomAlias): The room alias to query.
|
2015-02-05 04:47:11 -07:00
|
|
|
Returns:
|
|
|
|
namedtuple: with keys "room_id" and "servers" or None if no
|
|
|
|
association can be found.
|
|
|
|
"""
|
2015-02-09 08:01:28 -07:00
|
|
|
room_alias_str = room_alias.to_string()
|
2016-10-06 02:43:32 -06:00
|
|
|
services = self.store.get_app_services()
|
2016-08-17 10:20:50 -06:00
|
|
|
alias_query_services = [
|
2019-06-20 03:32:02 -06:00
|
|
|
s for s in services if (s.is_interested_in_alias(room_alias_str))
|
2016-08-17 10:20:50 -06:00
|
|
|
]
|
2015-02-05 04:47:11 -07:00
|
|
|
for alias_service in alias_query_services:
|
2020-07-06 05:40:35 -06:00
|
|
|
is_known_alias = await self.appservice_api.query_alias(
|
2015-02-09 08:01:28 -07:00
|
|
|
alias_service, room_alias_str
|
2015-02-05 04:47:11 -07:00
|
|
|
)
|
|
|
|
if is_known_alias:
|
|
|
|
# the alias exists now so don't query more ASes.
|
2020-07-06 05:40:35 -06:00
|
|
|
result = await self.store.get_association_from_room_alias(room_alias)
|
2019-07-23 07:00:55 -06:00
|
|
|
return result
|
2015-02-05 04:47:11 -07:00
|
|
|
|
2020-07-06 05:40:35 -06:00
|
|
|
async def query_3pe(self, kind, protocol, fields):
|
|
|
|
services = self._get_services_for_3pn(protocol)
|
2016-08-17 06:15:06 -06:00
|
|
|
|
2020-07-06 05:40:35 -06:00
|
|
|
results = await make_deferred_yieldable(
|
2019-06-20 03:32:02 -06:00
|
|
|
defer.DeferredList(
|
|
|
|
[
|
|
|
|
run_in_background(
|
|
|
|
self.appservice_api.query_3pe, service, kind, protocol, fields
|
|
|
|
)
|
|
|
|
for service in services
|
|
|
|
],
|
|
|
|
consumeErrors=True,
|
2018-04-27 04:29:27 -06:00
|
|
|
)
|
2019-06-20 03:32:02 -06:00
|
|
|
)
|
2016-08-18 08:40:41 -06:00
|
|
|
|
2016-08-18 09:09:50 -06:00
|
|
|
ret = []
|
|
|
|
for (success, result) in results:
|
2016-08-18 10:33:56 -06:00
|
|
|
if success:
|
|
|
|
ret.extend(result)
|
2016-08-17 06:15:06 -06:00
|
|
|
|
2019-07-23 07:00:55 -06:00
|
|
|
return ret
|
2016-08-17 06:15:06 -06:00
|
|
|
|
2020-07-06 05:40:35 -06:00
|
|
|
async def get_3pe_protocols(self, only_protocol=None):
|
2016-10-06 02:43:32 -06:00
|
|
|
services = self.store.get_app_services()
|
2016-08-24 06:01:53 -06:00
|
|
|
protocols = {}
|
2016-09-08 10:43:53 -06:00
|
|
|
|
|
|
|
# Collect up all the individual protocol responses out of the ASes
|
2016-08-24 05:33:01 -06:00
|
|
|
for s in services:
|
2016-08-24 06:01:53 -06:00
|
|
|
for p in s.protocols:
|
2016-09-09 06:25:02 -06:00
|
|
|
if only_protocol is not None and p != only_protocol:
|
|
|
|
continue
|
|
|
|
|
2016-09-08 10:43:53 -06:00
|
|
|
if p not in protocols:
|
|
|
|
protocols[p] = []
|
2016-09-09 08:07:04 -06:00
|
|
|
|
2020-07-06 05:40:35 -06:00
|
|
|
info = await self.appservice_api.get_3pe_protocol(s, p)
|
2016-09-09 08:07:04 -06:00
|
|
|
|
|
|
|
if info is not None:
|
|
|
|
protocols[p].append(info)
|
2016-09-08 10:43:53 -06:00
|
|
|
|
|
|
|
def _merge_instances(infos):
|
2016-09-09 07:54:16 -06:00
|
|
|
if not infos:
|
2016-09-08 10:43:53 -06:00
|
|
|
return {}
|
|
|
|
|
|
|
|
# Merge the 'instances' lists of multiple results, but just take
|
|
|
|
# the other fields from the first as they ought to be identical
|
2016-09-09 07:54:16 -06:00
|
|
|
# copy the result so as not to corrupt the cached one
|
2016-09-08 10:43:53 -06:00
|
|
|
combined = dict(infos[0])
|
2016-09-09 06:10:36 -06:00
|
|
|
combined["instances"] = list(combined["instances"])
|
2016-09-08 10:43:53 -06:00
|
|
|
|
|
|
|
for info in infos[1:]:
|
|
|
|
combined["instances"].extend(info["instances"])
|
|
|
|
|
|
|
|
return combined
|
|
|
|
|
|
|
|
for p in protocols.keys():
|
|
|
|
protocols[p] = _merge_instances(protocols[p])
|
2016-08-24 06:01:53 -06:00
|
|
|
|
2019-07-23 07:00:55 -06:00
|
|
|
return protocols
|
2016-08-24 05:33:01 -06:00
|
|
|
|
2020-07-06 05:40:35 -06:00
|
|
|
async def _get_services_for_event(self, event):
|
2015-02-05 04:47:11 -07:00
|
|
|
"""Retrieve a list of application services interested in this event.
|
|
|
|
|
|
|
|
Args:
|
|
|
|
event(Event): The event to check. Can be None if alias_list is not.
|
|
|
|
Returns:
|
|
|
|
list<ApplicationService>: A list of services interested in this
|
|
|
|
event based on the service regex.
|
|
|
|
"""
|
2016-10-06 02:43:32 -06:00
|
|
|
services = self.store.get_app_services()
|
2018-04-28 05:04:40 -06:00
|
|
|
|
|
|
|
# we can't use a list comprehension here. Since python 3, list
|
|
|
|
# comprehensions use a generator internally. This means you can't yield
|
|
|
|
# inside of a list comprehension anymore.
|
|
|
|
interested_list = []
|
|
|
|
for s in services:
|
2020-07-06 05:40:35 -06:00
|
|
|
if await s.is_interested(event, self.store):
|
2018-04-28 05:04:40 -06:00
|
|
|
interested_list.append(s)
|
|
|
|
|
2019-07-23 07:00:55 -06:00
|
|
|
return interested_list
|
2015-02-05 04:47:11 -07:00
|
|
|
|
2015-02-05 07:17:08 -07:00
|
|
|
def _get_services_for_user(self, user_id):
|
2016-10-06 02:43:32 -06:00
|
|
|
services = self.store.get_app_services()
|
2019-06-20 03:32:02 -06:00
|
|
|
interested_list = [s for s in services if (s.is_interested_in_user(user_id))]
|
2020-07-06 05:40:35 -06:00
|
|
|
return interested_list
|
2015-02-05 07:17:08 -07:00
|
|
|
|
2016-08-17 06:15:06 -06:00
|
|
|
def _get_services_for_3pn(self, protocol):
|
2016-10-06 02:43:32 -06:00
|
|
|
services = self.store.get_app_services()
|
2019-06-20 03:32:02 -06:00
|
|
|
interested_list = [s for s in services if s.is_interested_in_protocol(protocol)]
|
2020-07-06 05:40:35 -06:00
|
|
|
return interested_list
|
2016-08-17 06:15:06 -06:00
|
|
|
|
2020-07-06 05:40:35 -06:00
|
|
|
async def _is_unknown_user(self, user_id):
|
2016-05-31 06:53:48 -06:00
|
|
|
if not self.is_mine_id(user_id):
|
2015-02-05 04:25:32 -07:00
|
|
|
# we don't know if they are unknown or not since it isn't one of our
|
|
|
|
# users. We can't poke ASes.
|
2019-07-23 07:00:55 -06:00
|
|
|
return False
|
2015-02-05 04:25:32 -07:00
|
|
|
|
2020-07-06 05:40:35 -06:00
|
|
|
user_info = await self.store.get_user_by_id(user_id)
|
2015-06-17 10:26:03 -06:00
|
|
|
if user_info:
|
2019-07-23 07:00:55 -06:00
|
|
|
return False
|
2015-04-01 07:05:24 -06:00
|
|
|
|
|
|
|
# user not found; could be the AS though, so check.
|
2016-10-06 02:43:32 -06:00
|
|
|
services = self.store.get_app_services()
|
2015-04-01 07:05:24 -06:00
|
|
|
service_list = [s for s in services if s.sender == user_id]
|
2019-07-23 07:00:55 -06:00
|
|
|
return len(service_list) == 0
|
2015-02-05 04:25:32 -07:00
|
|
|
|
2020-07-06 05:40:35 -06:00
|
|
|
async def _check_user_exists(self, user_id):
|
|
|
|
unknown_user = await self._is_unknown_user(user_id)
|
2015-02-05 07:17:08 -07:00
|
|
|
if unknown_user:
|
2020-07-06 05:40:35 -06:00
|
|
|
exists = await self.query_user_exists(user_id)
|
2019-07-23 07:00:55 -06:00
|
|
|
return exists
|
|
|
|
return True
|