473 lines
17 KiB
Python
473 lines
17 KiB
Python
# -*- coding: utf-8 -*-
|
|
# Copyright 2015 OpenMarket 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.
|
|
|
|
from twisted.internet import defer
|
|
|
|
from synapse.streams.config import PaginationConfig
|
|
from synapse.types import StreamToken, UserID
|
|
|
|
import synapse.util.async
|
|
import baserules
|
|
|
|
import logging
|
|
import simplejson as json
|
|
import re
|
|
import random
|
|
|
|
logger = logging.getLogger(__name__)
|
|
|
|
|
|
class Pusher(object):
|
|
INITIAL_BACKOFF = 1000
|
|
MAX_BACKOFF = 60 * 60 * 1000
|
|
GIVE_UP_AFTER = 24 * 60 * 60 * 1000
|
|
DEFAULT_ACTIONS = ['dont_notify']
|
|
|
|
INEQUALITY_EXPR = re.compile("^([=<>]*)([0-9]*)$")
|
|
|
|
def __init__(self, _hs, profile_tag, user_name, app_id,
|
|
app_display_name, device_display_name, pushkey, pushkey_ts,
|
|
data, last_token, last_success, failing_since):
|
|
self.hs = _hs
|
|
self.evStreamHandler = self.hs.get_handlers().event_stream_handler
|
|
self.store = self.hs.get_datastore()
|
|
self.clock = self.hs.get_clock()
|
|
self.profile_tag = profile_tag
|
|
self.user_name = user_name
|
|
self.app_id = app_id
|
|
self.app_display_name = app_display_name
|
|
self.device_display_name = device_display_name
|
|
self.pushkey = pushkey
|
|
self.pushkey_ts = pushkey_ts
|
|
self.data = data
|
|
self.last_token = last_token
|
|
self.last_success = last_success # not actually used
|
|
self.backoff_delay = Pusher.INITIAL_BACKOFF
|
|
self.failing_since = failing_since
|
|
self.alive = True
|
|
|
|
# The last value of last_active_time that we saw
|
|
self.last_last_active_time = 0
|
|
self.has_unread = True
|
|
|
|
@defer.inlineCallbacks
|
|
def _actions_for_event(self, ev):
|
|
"""
|
|
This should take into account notification settings that the user
|
|
has configured both globally and per-room when we have the ability
|
|
to do such things.
|
|
"""
|
|
if ev['user_id'] == self.user_name:
|
|
# let's assume you probably know about messages you sent yourself
|
|
defer.returnValue(['dont_notify'])
|
|
|
|
rawrules = yield self.store.get_push_rules_for_user(self.user_name)
|
|
|
|
rules = []
|
|
for rawrule in rawrules:
|
|
rule = dict(rawrule)
|
|
rule['conditions'] = json.loads(rawrule['conditions'])
|
|
rule['actions'] = json.loads(rawrule['actions'])
|
|
rules.append(rule)
|
|
|
|
enabled_map = yield self.store.get_push_rules_enabled_for_user(self.user_name)
|
|
|
|
user = UserID.from_string(self.user_name)
|
|
|
|
rules = baserules.list_with_base_rules(rules, user)
|
|
|
|
room_id = ev['room_id']
|
|
|
|
# get *our* member event for display name matching
|
|
my_display_name = None
|
|
our_member_event = yield self.store.get_current_state(
|
|
room_id=room_id,
|
|
event_type='m.room.member',
|
|
state_key=self.user_name,
|
|
)
|
|
if our_member_event:
|
|
my_display_name = our_member_event[0].content.get("displayname")
|
|
|
|
room_members = yield self.store.get_users_in_room(room_id)
|
|
room_member_count = len(room_members)
|
|
|
|
for r in rules:
|
|
if r['rule_id'] in enabled_map:
|
|
r['enabled'] = enabled_map[r['rule_id']]
|
|
elif 'enabled' not in r:
|
|
r['enabled'] = True
|
|
if not r['enabled']:
|
|
continue
|
|
matches = True
|
|
|
|
conditions = r['conditions']
|
|
actions = r['actions']
|
|
|
|
for c in conditions:
|
|
matches &= self._event_fulfills_condition(
|
|
ev, c, display_name=my_display_name,
|
|
room_member_count=room_member_count
|
|
)
|
|
logger.debug(
|
|
"Rule %s %s",
|
|
r['rule_id'], "matches" if matches else "doesn't match"
|
|
)
|
|
# ignore rules with no actions (we have an explict 'dont_notify')
|
|
if len(actions) == 0:
|
|
logger.warn(
|
|
"Ignoring rule id %s with no actions for user %s",
|
|
r['rule_id'], self.user_name
|
|
)
|
|
continue
|
|
if matches:
|
|
logger.info(
|
|
"%s matches for user %s, event %s",
|
|
r['rule_id'], self.user_name, ev['event_id']
|
|
)
|
|
defer.returnValue(actions)
|
|
|
|
logger.info(
|
|
"No rules match for user %s, event %s",
|
|
self.user_name, ev['event_id']
|
|
)
|
|
defer.returnValue(Pusher.DEFAULT_ACTIONS)
|
|
|
|
@staticmethod
|
|
def _glob_to_regexp(glob):
|
|
r = re.escape(glob)
|
|
r = re.sub(r'\\\*', r'.*?', r)
|
|
r = re.sub(r'\\\?', r'.', r)
|
|
|
|
# handle [abc], [a-z] and [!a-z] style ranges.
|
|
r = re.sub(r'\\\[(\\\!|)(.*)\\\]',
|
|
lambda x: ('[%s%s]' % (x.group(1) and '^' or '',
|
|
re.sub(r'\\\-', '-', x.group(2)))), r)
|
|
return r
|
|
|
|
def _event_fulfills_condition(self, ev, condition, display_name, room_member_count):
|
|
if condition['kind'] == 'event_match':
|
|
if 'pattern' not in condition:
|
|
logger.warn("event_match condition with no pattern")
|
|
return False
|
|
# XXX: optimisation: cache our pattern regexps
|
|
if condition['key'] == 'content.body':
|
|
r = r'\b%s\b' % self._glob_to_regexp(condition['pattern'])
|
|
else:
|
|
r = r'^%s$' % self._glob_to_regexp(condition['pattern'])
|
|
val = _value_for_dotted_key(condition['key'], ev)
|
|
if val is None:
|
|
return False
|
|
return re.search(r, val, flags=re.IGNORECASE) is not None
|
|
|
|
elif condition['kind'] == 'device':
|
|
if 'profile_tag' not in condition:
|
|
return True
|
|
return condition['profile_tag'] == self.profile_tag
|
|
|
|
elif condition['kind'] == 'contains_display_name':
|
|
# This is special because display names can be different
|
|
# between rooms and so you can't really hard code it in a rule.
|
|
# Optimisation: we should cache these names and update them from
|
|
# the event stream.
|
|
if 'content' not in ev or 'body' not in ev['content']:
|
|
return False
|
|
if not display_name:
|
|
return False
|
|
return re.search(
|
|
"\b%s\b" % re.escape(display_name), ev['content']['body'],
|
|
flags=re.IGNORECASE
|
|
) is not None
|
|
|
|
elif condition['kind'] == 'room_member_count':
|
|
if 'is' not in condition:
|
|
return False
|
|
m = Pusher.INEQUALITY_EXPR.match(condition['is'])
|
|
if not m:
|
|
return False
|
|
ineq = m.group(1)
|
|
rhs = m.group(2)
|
|
if not rhs.isdigit():
|
|
return False
|
|
rhs = int(rhs)
|
|
|
|
if ineq == '' or ineq == '==':
|
|
return room_member_count == rhs
|
|
elif ineq == '<':
|
|
return room_member_count < rhs
|
|
elif ineq == '>':
|
|
return room_member_count > rhs
|
|
elif ineq == '>=':
|
|
return room_member_count >= rhs
|
|
elif ineq == '<=':
|
|
return room_member_count <= rhs
|
|
else:
|
|
return False
|
|
else:
|
|
return True
|
|
|
|
@defer.inlineCallbacks
|
|
def get_context_for_event(self, ev):
|
|
name_aliases = yield self.store.get_room_name_and_aliases(
|
|
ev['room_id']
|
|
)
|
|
|
|
ctx = {'aliases': name_aliases[1]}
|
|
if name_aliases[0] is not None:
|
|
ctx['name'] = name_aliases[0]
|
|
|
|
their_member_events_for_room = yield self.store.get_current_state(
|
|
room_id=ev['room_id'],
|
|
event_type='m.room.member',
|
|
state_key=ev['user_id']
|
|
)
|
|
for mev in their_member_events_for_room:
|
|
if mev.content['membership'] == 'join' and 'displayname' in mev.content:
|
|
dn = mev.content['displayname']
|
|
if dn is not None:
|
|
ctx['sender_display_name'] = dn
|
|
|
|
defer.returnValue(ctx)
|
|
|
|
@defer.inlineCallbacks
|
|
def start(self):
|
|
if not self.last_token:
|
|
# First-time setup: get a token to start from (we can't
|
|
# just start from no token, ie. 'now'
|
|
# because we need the result to be reproduceable in case
|
|
# we fail to dispatch the push)
|
|
config = PaginationConfig(from_token=None, limit='1')
|
|
chunk = yield self.evStreamHandler.get_stream(
|
|
self.user_name, config, timeout=0)
|
|
self.last_token = chunk['end']
|
|
self.store.update_pusher_last_token(
|
|
self.app_id, self.pushkey, self.user_name, self.last_token
|
|
)
|
|
logger.info("Pusher %s for user %s starting from token %s",
|
|
self.pushkey, self.user_name, self.last_token)
|
|
|
|
wait = 0
|
|
while self.alive:
|
|
try:
|
|
if wait > 0:
|
|
yield synapse.util.async.sleep(wait)
|
|
yield self.get_and_dispatch()
|
|
wait = 0
|
|
except:
|
|
if wait == 0:
|
|
wait = 1
|
|
else:
|
|
wait = min(wait * 2, 1800)
|
|
logger.exception(
|
|
"Exception in pusher loop for pushkey %s. Pausing for %ds",
|
|
self.pushkey, wait
|
|
)
|
|
|
|
@defer.inlineCallbacks
|
|
def get_and_dispatch(self):
|
|
from_tok = StreamToken.from_string(self.last_token)
|
|
config = PaginationConfig(from_token=from_tok, limit='1')
|
|
timeout = (300 + random.randint(-60, 60)) * 1000
|
|
chunk = yield self.evStreamHandler.get_stream(
|
|
self.user_name, config,
|
|
timeout=timeout, affect_presence=False
|
|
)
|
|
|
|
# limiting to 1 may get 1 event plus 1 presence event, so
|
|
# pick out the actual event
|
|
single_event = None
|
|
for c in chunk['chunk']:
|
|
if 'event_id' in c: # Hmmm...
|
|
single_event = c
|
|
break
|
|
if not single_event:
|
|
self.last_token = chunk['end']
|
|
logger.debug("Event stream timeout for pushkey %s", self.pushkey)
|
|
yield self.store.update_pusher_last_token(
|
|
self.app_id,
|
|
self.pushkey,
|
|
self.user_name,
|
|
self.last_token
|
|
)
|
|
return
|
|
|
|
if not self.alive:
|
|
return
|
|
|
|
processed = False
|
|
actions = yield self._actions_for_event(single_event)
|
|
tweaks = _tweaks_for_actions(actions)
|
|
|
|
if len(actions) == 0:
|
|
logger.warn("Empty actions! Using default action.")
|
|
actions = Pusher.DEFAULT_ACTIONS
|
|
|
|
if 'notify' not in actions and 'dont_notify' not in actions:
|
|
logger.warn("Neither notify nor dont_notify in actions: adding default")
|
|
actions.extend(Pusher.DEFAULT_ACTIONS)
|
|
|
|
if 'dont_notify' in actions:
|
|
logger.debug(
|
|
"%s for %s: dont_notify",
|
|
single_event['event_id'], self.user_name
|
|
)
|
|
processed = True
|
|
else:
|
|
rejected = yield self.dispatch_push(single_event, tweaks)
|
|
self.has_unread = True
|
|
if isinstance(rejected, list) or isinstance(rejected, tuple):
|
|
processed = True
|
|
for pk in rejected:
|
|
if pk != self.pushkey:
|
|
# for sanity, we only remove the pushkey if it
|
|
# was the one we actually sent...
|
|
logger.warn(
|
|
("Ignoring rejected pushkey %s because we"
|
|
" didn't send it"), pk
|
|
)
|
|
else:
|
|
logger.info(
|
|
"Pushkey %s was rejected: removing",
|
|
pk
|
|
)
|
|
yield self.hs.get_pusherpool().remove_pusher(
|
|
self.app_id, pk, self.user_name
|
|
)
|
|
|
|
if not self.alive:
|
|
return
|
|
|
|
if processed:
|
|
self.backoff_delay = Pusher.INITIAL_BACKOFF
|
|
self.last_token = chunk['end']
|
|
yield self.store.update_pusher_last_token_and_success(
|
|
self.app_id,
|
|
self.pushkey,
|
|
self.user_name,
|
|
self.last_token,
|
|
self.clock.time_msec()
|
|
)
|
|
if self.failing_since:
|
|
self.failing_since = None
|
|
yield self.store.update_pusher_failing_since(
|
|
self.app_id,
|
|
self.pushkey,
|
|
self.user_name,
|
|
self.failing_since)
|
|
else:
|
|
if not self.failing_since:
|
|
self.failing_since = self.clock.time_msec()
|
|
yield self.store.update_pusher_failing_since(
|
|
self.app_id,
|
|
self.pushkey,
|
|
self.user_name,
|
|
self.failing_since
|
|
)
|
|
|
|
if (self.failing_since and
|
|
self.failing_since <
|
|
self.clock.time_msec() - Pusher.GIVE_UP_AFTER):
|
|
# we really only give up so that if the URL gets
|
|
# fixed, we don't suddenly deliver a load
|
|
# of old notifications.
|
|
logger.warn("Giving up on a notification to user %s, "
|
|
"pushkey %s",
|
|
self.user_name, self.pushkey)
|
|
self.backoff_delay = Pusher.INITIAL_BACKOFF
|
|
self.last_token = chunk['end']
|
|
yield self.store.update_pusher_last_token(
|
|
self.app_id,
|
|
self.pushkey,
|
|
self.user_name,
|
|
self.last_token
|
|
)
|
|
|
|
self.failing_since = None
|
|
yield self.store.update_pusher_failing_since(
|
|
self.app_id,
|
|
self.pushkey,
|
|
self.user_name,
|
|
self.failing_since
|
|
)
|
|
else:
|
|
logger.warn("Failed to dispatch push for user %s "
|
|
"(failing for %dms)."
|
|
"Trying again in %dms",
|
|
self.user_name,
|
|
self.clock.time_msec() - self.failing_since,
|
|
self.backoff_delay)
|
|
yield synapse.util.async.sleep(self.backoff_delay / 1000.0)
|
|
self.backoff_delay *= 2
|
|
if self.backoff_delay > Pusher.MAX_BACKOFF:
|
|
self.backoff_delay = Pusher.MAX_BACKOFF
|
|
|
|
def stop(self):
|
|
self.alive = False
|
|
|
|
def dispatch_push(self, p, tweaks):
|
|
"""
|
|
Overridden by implementing classes to actually deliver the notification
|
|
Args:
|
|
p: The event to notify for as a single event from the event stream
|
|
Returns: If the notification was delivered, an array containing any
|
|
pushkeys that were rejected by the push gateway.
|
|
False if the notification could not be delivered (ie.
|
|
should be retried).
|
|
"""
|
|
pass
|
|
|
|
def reset_badge_count(self):
|
|
pass
|
|
|
|
def presence_changed(self, state):
|
|
"""
|
|
We clear badge counts whenever a user's last_active time is bumped
|
|
This is by no means perfect but I think it's the best we can do
|
|
without read receipts.
|
|
"""
|
|
if 'last_active' in state.state:
|
|
last_active = state.state['last_active']
|
|
if last_active > self.last_last_active_time:
|
|
self.last_last_active_time = last_active
|
|
if self.has_unread:
|
|
logger.info("Resetting badge count for %s", self.user_name)
|
|
self.reset_badge_count()
|
|
self.has_unread = False
|
|
|
|
|
|
def _value_for_dotted_key(dotted_key, event):
|
|
parts = dotted_key.split(".")
|
|
val = event
|
|
while len(parts) > 0:
|
|
if parts[0] not in val:
|
|
return None
|
|
val = val[parts[0]]
|
|
parts = parts[1:]
|
|
return val
|
|
|
|
|
|
def _tweaks_for_actions(actions):
|
|
tweaks = {}
|
|
for a in actions:
|
|
if not isinstance(a, dict):
|
|
continue
|
|
if 'set_tweak' in a and 'value' in a:
|
|
tweaks[a['set_tweak']] = a['value']
|
|
return tweaks
|
|
|
|
|
|
class PusherConfigException(Exception):
|
|
def __init__(self, msg):
|
|
super(PusherConfigException, self).__init__(msg)
|