MatrixSynapse/synapse/handlers/pagination.py

426 lines
15 KiB
Python
Raw Normal View History

2018-07-20 16:32:23 +02:00
# -*- coding: utf-8 -*-
# Copyright 2014 - 2016 OpenMarket Ltd
# Copyright 2017 - 2018 New Vector Ltd
#
# 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.
import logging
from six import iteritems
2018-07-20 16:32:23 +02:00
from twisted.internet import defer
from twisted.python.failure import Failure
2018-08-16 15:22:47 +02:00
from synapse.api.constants import EventTypes, Membership
2018-07-20 16:32:23 +02:00
from synapse.api.errors import SynapseError
from synapse.logging.context import run_in_background
from synapse.metrics.background_process_metrics import run_as_background_process
from synapse.storage.state import StateFilter
2018-07-20 16:32:23 +02:00
from synapse.types import RoomStreamToken
from synapse.util.async_helpers import ReadWriteLock
2018-07-20 16:32:23 +02:00
from synapse.util.stringutils import random_string
from synapse.visibility import filter_events_for_client
logger = logging.getLogger(__name__)
class PurgeStatus(object):
"""Object tracking the status of a purge request
This class contains information on the progress of a purge request, for
return by get_purge_status.
Attributes:
status (int): Tracks whether this request has completed. One of
STATUS_{ACTIVE,COMPLETE,FAILED}
"""
STATUS_ACTIVE = 0
STATUS_COMPLETE = 1
STATUS_FAILED = 2
STATUS_TEXT = {
STATUS_ACTIVE: "active",
STATUS_COMPLETE: "complete",
STATUS_FAILED: "failed",
}
def __init__(self):
self.status = PurgeStatus.STATUS_ACTIVE
def asdict(self):
2019-06-20 11:32:02 +02:00
return {"status": PurgeStatus.STATUS_TEXT[self.status]}
2018-07-20 16:32:23 +02:00
class PaginationHandler(object):
"""Handles pagination and purge history requests.
These are in the same handler due to the fact we need to block clients
paginating during a purge.
"""
def __init__(self, hs):
self.hs = hs
self.auth = hs.get_auth()
self.store = hs.get_datastore()
2019-10-23 18:25:54 +02:00
self.storage = hs.get_storage()
self.state_store = self.storage.state
2018-07-20 16:32:23 +02:00
self.clock = hs.get_clock()
2019-08-22 11:42:59 +02:00
self._server_name = hs.hostname
2018-07-20 16:32:23 +02:00
self.pagination_lock = ReadWriteLock()
self._purges_in_progress_by_room = set()
# map from purge id to PurgeStatus
self._purges_by_id = {}
self._event_serializer = hs.get_event_client_serializer()
2018-07-20 16:32:23 +02:00
self._retention_default_max_lifetime = hs.config.retention_default_max_lifetime
if hs.config.retention_enabled:
# Run the purge jobs described in the configuration file.
for job in hs.config.retention_purge_jobs:
logger.info("Setting up purge job with config: %s", job)
self.clock.looping_call(
run_as_background_process,
job["interval"],
"purge_history_for_rooms_in_range",
self.purge_history_for_rooms_in_range,
job["shortest_max_lifetime"],
job["longest_max_lifetime"],
)
@defer.inlineCallbacks
def purge_history_for_rooms_in_range(self, min_ms, max_ms):
"""Purge outdated events from rooms within the given retention range.
If a default retention policy is defined in the server's configuration and its
'max_lifetime' is within this range, also targets rooms which don't have a
retention policy.
Args:
min_ms (int|None): Duration in milliseconds that define the lower limit of
the range to handle (exclusive). If None, it means that the range has no
lower limit.
max_ms (int|None): Duration in milliseconds that define the upper limit of
the range to handle (inclusive). If None, it means that the range has no
upper limit.
"""
# We want the storage layer to to include rooms with no retention policy in its
# return value only if a default retention policy is defined in the server's
# configuration and that policy's 'max_lifetime' is either lower (or equal) than
# max_ms or higher than min_ms (or both).
if self._retention_default_max_lifetime is not None:
include_null = True
if min_ms is not None and min_ms >= self._retention_default_max_lifetime:
# The default max_lifetime is lower than (or equal to) min_ms.
include_null = False
if max_ms is not None and max_ms < self._retention_default_max_lifetime:
# The default max_lifetime is higher than max_ms.
include_null = False
else:
include_null = False
logger.info(
"[purge] Running purge job for %d < max_lifetime <= %d (include NULLs = %s)",
min_ms,
max_ms,
include_null,
)
rooms = yield self.store.get_rooms_for_retention_period_in_range(
min_ms, max_ms, include_null
)
logger.debug("[purge] Rooms to purge: %s", rooms)
for room_id, retention_policy in iteritems(rooms):
logger.info("[purge] Attempting to purge messages in room %s", room_id)
if room_id in self._purges_in_progress_by_room:
logger.warning(
"[purge] not purging room %s as there's an ongoing purge running"
" for this room",
room_id,
)
continue
max_lifetime = retention_policy["max_lifetime"]
if max_lifetime is None:
# If max_lifetime is None, it means that include_null equals True,
# therefore we can safely assume that there is a default policy defined
# in the server's configuration.
max_lifetime = self._retention_default_max_lifetime
# Figure out what token we should start purging at.
ts = self.clock.time_msec() - max_lifetime
2019-11-19 14:22:37 +01:00
stream_ordering = yield self.store.find_first_stream_ordering_after_ts(ts)
r = yield self.store.get_room_event_before_stream_ordering(
2019-11-19 14:22:37 +01:00
room_id, stream_ordering,
)
if not r:
logger.warning(
"[purge] purging events not possible: No event found "
"(ts %i => stream_ordering %i)",
2019-11-19 14:22:37 +01:00
ts,
stream_ordering,
)
continue
(stream, topo, _event_id) = r
token = "t%d-%d" % (topo, stream)
purge_id = random_string(16)
self._purges_by_id[purge_id] = PurgeStatus()
logger.info(
"Starting purging events in room %s (purge_id %s)" % (room_id, purge_id)
)
# We want to purge everything, including local events, and to run the purge in
# the background so that it's not blocking any other operation apart from
# other purges in the same room.
run_as_background_process(
2019-11-19 14:22:37 +01:00
"_purge_history", self._purge_history, purge_id, room_id, token, True,
)
2019-06-20 11:32:02 +02:00
def start_purge_history(self, room_id, token, delete_local_events=False):
2018-07-20 16:32:23 +02:00
"""Start off a history purge on a room.
Args:
room_id (str): The room to purge from
token (str): topological token to delete events before
delete_local_events (bool): True to delete local events as well as
remote ones
Returns:
str: unique ID for this purge transaction.
"""
if room_id in self._purges_in_progress_by_room:
raise SynapseError(
2019-06-20 11:32:02 +02:00
400, "History purge already in progress for %s" % (room_id,)
2018-07-20 16:32:23 +02:00
)
purge_id = random_string(16)
# we log the purge_id here so that it can be tied back to the
# request id in the log lines.
logger.info("[purge] starting purge_id %s", purge_id)
self._purges_by_id[purge_id] = PurgeStatus()
run_in_background(
2019-06-20 11:32:02 +02:00
self._purge_history, purge_id, room_id, token, delete_local_events
2018-07-20 16:32:23 +02:00
)
return purge_id
@defer.inlineCallbacks
2019-06-20 11:32:02 +02:00
def _purge_history(self, purge_id, room_id, token, delete_local_events):
2018-07-20 16:32:23 +02:00
"""Carry out a history purge on a room.
Args:
purge_id (str): The id for this purge
room_id (str): The room to purge from
token (str): topological token to delete events before
delete_local_events (bool): True to delete local events as well as
remote ones
Returns:
Deferred
"""
self._purges_in_progress_by_room.add(room_id)
try:
with (yield self.pagination_lock.write(room_id)):
2019-10-30 16:12:49 +01:00
yield self.storage.purge_events.purge_history(
room_id, token, delete_local_events
)
2018-07-20 16:32:23 +02:00
logger.info("[purge] complete")
self._purges_by_id[purge_id].status = PurgeStatus.STATUS_COMPLETE
except Exception:
2019-02-25 17:56:41 +01:00
f = Failure()
logger.error(
2019-06-20 11:32:02 +02:00
"[purge] failed", exc_info=(f.type, f.value, f.getTracebackObject())
2019-02-25 17:56:41 +01:00
)
2018-07-20 16:32:23 +02:00
self._purges_by_id[purge_id].status = PurgeStatus.STATUS_FAILED
finally:
self._purges_in_progress_by_room.discard(room_id)
# remove the purge from the list 24 hours after it completes
def clear_purge():
del self._purges_by_id[purge_id]
2019-06-20 11:32:02 +02:00
2018-07-20 16:32:23 +02:00
self.hs.get_reactor().callLater(24 * 3600, clear_purge)
def get_purge_status(self, purge_id):
"""Get the current status of an active purge
Args:
purge_id (str): purge_id returned by start_purge_history
Returns:
PurgeStatus|None
"""
return self._purges_by_id.get(purge_id)
2019-08-22 11:42:59 +02:00
async def purge_room(self, room_id):
"""Purge the given room from the database"""
with (await self.pagination_lock.write(room_id)):
# check we know about the room
await self.store.get_room_version(room_id)
# first check that we have no users in this room
joined = await defer.maybeDeferred(
self.store.is_host_joined, room_id, self._server_name
)
if joined:
raise SynapseError(400, "Users are still joined to this room")
2019-10-30 16:12:49 +01:00
await self.storage.purge_events.purge_room(room_id)
2019-08-22 11:42:59 +02:00
async def get_messages(
2019-06-20 11:32:02 +02:00
self,
requester,
room_id=None,
pagin_config=None,
as_client_event=True,
event_filter=None,
):
2018-07-20 16:32:23 +02:00
"""Get messages in a room.
Args:
requester (Requester): The user requesting messages.
room_id (str): The room they want messages from.
pagin_config (synapse.api.streams.PaginationConfig): The pagination
config rules to apply, if any.
as_client_event (bool): True to get events in client-server format.
event_filter (Filter): Filter to apply to results or None
Returns:
dict: Pagination API results
"""
user_id = requester.user.to_string()
if pagin_config.from_token:
room_token = pagin_config.from_token.room_key
else:
pagin_config.from_token = (
await self.hs.get_event_sources().get_current_token_for_pagination()
2018-07-20 16:32:23 +02:00
)
room_token = pagin_config.from_token.room_key
room_token = RoomStreamToken.parse(room_token)
pagin_config.from_token = pagin_config.from_token.copy_and_replace(
"room_key", str(room_token)
)
source_config = pagin_config.get_source_config("room")
with (await self.pagination_lock.read(room_id)):
(
membership,
member_event_id,
) = await self.auth.check_in_room_or_world_readable(room_id, user_id)
2018-07-20 16:32:23 +02:00
2019-06-20 11:32:02 +02:00
if source_config.direction == "b":
2018-07-20 16:32:23 +02:00
# if we're going backwards, we might need to backfill. This
# requires that we have a topo token.
if room_token.topological:
max_topo = room_token.topological
else:
max_topo = await self.store.get_max_topological_token(
2018-07-20 16:32:23 +02:00
room_id, room_token.stream
)
if membership == Membership.LEAVE:
# If they have left the room then clamp the token to be before
# they left the room, to save the effort of loading from the
# database.
leave_token = await self.store.get_topological_token_for_event(
2018-07-20 16:32:23 +02:00
member_event_id
)
leave_token = RoomStreamToken.parse(leave_token)
if leave_token.topological < max_topo:
source_config.from_key = str(leave_token)
await self.hs.get_handlers().federation_handler.maybe_backfill(
2018-07-20 16:32:23 +02:00
room_id, max_topo
)
events, next_key = await self.store.paginate_room_events(
2018-07-20 16:32:23 +02:00
room_id=room_id,
from_key=source_config.from_key,
to_key=source_config.to_key,
direction=source_config.direction,
limit=source_config.limit,
event_filter=event_filter,
)
2019-06-20 11:32:02 +02:00
next_token = pagin_config.from_token.copy_and_replace("room_key", next_key)
2018-07-20 16:32:23 +02:00
if events:
if event_filter:
events = event_filter.filter(events)
events = await filter_events_for_client(
2019-10-23 18:25:54 +02:00
self.storage, user_id, events, is_peeking=(member_event_id is None)
)
2018-07-20 16:32:23 +02:00
if not events:
return {
"chunk": [],
"start": pagin_config.from_token.to_string(),
"end": next_token.to_string(),
}
2018-07-20 16:32:23 +02:00
2018-08-16 15:22:47 +02:00
state = None
2018-12-06 11:32:05 +01:00
if event_filter and event_filter.lazy_load_members() and len(events) > 0:
2018-08-16 15:22:47 +02:00
# TODO: remove redundant members
# FIXME: we also care about invite targets etc.
state_filter = StateFilter.from_types(
2019-06-20 11:32:02 +02:00
(EventTypes.Member, event.sender) for event in events
)
2018-08-16 15:22:47 +02:00
state_ids = await self.state_store.get_state_ids_for_event(
2019-06-20 11:32:02 +02:00
events[0].event_id, state_filter=state_filter
2018-08-16 15:22:47 +02:00
)
if state_ids:
state = await self.store.get_events(list(state_ids.values()))
state = state.values()
2018-08-16 15:22:47 +02:00
2018-07-20 16:32:23 +02:00
time_now = self.clock.time_msec()
chunk = {
"chunk": (
await self._event_serializer.serialize_events(
2019-06-20 11:32:02 +02:00
events, time_now, as_client_event=as_client_event
)
),
2018-07-20 16:32:23 +02:00
"start": pagin_config.from_token.to_string(),
"end": next_token.to_string(),
}
2018-08-16 15:22:47 +02:00
if state:
chunk["state"] = await self._event_serializer.serialize_events(
state, time_now, as_client_event=as_client_event
)
2018-08-16 15:22:47 +02:00
return chunk