microblog.pub/tasks.py

486 lines
18 KiB
Python
Raw Normal View History

2018-06-01 13:29:44 -05:00
import json
2018-05-18 13:41:41 -05:00
import logging
2018-06-16 15:02:10 -05:00
import os
2018-05-18 13:41:41 -05:00
import random
import requests
from celery import Celery
2018-07-11 16:22:47 -05:00
from little_boxes import activitypub as ap
2018-09-03 13:21:33 -05:00
from little_boxes.errors import BadActivityError
2018-07-12 17:44:33 -05:00
from little_boxes.errors import ActivityGoneError
from little_boxes.errors import ActivityNotFoundError
2018-07-22 05:04:18 -05:00
from little_boxes.errors import NotAnActivityError
2018-07-11 16:22:47 -05:00
from little_boxes.httpsig import HTTPSigAuth
from little_boxes.linked_data_sig import generate_signature
2018-05-18 13:41:41 -05:00
from requests.exceptions import HTTPError
2018-07-11 16:22:47 -05:00
import activitypub
2018-07-29 09:07:27 -05:00
from activitypub import Box
2018-06-16 15:02:10 -05:00
from config import DB
2018-05-18 13:41:41 -05:00
from config import HEADERS
2018-07-29 09:07:27 -05:00
from config import ME
2018-07-20 18:42:59 -05:00
from config import ID
2018-05-18 13:41:41 -05:00
from config import KEY
2018-07-11 16:22:47 -05:00
from config import MEDIA_CACHE
2018-05-18 13:41:41 -05:00
from config import USER_AGENT
2018-08-05 06:49:49 -05:00
from config import BASE_URL
2018-07-21 16:16:40 -05:00
from utils import opengraph
2018-07-11 14:53:47 -05:00
from utils.media import Kind
2018-05-18 13:41:41 -05:00
2018-05-28 12:46:23 -05:00
log = logging.getLogger(__name__)
2018-06-16 15:02:10 -05:00
app = Celery(
"tasks", broker=os.getenv("MICROBLOGPUB_AMQP_BROKER", "pyamqp://guest@localhost//")
)
2018-06-16 15:33:51 -05:00
SigAuth = HTTPSigAuth(KEY)
2018-05-18 13:41:41 -05:00
2018-07-11 16:22:47 -05:00
back = activitypub.MicroblogPubBackend()
ap.use_backend(back)
2018-07-29 09:40:33 -05:00
2018-07-29 09:07:27 -05:00
MY_PERSON = ap.Person(**ME)
2018-08-06 02:42:32 -05:00
MAX_RETRIES = 9
2018-07-11 16:22:47 -05:00
2018-08-06 02:42:32 -05:00
@app.task(bind=True, max_retries=MAX_RETRIES) # noqa: C901
2018-07-11 14:53:47 -05:00
def process_new_activity(self, iri: str) -> None:
2018-07-20 18:42:59 -05:00
"""Process an activity received in the inbox"""
2018-07-11 14:53:47 -05:00
try:
activity = ap.fetch_remote_activity(iri)
log.info(f"activity={activity!r}")
2018-07-14 05:14:08 -05:00
# Is the activity expected?
# following = ap.get_backend().following()
2018-07-21 14:05:35 -05:00
should_forward = False
2018-07-22 05:04:18 -05:00
should_delete = False
2018-07-14 05:14:08 -05:00
2018-07-11 14:53:47 -05:00
tag_stream = False
2018-07-11 16:22:47 -05:00
if activity.has_type(ap.ActivityType.ANNOUNCE):
2018-07-22 05:04:18 -05:00
try:
activity.get_object()
tag_stream = True
2018-09-03 13:21:33 -05:00
except (NotAnActivityError, BadActivityError):
log.exception(f"failed to get announce object for {activity!r}")
2018-07-22 05:04:18 -05:00
# Most likely on OStatus notice
tag_stream = False
should_delete = True
2018-08-05 06:49:49 -05:00
except (ActivityGoneError, ActivityNotFoundError):
# The announced activity is deleted/gone, drop it
should_delete = True
2018-07-21 14:05:35 -05:00
2018-07-11 14:53:47 -05:00
elif activity.has_type(ap.ActivityType.CREATE):
note = activity.get_object()
2018-07-21 14:05:35 -05:00
# Make the note part of the stream if it's not a reply, or if it's a local reply
if not note.inReplyTo or note.inReplyTo.startswith(ID):
tag_stream = True
2018-07-20 18:42:59 -05:00
if note.inReplyTo:
2018-07-22 05:04:18 -05:00
try:
reply = ap.fetch_remote_activity(note.inReplyTo)
if (
reply.id.startswith(ID) or reply.has_mention(ID)
) and activity.is_public():
# The reply is public "local reply", forward the reply (i.e. the original activity) to the
# original recipients
should_forward = True
except NotAnActivityError:
# Most likely a reply to an OStatus notce
should_delete = True
2018-07-20 19:06:45 -05:00
# (partial) Ghost replies handling
# [X] This is the first time the server has seen this Activity.
should_forward = False
local_followers = ID + "/followers"
for field in ["to", "cc"]:
if field in activity._data:
if local_followers in activity._data[field]:
# [X] The values of to, cc, and/or audience contain a Collection owned by the server.
should_forward = True
2018-07-21 14:05:35 -05:00
# [X] The values of inReplyTo, object, target and/or tag are objects owned by the server
2018-07-20 19:06:45 -05:00
if not (note.inReplyTo and note.inReplyTo.startswith(ID)):
should_forward = False
2018-07-21 14:05:35 -05:00
elif activity.has_type(ap.ActivityType.DELETE):
note = DB.activities.find_one(
{"activity.object.id": activity.get_object().id}
)
2018-07-22 05:04:18 -05:00
if note and note["meta"].get("forwarded", False):
2018-07-21 14:05:35 -05:00
# If the activity was originally forwarded, forward the delete too
should_forward = True
2018-08-05 06:49:49 -05:00
elif activity.has_type(ap.ActivityType.LIKE):
2018-08-05 12:31:47 -05:00
if not activity.get_object_id().startswith(BASE_URL):
2018-08-05 06:49:49 -05:00
# We only want to keep a like if it's a like for a local activity
# (Pleroma relay the likes it received, we don't want to store them)
should_delete = True
2018-07-21 14:05:35 -05:00
if should_forward:
log.info(f"will forward {activity!r} to followers")
2018-07-30 03:06:44 -05:00
forward_activity.delay(activity.id)
2018-07-11 14:53:47 -05:00
2018-07-22 05:04:18 -05:00
if should_delete:
log.info(f"will soft delete {activity!r}")
2018-07-11 14:53:47 -05:00
log.info(f"{iri} tag_stream={tag_stream}")
2018-07-11 16:22:47 -05:00
DB.activities.update_one(
2018-07-21 14:05:35 -05:00
{"remote_id": activity.id},
2018-07-22 05:04:18 -05:00
{
"$set": {
"meta.stream": tag_stream,
"meta.forwarded": should_forward,
"meta.deleted": should_delete,
}
},
2018-07-11 16:22:47 -05:00
)
2018-07-11 14:53:47 -05:00
log.info(f"new activity {iri} processed")
2018-08-05 06:49:49 -05:00
if not should_delete and not activity.has_type(ap.ActivityType.DELETE):
2018-07-22 05:53:09 -05:00
cache_actor.delay(iri)
2018-07-12 17:44:33 -05:00
except (ActivityGoneError, ActivityNotFoundError):
2018-07-19 18:12:02 -05:00
log.exception(f"dropping activity {iri}, skip processing")
2018-07-11 14:53:47 -05:00
except Exception as err:
log.exception(f"failed to process new activity {iri}")
self.retry(exc=err, countdown=int(random.uniform(2, 4) ** self.request.retries))
2018-08-06 02:42:32 -05:00
@app.task(bind=True, max_retries=MAX_RETRIES) # noqa: C901
2018-07-21 16:16:40 -05:00
def fetch_og_metadata(self, iri: str) -> None:
try:
activity = ap.fetch_remote_activity(iri)
log.info(f"activity={activity!r}")
if activity.has_type(ap.ActivityType.CREATE):
note = activity.get_object()
links = opengraph.links_from_note(note.to_dict())
og_metadata = opengraph.fetch_og_metadata(USER_AGENT, links)
for og in og_metadata:
if not og.get("image"):
continue
MEDIA_CACHE.cache_og_image(og["image"])
log.debug(f"OG metadata {og_metadata!r}")
DB.activities.update_one(
{"remote_id": iri}, {"$set": {"meta.og_metadata": og_metadata}}
)
log.info(f"OG metadata fetched for {iri}")
except (ActivityGoneError, ActivityNotFoundError):
log.exception(f"dropping activity {iri}, skip OG metedata")
except requests.exceptions.HTTPError as http_err:
if 400 <= http_err.response.status_code < 500:
log.exception("bad request, no retry")
2018-07-23 16:50:22 -05:00
return
2018-07-21 16:16:40 -05:00
log.exception("failed to fetch OG metadata")
self.retry(
exc=http_err, countdown=int(random.uniform(2, 4) ** self.request.retries)
)
except Exception as err:
log.exception(f"failed to fetch OG metadata for {iri}")
self.retry(exc=err, countdown=int(random.uniform(2, 4) ** self.request.retries))
2018-08-06 02:42:32 -05:00
@app.task(bind=True, max_retries=MAX_RETRIES)
2018-07-30 11:12:27 -05:00
def cache_object(self, iri: str) -> None:
try:
activity = ap.fetch_remote_activity(iri)
log.info(f"activity={activity!r}")
obj = activity.get_object()
DB.activities.update_one(
{"remote_id": activity.id},
{
"$set": {
"meta.object": obj.to_dict(embed=True),
"meta.object_actor": activitypub._actor_to_meta(obj.get_actor()),
}
},
)
except (ActivityGoneError, ActivityNotFoundError, NotAnActivityError):
DB.activities.update_one({"remote_id": iri}, {"$set": {"meta.deleted": True}})
log.exception(f"flagging activity {iri} as deleted, no object caching")
except Exception as err:
log.exception(f"failed to cache object for {iri}")
self.retry(exc=err, countdown=int(random.uniform(2, 4) ** self.request.retries))
2018-08-06 02:42:32 -05:00
@app.task(bind=True, max_retries=MAX_RETRIES)
2018-07-19 18:12:02 -05:00
def cache_actor(self, iri: str, also_cache_attachments: bool = True) -> None:
try:
activity = ap.fetch_remote_activity(iri)
log.info(f"activity={activity!r}")
2018-07-21 16:16:40 -05:00
if activity.has_type(ap.ActivityType.CREATE):
fetch_og_metadata.delay(iri)
2018-07-30 11:12:27 -05:00
if activity.has_type([ap.ActivityType.LIKE, ap.ActivityType.ANNOUNCE]):
cache_object.delay(iri)
2018-07-19 18:12:02 -05:00
actor = activity.get_actor()
cache_actor_with_inbox = False
if activity.has_type(ap.ActivityType.FOLLOW):
if actor.id != ID:
# It's a Follow from the Inbox
cache_actor_with_inbox = True
else:
# It's a new following, cache the "object" (which is the actor we follow)
DB.activities.update_one(
{"remote_id": iri},
{
"$set": {
"meta.object": activitypub._actor_to_meta(
activity.get_object()
)
}
},
)
2018-07-19 18:12:02 -05:00
# Cache the actor info
DB.activities.update_one(
2018-07-20 18:05:51 -05:00
{"remote_id": iri},
{
"$set": {
"meta.actor": activitypub._actor_to_meta(
actor, cache_actor_with_inbox
)
}
},
2018-07-19 18:12:02 -05:00
)
log.info(f"actor cached for {iri}")
2018-08-05 06:49:49 -05:00
if also_cache_attachments and activity.has_type(ap.ActivityType.CREATE):
2018-07-19 18:12:02 -05:00
cache_attachments.delay(iri)
except (ActivityGoneError, ActivityNotFoundError):
DB.activities.update_one({"remote_id": iri}, {"$set": {"meta.deleted": True}})
log.exception(f"flagging activity {iri} as deleted, no actor caching")
except Exception as err:
log.exception(f"failed to cache actor for {iri}")
self.retry(exc=err, countdown=int(random.uniform(2, 4) ** self.request.retries))
2018-08-06 02:42:32 -05:00
@app.task(bind=True, max_retries=MAX_RETRIES)
2018-07-11 14:53:47 -05:00
def cache_attachments(self, iri: str) -> None:
try:
activity = ap.fetch_remote_activity(iri)
log.info(f"activity={activity!r}")
# Generates thumbnails for the actor's icon and the attachments if any
actor = activity.get_actor()
# Update the cached actor
DB.actors.update_one(
{"remote_id": iri},
{"$set": {"remote_id": iri, "data": actor.to_dict(embed=True)}},
upsert=True,
)
if actor.icon:
MEDIA_CACHE.cache(actor.icon["url"], Kind.ACTOR_ICON)
2018-07-11 15:52:20 -05:00
2018-07-11 14:53:47 -05:00
if activity.has_type(ap.ActivityType.CREATE):
for attachment in activity.get_object()._data.get("attachment", []):
if (
attachment.get("mediaType", "").startswith("image/")
or attachment.get("type") == ap.ActivityType.IMAGE.value
):
2018-07-24 16:58:13 -05:00
try:
MEDIA_CACHE.cache(attachment["url"], Kind.ATTACHMENT)
except ValueError:
log.exception(f"failed to cache {attachment}")
2018-07-11 14:53:47 -05:00
2018-07-11 16:22:47 -05:00
log.info(f"attachments cached for {iri}")
2018-07-11 14:53:47 -05:00
2018-08-06 02:42:32 -05:00
except (ActivityGoneError, ActivityNotFoundError, NotAnActivityError):
2018-07-19 18:12:02 -05:00
log.exception(f"dropping activity {iri}, no attachment caching")
2018-07-11 14:53:47 -05:00
except Exception as err:
2018-07-19 18:12:02 -05:00
log.exception(f"failed to cache attachments for {iri}")
2018-07-11 14:53:47 -05:00
self.retry(exc=err, countdown=int(random.uniform(2, 4) ** self.request.retries))
2018-07-29 09:07:27 -05:00
def post_to_inbox(activity: ap.BaseActivity) -> None:
# Check for Block activity
actor = activity.get_actor()
if back.outbox_is_blocked(MY_PERSON, actor.id):
log.info(
f"actor {actor!r} is blocked, dropping the received activity {activity!r}"
)
2018-07-29 09:58:25 -05:00
return
2018-07-29 09:07:27 -05:00
if back.inbox_check_duplicate(MY_PERSON, activity.id):
# The activity is already in the inbox
log.info(f"received duplicate activity {activity!r}, dropping it")
back.save(Box.INBOX, activity)
2018-08-05 06:49:49 -05:00
process_new_activity.delay(activity.id)
2018-07-29 09:58:25 -05:00
log.info(f"spawning task for {activity!r}")
2018-07-29 09:07:27 -05:00
finish_post_to_inbox.delay(activity.id)
2018-09-03 13:21:33 -05:00
def invalidate_cache(activity):
if activity.has_type(ap.ActivityType.LIKE):
if activity.get_object().id.startswith(BASE_URL):
DB.cache2.remove()
elif activity.has_type(ap.ActivityType.ANNOUNCE):
2018-09-05 14:47:11 -05:00
if activity.get_object().id.startswith(BASE_URL):
2018-09-03 13:21:33 -05:00
DB.cache2.remove()
elif activity.has_type(ap.ActivityType.UNDO):
DB.cache2.remove()
elif activity.has_type(ap.ActivityType.DELETE):
# TODO(tsileo): only invalidate if it's a delete of a reply
DB.cache2.remove()
elif activity.has_type(ap.ActivityType.UPDATE):
DB.cache2.remove()
elif activity.has_type(ap.ActivityType.CREATE):
note = activity.get_object()
if not note.inReplyTo or note.inReplyTo.startswith(ID):
DB.cache2.remove()
# FIXME(tsileo): check if it's a reply of a reply
2019-04-05 04:35:48 -05:00
2018-08-06 02:42:32 -05:00
@app.task(bind=True, max_retries=MAX_RETRIES) # noqa: C901
2018-07-29 09:07:27 -05:00
def finish_post_to_inbox(self, iri: str) -> None:
try:
activity = ap.fetch_remote_activity(iri)
log.info(f"activity={activity!r}")
if activity.has_type(ap.ActivityType.DELETE):
back.inbox_delete(MY_PERSON, activity)
elif activity.has_type(ap.ActivityType.UPDATE):
back.inbox_update(MY_PERSON, activity)
elif activity.has_type(ap.ActivityType.CREATE):
back.inbox_create(MY_PERSON, activity)
elif activity.has_type(ap.ActivityType.ANNOUNCE):
back.inbox_announce(MY_PERSON, activity)
elif activity.has_type(ap.ActivityType.LIKE):
back.inbox_like(MY_PERSON, activity)
elif activity.has_type(ap.ActivityType.FOLLOW):
# Reply to a Follow with an Accept
accept = ap.Accept(actor=ID, object=activity.to_dict(embed=True))
post_to_outbox(accept)
elif activity.has_type(ap.ActivityType.UNDO):
obj = activity.get_object()
if obj.has_type(ap.ActivityType.LIKE):
back.inbox_undo_like(MY_PERSON, obj)
elif obj.has_type(ap.ActivityType.ANNOUNCE):
back.inbox_undo_announce(MY_PERSON, obj)
elif obj.has_type(ap.ActivityType.FOLLOW):
back.undo_new_follower(MY_PERSON, obj)
2018-09-03 13:21:33 -05:00
try:
invalidate_cache(activity)
except Exception:
log.exception("failed to invalidate cache")
2018-08-06 02:42:32 -05:00
except (ActivityGoneError, ActivityNotFoundError, NotAnActivityError):
2018-08-05 07:21:01 -05:00
log.exception(f"no retry")
2018-07-29 09:07:27 -05:00
except Exception as err:
log.exception(f"failed to cache attachments for {iri}")
self.retry(exc=err, countdown=int(random.uniform(2, 4) ** self.request.retries))
def post_to_outbox(activity: ap.BaseActivity) -> str:
if activity.has_type(ap.CREATE_TYPES):
activity = activity.build_create()
# Assign create a random ID
obj_id = back.random_object_id()
activity.set_id(back.activity_url(obj_id), obj_id)
back.save(Box.OUTBOX, activity)
2018-08-05 06:49:49 -05:00
cache_actor.delay(activity.id)
2018-07-29 09:07:27 -05:00
finish_post_to_outbox.delay(activity.id)
return activity.id
2018-08-06 02:42:32 -05:00
@app.task(bind=True, max_retries=MAX_RETRIES) # noqa:C901
2018-07-29 09:07:27 -05:00
def finish_post_to_outbox(self, iri: str) -> None:
try:
activity = ap.fetch_remote_activity(iri)
log.info(f"activity={activity!r}")
2018-07-29 11:23:29 -05:00
recipients = activity.recipients()
2018-07-29 09:40:33 -05:00
if activity.has_type(ap.ActivityType.DELETE):
2018-07-29 09:07:27 -05:00
back.outbox_delete(MY_PERSON, activity)
elif activity.has_type(ap.ActivityType.UPDATE):
back.outbox_update(MY_PERSON, activity)
elif activity.has_type(ap.ActivityType.CREATE):
back.outbox_create(MY_PERSON, activity)
elif activity.has_type(ap.ActivityType.ANNOUNCE):
back.outbox_announce(MY_PERSON, activity)
elif activity.has_type(ap.ActivityType.LIKE):
back.outbox_like(MY_PERSON, activity)
elif activity.has_type(ap.ActivityType.UNDO):
obj = activity.get_object()
if obj.has_type(ap.ActivityType.LIKE):
back.outbox_undo_like(MY_PERSON, obj)
elif obj.has_type(ap.ActivityType.ANNOUNCE):
back.outbox_undo_announce(MY_PERSON, obj)
elif obj.has_type(ap.ActivityType.FOLLOW):
back.undo_new_following(MY_PERSON, obj)
log.info(f"recipients={recipients}")
activity = ap.clean_activity(activity.to_dict())
2018-09-03 13:21:33 -05:00
DB.cache2.remove()
2018-07-29 09:07:27 -05:00
payload = json.dumps(activity)
for recp in recipients:
log.debug(f"posting to {recp}")
post_to_remote_inbox.delay(payload, recp)
2018-08-05 07:21:01 -05:00
except (ActivityGoneError, ActivityNotFoundError):
log.exception(f"no retry")
2018-07-30 03:06:44 -05:00
except Exception as err:
log.exception(f"failed to post to remote inbox for {iri}")
self.retry(exc=err, countdown=int(random.uniform(2, 4) ** self.request.retries))
2018-08-06 02:42:32 -05:00
@app.task(bind=True, max_retries=MAX_RETRIES) # noqa:C901
2018-07-30 03:06:44 -05:00
def forward_activity(self, iri: str) -> None:
try:
activity = ap.fetch_remote_activity(iri)
recipients = back.followers_as_recipients()
2018-07-30 03:06:44 -05:00
log.debug(f"Forwarding {activity!r} to {recipients}")
activity = ap.clean_activity(activity.to_dict())
payload = json.dumps(activity)
for recp in recipients:
log.debug(f"forwarding {activity!r} to {recp}")
post_to_remote_inbox.delay(payload, recp)
2018-07-29 09:07:27 -05:00
except Exception as err:
log.exception(f"failed to cache attachments for {iri}")
self.retry(exc=err, countdown=int(random.uniform(2, 4) ** self.request.retries))
2018-08-06 02:42:32 -05:00
@app.task(bind=True, max_retries=MAX_RETRIES)
2018-07-29 09:07:27 -05:00
def post_to_remote_inbox(self, payload: str, to: str) -> None:
2018-05-18 13:41:41 -05:00
try:
2018-06-16 15:02:10 -05:00
log.info("payload=%s", payload)
log.info("generating sig")
2018-06-01 13:29:44 -05:00
signed_payload = json.loads(payload)
2018-07-20 18:42:59 -05:00
# Don't overwrite the signature if we're forwarding an activity
if "signature" not in signed_payload:
generate_signature(signed_payload, KEY)
2018-06-16 15:02:10 -05:00
log.info("to=%s", to)
resp = requests.post(
to,
data=json.dumps(signed_payload),
auth=SigAuth,
headers={
"Content-Type": HEADERS[1],
"Accept": HEADERS[1],
"User-Agent": USER_AGENT,
},
)
log.info("resp=%s", resp)
log.info("resp_body=%s", resp.text)
2018-05-18 13:41:41 -05:00
resp.raise_for_status()
except HTTPError as err:
2018-06-16 15:02:10 -05:00
log.exception("request failed")
2018-05-18 13:41:41 -05:00
if 400 >= err.response.status_code >= 499:
2018-06-16 15:02:10 -05:00
log.info("client error, no retry")
2018-05-18 13:41:41 -05:00
return
self.retry(exc=err, countdown=int(random.uniform(2, 4) ** self.request.retries))