PluralKit/PluralKit.Bot/Bot.cs

304 lines
12 KiB
C#
Raw Normal View History

2019-04-19 18:48:37 +00:00
using System;
2020-12-22 12:15:26 +00:00
using System.Collections.Concurrent;
using System.Collections.Generic;
2019-04-19 18:48:37 +00:00
using System.Linq;
2020-04-24 21:47:35 +00:00
using System.Net.WebSockets;
using System.Threading;
2019-04-19 18:48:37 +00:00
using System.Threading.Tasks;
2019-07-16 19:59:06 +00:00
using App.Metrics;
2020-01-26 00:27:45 +00:00
using Autofac;
2020-12-22 12:15:26 +00:00
using Myriad.Cache;
using Myriad.Extensions;
2020-12-22 12:15:26 +00:00
using Myriad.Gateway;
using Myriad.Rest;
using Myriad.Types;
2020-04-28 23:14:49 +00:00
using NodaTime;
2020-01-26 00:27:45 +00:00
using PluralKit.Core;
2019-10-05 05:41:00 +00:00
2019-07-15 19:02:50 +00:00
using Sentry;
2020-01-26 00:27:45 +00:00
2019-07-18 15:13:42 +00:00
using Serilog;
using Serilog.Context;
2019-04-19 18:48:37 +00:00
namespace PluralKit.Bot
2019-04-19 18:48:37 +00:00
{
public class Bot
2019-04-19 18:48:37 +00:00
{
2021-08-27 15:03:47 +00:00
2020-12-22 12:15:26 +00:00
private readonly Cluster _cluster;
private readonly DiscordApiClient _rest;
private readonly ILogger _logger;
private readonly ILifetimeScope _services;
private readonly PeriodicStatCollector _collector;
private readonly IMetrics _metrics;
private readonly BotConfig _config;
private readonly ErrorMessageService _errorMessageService;
2020-10-23 10:18:28 +00:00
private readonly CommandMessageService _commandMessageService;
2020-12-22 12:15:26 +00:00
private readonly IDiscordCache _cache;
2019-04-19 18:48:37 +00:00
private bool _hasReceivedReady = false;
private Timer _periodicTask; // Never read, just kept here for GC reasons
2019-07-18 15:13:42 +00:00
public Bot(ILifetimeScope services, ILogger logger, PeriodicStatCollector collector, IMetrics metrics, BotConfig config,
2020-12-22 12:15:26 +00:00
ErrorMessageService errorMessageService, CommandMessageService commandMessageService, Cluster cluster, DiscordApiClient rest, IDiscordCache cache)
2019-04-19 18:48:37 +00:00
{
_logger = logger.ForContext<Bot>();
_services = services;
2019-07-16 21:34:22 +00:00
_collector = collector;
_metrics = metrics;
_config = config;
_errorMessageService = errorMessageService;
2020-10-23 10:18:28 +00:00
_commandMessageService = commandMessageService;
2020-12-22 12:15:26 +00:00
_cluster = cluster;
_rest = rest;
_cache = cache;
2019-04-19 18:48:37 +00:00
}
public void Init()
2019-04-19 18:48:37 +00:00
{
2020-12-22 12:15:26 +00:00
_cluster.EventReceived += OnEventReceived;
2021-08-27 15:03:47 +00:00
// Init the shard stuff
2020-05-09 13:44:56 +00:00
_services.Resolve<ShardInfoService>().Init();
2019-10-05 05:41:00 +00:00
// Not awaited, just needs to run in the background
// Trying our best to run it at whole minute boundaries (xx:00), with ~250ms buffer
// This *probably* doesn't matter in practice but I jut think it's neat, y'know.
var timeNow = SystemClock.Instance.GetCurrentInstant();
var timeTillNextWholeMinute = TimeSpan.FromMilliseconds(60000 - timeNow.ToUnixTimeMilliseconds() % 60000 + 250);
_periodicTask = new Timer(_ =>
{
2021-08-27 15:03:47 +00:00
var __ = UpdatePeriodic();
}, null, timeTillNextWholeMinute, TimeSpan.FromMinutes(1));
}
2021-11-19 12:57:01 +00:00
public async Task<PermissionSet> PermissionsIn(ulong channelId)
{
var channel = await _cache.GetRootChannel(channelId);
if (channel.GuildId != null)
{
var member = await _cache.TryGetSelfMember(channel.GuildId.Value);
return await _cache.PermissionsFor(channelId, _cluster.User?.Id ?? default, member);
}
2020-12-22 12:15:26 +00:00
return PermissionSet.Dm;
}
2021-08-27 15:03:47 +00:00
2020-12-22 12:15:26 +00:00
private async Task OnEventReceived(Shard shard, IGatewayEvent evt)
{
await _cache.TryUpdateSelfMember(shard, evt);
2020-12-22 12:15:26 +00:00
await _cache.HandleGatewayEvent(evt);
// HandleEvent takes a type parameter, automatically inferred by the event type
// It will then look up an IEventHandler<TypeOfEvent> in the DI container and call that object's handler method
// For registering new ones, see Modules.cs
if (evt is MessageCreateEvent mc)
await HandleEvent(shard, mc);
if (evt is MessageUpdateEvent mu)
await HandleEvent(shard, mu);
if (evt is MessageDeleteEvent md)
await HandleEvent(shard, md);
if (evt is MessageDeleteBulkEvent mdb)
await HandleEvent(shard, mdb);
if (evt is MessageReactionAddEvent mra)
await HandleEvent(shard, mra);
2021-05-26 20:27:52 +00:00
if (evt is InteractionCreateEvent ic)
await HandleEvent(shard, ic);
2020-12-22 12:15:26 +00:00
// Update shard status for shards immediately on connect
if (evt is ReadyEvent re)
await HandleReady(shard, re);
if (evt is ResumedEvent)
await HandleResumed(shard);
}
private Task HandleResumed(Shard shard)
{
return UpdateBotStatus(shard);
}
private Task HandleReady(Shard shard, ReadyEvent _)
{
_hasReceivedReady = true;
return UpdateBotStatus(shard);
}
public async Task Shutdown()
{
// This will stop the timer and prevent any subsequent invocations
await _periodicTask.DisposeAsync();
// Send users a lil status message
// We're not actually properly disconnecting from the gateway (lol) so it'll linger for a few minutes
// Should be plenty of time for the bot to connect again next startup and set the real status
if (_hasReceivedReady)
2020-12-22 12:15:26 +00:00
{
await Task.WhenAll(_cluster.Shards.Values.Select(shard =>
shard.UpdateStatus(new GatewayStatusUpdate
{
Activities = new[]
{
new ActivityPartial
{
2021-08-27 15:03:47 +00:00
Name = "Restarting... (please wait)",
2020-12-22 12:15:26 +00:00
Type = ActivityType.Game
}
},
Status = GatewayStatusUpdate.UserStatus.Idle
})));
}
}
2021-08-27 15:03:47 +00:00
private Task HandleEvent<T>(Shard shard, T evt) where T : IGatewayEvent
{
// We don't want to stall the event pipeline, so we'll "fork" inside here
var _ = HandleEventInner();
2019-10-05 05:41:00 +00:00
return Task.CompletedTask;
async Task HandleEventInner()
{
2021-01-30 00:07:43 +00:00
await Task.Yield();
2021-06-10 12:21:05 +00:00
2020-06-14 22:52:20 +00:00
await using var serviceScope = _services.BeginLifetimeScope();
2020-05-05 14:03:46 +00:00
// Find an event handler that can handle the type of event (<T>) we're given
IEventHandler<T> handler;
try
{
handler = serviceScope.Resolve<IEventHandler<T>>();
}
catch (Exception e)
{
_logger.Error(e, "Error instantiating handler class");
return;
}
2020-11-16 08:57:16 +00:00
try
{
var queue = serviceScope.ResolveOptional<HandlerQueue<T>>();
using var _ = LogContext.PushProperty("EventId", Guid.NewGuid());
using var __ = LogContext.Push(await serviceScope.Resolve<SerilogGatewayEnricherFactory>().GetEnricher(shard, evt));
_logger.Verbose("Received gateway event: {@Event}", evt);
// Also, find a Sentry enricher for the event type (if one is present), and ask it to put some event data in the Sentry scope
var sentryEnricher = serviceScope.ResolveOptional<ISentryEnricher<T>>();
sentryEnricher?.Enrich(serviceScope.Resolve<Scope>(), shard, evt);
2021-08-27 15:03:47 +00:00
using var timer = _metrics.Measure.Timer.Time(BotMetrics.EventsHandled,
2020-12-22 12:15:26 +00:00
new MetricTags("event", typeof(T).Name.Replace("Event", "")));
2020-11-16 08:57:16 +00:00
2020-05-05 14:03:46 +00:00
// Delegate to the queue to see if it wants to handle this event
// the TryHandle call returns true if it's handled the event
// Usually it won't, so just pass it on to the main handler
2021-08-27 15:03:47 +00:00
if (queue == null || !await queue.TryHandle(evt))
2020-11-15 12:53:31 +00:00
await handler.Handle(shard, evt);
}
catch (Exception exc)
{
2020-12-22 12:15:26 +00:00
await HandleError(shard, handler, evt, serviceScope, exc);
}
}
2019-07-19 00:29:08 +00:00
}
2021-08-27 15:03:47 +00:00
2020-12-22 12:15:26 +00:00
private async Task HandleError<T>(Shard shard, IEventHandler<T> handler, T evt, ILifetimeScope serviceScope, Exception exc)
2021-08-27 15:03:47 +00:00
where T : IGatewayEvent
2019-07-19 00:29:08 +00:00
{
2020-11-16 08:57:16 +00:00
_metrics.Measure.Meter.Mark(BotMetrics.BotErrors, exc.GetType().FullName);
2021-08-27 15:03:47 +00:00
2020-05-05 14:42:14 +00:00
// Make this beforehand so we can access the event ID for logging
var sentryEvent = new SentryEvent(exc);
2020-08-27 16:20:20 +00:00
// If the event is us responding to our own error messages, don't bother logging
2020-12-22 12:15:26 +00:00
if (evt is MessageCreateEvent mc && mc.Author.Id == shard.User?.Id)
2020-08-27 16:20:20 +00:00
return;
2019-07-19 00:29:08 +00:00
var shouldReport = exc.IsOurProblem();
if (shouldReport)
{
2021-11-02 09:34:17 +00:00
// only log exceptions if they're our problem
_logger.Error(exc, "Exception in event handler: {SentryEventId}", sentryEvent.EventId);
// Report error to Sentry
// This will just no-op if there's no URL set
var sentryScope = serviceScope.Resolve<Scope>();
2021-08-27 15:03:47 +00:00
// Add some specific info about Discord error responses, as a breadcrumb
2020-12-22 12:15:26 +00:00
// TODO: headers to dict
// if (exc is BadRequestException bre)
// sentryScope.AddBreadcrumb(bre.Response, "response.error", data: new Dictionary<string, string>(bre.Response.Headers));
// if (exc is NotFoundException nfe)
// sentryScope.AddBreadcrumb(nfe.Response, "response.error", data: new Dictionary<string, string>(nfe.Response.Headers));
// if (exc is UnauthorizedException ue)
// sentryScope.AddBreadcrumb(ue.Response, "response.error", data: new Dictionary<string, string>(ue.Response.Headers));
2021-08-27 15:03:47 +00:00
SentrySdk.CaptureEvent(sentryEvent, sentryScope);
// most of these errors aren't useful...
if (_config.DisableErrorReporting)
return;
2021-11-07 17:25:20 +00:00
// Once we've sent it to Sentry, report it to the user (if we have permission to)
var reportChannel = handler.ErrorChannelFor(evt);
if (reportChannel == null)
return;
var botPerms = await PermissionsIn(reportChannel.Value);
if (botPerms.HasFlag(PermissionSet.SendMessages | PermissionSet.EmbedLinks))
await _errorMessageService.SendErrorMessage(reportChannel.Value, sentryEvent.EventId.ToString());
}
2019-04-19 18:48:37 +00:00
}
2021-08-27 15:03:47 +00:00
2019-04-25 16:50:07 +00:00
private async Task UpdatePeriodic()
2019-04-20 20:25:03 +00:00
{
_logger.Debug("Running once-per-minute scheduled tasks");
await UpdateBotStatus();
// Collect some stats, submit them to the metrics backend
await _collector.CollectStats();
2021-08-27 15:03:47 +00:00
await Task.WhenAll(((IMetricsRoot)_metrics).ReportRunner.RunAllAsync());
_logger.Debug("Submitted metrics to backend");
}
2020-12-22 12:15:26 +00:00
private async Task UpdateBotStatus(Shard specificShard = null)
{
// If we're not on any shards, don't bother (this happens if the periodic timer fires before the first Ready)
if (!_hasReceivedReady) return;
2020-12-22 12:15:26 +00:00
var totalGuilds = await _cache.GetAllGuilds().CountAsync();
try // DiscordClient may throw an exception if the socket is closed (e.g just after OP 7 received)
2020-04-24 21:47:35 +00:00
{
2020-12-22 12:15:26 +00:00
Task UpdateStatus(Shard shard) =>
shard.UpdateStatus(new GatewayStatusUpdate
{
Activities = new[]
{
new ActivityPartial
{
2021-06-08 08:20:59 +00:00
Name = $"pk;help | in {totalGuilds:N0} servers | shard #{shard.ShardId}",
2020-12-22 12:15:26 +00:00
Type = ActivityType.Game,
Url = "https://pluralkit.me/"
}
}
});
if (specificShard != null)
await UpdateStatus(specificShard);
else // Run shard updates concurrently
2020-12-22 12:15:26 +00:00
await Task.WhenAll(_cluster.Shards.Values.Select(UpdateStatus));
}
catch (WebSocketException)
{
// TODO: this still thrown?
2020-04-28 23:14:49 +00:00
}
2019-04-25 16:50:07 +00:00
}
}
}