Display the runtime

[friendica.git] / src / Worker / APDelivery.php

diff --git a/src/Worker/APDelivery.php b/src/Worker/APDelivery.php
index 7af50c67e4bfb597acff85868e3b6093fa114150..e341787e21151bdc7740936f00daaeca4a81dc2e 100644 (file)
--- a/src/Worker/APDelivery.php
+++ b/src/Worker/APDelivery.php
@@ -1,6 +1,6 @@
 <?php
 /**
- * @copyright Copyright (C) 2010-2021, the Friendica project
+ * @copyright Copyright (C) 2010-2022, the Friendica project
  *
  * @license GNU AGPL version 3 or any later version
  *
@@ -46,17 +46,87 @@ class APDelivery
        public static function execute(string $cmd, int $item_id, string $inbox, int $uid, array $receivers = [], int $uri_id = 0)
        {
                if (ActivityPub\Transmitter::archivedInbox($inbox)) {
-                       Logger::info('Inbox is archived', ['cmd' => $cmd, 'inbox' => $inbox, 'id' => $item_id, 'uid' => $uid]);
-                       if (in_array($cmd, [Delivery::POST])) {
+                       Logger::info('Inbox is archived', ['cmd' => $cmd, 'inbox' => $inbox, 'id' => $item_id, 'uri-id' => $uri_id, 'uid' => $uid]);
+                       if (empty($uri_id) && !empty($item_id)) {
                                $item = Post::selectFirst(['uri-id'], ['id' => $item_id]);
-                               Post\DeliveryData::incrementQueueFailed($item['uri-id'] ?? 0);
+                               $uri_id = $item['uri-id'] ?? 0;
+                       }
+                       if (empty($uri_id)) {
+                               $posts   = Post\Delivery::selectForInbox($inbox);
+                               $uri_ids = array_column($posts, 'uri-id');
+                       } else {
+                               $uri_ids = [$uri_id];
+                       }
+
+                       foreach ($uri_ids as $uri_id) {
+                               Post\Delivery::remove($uri_id, $inbox);
+                               Post\DeliveryData::incrementQueueFailed($uri_id);
                        }
                        return;
                }
 
                Logger::info('Invoked', ['cmd' => $cmd, 'inbox' => $inbox, 'id' => $item_id, 'uri-id' => $uri_id, 'uid' => $uid]);
 
+               if (empty($uri_id)) {
+                       $result  = self::deliver($inbox);
+                       $success = $result['success'];
+                       $uri_ids = $result['uri_ids'];
+               } else {
+                       $result  = self::deliverToInbox($cmd, $item_id, $inbox, $uid, $receivers, $uri_id);
+                       $success = $result['success'];
+                       $uri_ids = [$uri_id];
+               }
+
+               if (!$success && !Worker::defer() && !empty($uri_ids)) {
+                       foreach ($uri_ids as $uri_id) {
+                               Post\Delivery::remove($uri_id, $inbox);
+                               Post\DeliveryData::incrementQueueFailed($uri_id);
+                       }
+               }
+       }
+
+       private static function deliver(string $inbox):array
+       {
+               $uri_ids = [];
+               $posts   = Post\Delivery::selectForInbox($inbox);
+               $timeout = false;
+
+               foreach ($posts as $post) {
+                       if (!$timeout) {
+                               $result = self::deliverToInbox($post['command'], 0, $inbox, $post['uid'], $post['receivers'], $post['uri-id']);
+
+                               if ($result['timeout']) {
+                                       // In a timeout situation we assume that every delivery to that inbox will time out.
+                                       // So we set the flag and try all deliveries at a later time.
+                                       Logger::debug('Inbox delivery has a time out', ['inbox' => $inbox]);
+                                       $timeout = true;
+                               }
+                       }
+
+                       if ($timeout || !$result['success']) {
+                               $uri_ids[] = $post['uri-id'];
+                       }
+               }
+
+               Logger::debug('Inbox delivery done', ['inbox' => $inbox, 'posts' => count($posts), 'failed' => count($uri_ids)]);
+               return ['success' => empty($uri_ids), 'uri_ids' => $uri_ids];
+       }
+
+       private static function deliverToInbox(string $cmd, int $item_id, string $inbox, int $uid, array $receivers, int $uri_id): array
+       {
+               if (empty($item_id) && !empty($uri_id) && !empty($uid)) {
+                       $item = Post::selectFirst(['id', 'parent', 'origin'], ['uri-id' => $uri_id, 'uid' => [$uid, 0]], ['order' => ['uid' => true]]);
+                       if (empty($item['id'])) {
+                               Logger::debug('Item not found, removing delivery', ['uri-id' => $uri_id, 'uid' => $uid, 'cmd' => $cmd, 'inbox' => $inbox]);
+                               Post\Delivery::remove($uri_id, $inbox);
+                               return true;
+                       } else {
+                               $item_id = $item['id'];
+                       }
+               }
+
                $success = true;
+               $timeout = false;
 
                if ($cmd == Delivery::MAIL) {
                        $data = ActivityPub\Transmitter::createActivityFromMail($item_id);
@@ -76,10 +146,37 @@ class APDelivery
                } else {
                        $data = ActivityPub\Transmitter::createCachedActivityFromItem($item_id);
                        if (!empty($data)) {
-                               $success = HTTPSignature::transmit($data, $inbox, $uid);
+                               $timestamp = microtime(true);
+                               $response = HTTPSignature::post($data, $inbox, $uid);
+                               $runtime  = microtime(true) - $timestamp;
+                               $success  = $response->isSuccess();
+                               $timeout  = $response->isTimeout();
+                               if (!$success) {
+                                       Logger::debug('Delivery failed', ['retcode' => $response->getReturnCode(), 'timeout' => $timeout, 'runtime' => round($runtime, 3), 'uri-id' => $uri_id, 'uid' => $uid, 'item_id' => $item_id, 'cmd' => $cmd, 'inbox' => $inbox]);
+                               }
+                               if ($uri_id) {
+                                       if ($success) {
+                                               Post\Delivery::remove($uri_id, $inbox);
+                                       } else {
+                                               Post\Delivery::incrementFailed($uri_id, $inbox);
+                                       }
+                               }
                        }
                }
 
+               self::setSuccess($receivers, $success);
+
+               Logger::info('Delivered', ['uri-id' => $uri_id, 'uid' => $uid, 'item_id' => $item_id, 'cmd' => $cmd, 'inbox' => $inbox, 'success' => $success]);
+
+               if ($success && in_array($cmd, [Delivery::POST])) {
+                       Post\DeliveryData::incrementQueueDone($uri_id, Post\DeliveryData::ACTIVITYPUB);
+               }
+
+               return ['success' => $success, 'timeout' => $timeout];
+       }
+
+       private static function setSuccess(array $receivers, bool $success)
+       {
                $gsid = null;
 
                foreach ($receivers as $receiver) {
@@ -100,11 +197,5 @@ class APDelivery
                if (!empty($gsid)) {
                        GServer::setProtocol($gsid, Post\DeliveryData::ACTIVITYPUB);
                }
-
-               if (!$success && !Worker::defer() && in_array($cmd, [Delivery::POST])) {
-                       Post\DeliveryData::incrementQueueFailed($uri_id);
-               } elseif ($success && in_array($cmd, [Delivery::POST])) {
-                       Post\DeliveryData::incrementQueueDone($uri_id, Post\DeliveryData::ACTIVITYPUB);
-               }
        }
 }