You signed in with another tab or window. Reload to refresh your session.You signed out in another tab or window. Reload to refresh your session.You switched accounts on another tab or window. Reload to refresh your session.Dismiss alert
I see a long list of errors and warnings in the prod log.
We really need to clean-up some of those and try to fix the PHP warning or PHP or Twig errors we see... This is a follow-up issue of: #1119
On which Mbin instance did you find the bug?
kbin.melroy.org
Which Mbin version was running on the instance?
1.7.2
To Reproduce
Steps to reproduce the behavior:
Check-out the log: tail -f var/log/prod-xx-x-x-x.log
And follow it for a while...
Expected behavior
Minimal amount of errors and warnings in the production log. Only some error messages make sense if there is a real error and we still want to keep reporting it to admin of the instance.
Let's just see how much we can resolve in the upcoming 1.7.3 milestone..
List of issues
the 12th NotFoundHttpException App\Entity\Entry and App\Entity\Post object not found.. :
Full stack trace
[2024-09-12T20:35:38.330174+02:00] request.ERROR: Uncaught PHP Exception Symfony\Component\HttpKernel\Exception\NotFoundHttpException: ""App\Entity\Entry" object not found by "Symfony\Bridge\Doctrine\ArgumentResolver\EntityValueResolver"." at EntityValueResolver.php line 76 {"exception":"[object] (Symfony\\Component\\HttpKernel\\Exception\\NotFoundHttpException(code: 0): \"App\\Entity\\Entry\" object not found by \"Symfony\\Bridge\\Doctrine\\ArgumentResolver\\EntityValueResolver\". at /var/www/kbin.melroy.org/html/vendor/symfony/doctrine-bridge/ArgumentResolver/EntityValueResolver.php:76)
[stacktrace]
#0 /var/www/kbin.melroy.org/html/vendor/symfony/http-kernel/Controller/ArgumentResolver/TraceableValueResolver.php(37): Symfony\\Bridge\\Doctrine\\ArgumentResolver\\EntityValueResolver->resolve()
#1 /var/www/kbin.melroy.org/html/vendor/symfony/http-kernel/Controller/ArgumentResolver.php(90): Symfony\\Component\\HttpKernel\\Controller\\ArgumentResolver\\TraceableValueResolver->resolve()
#2 /var/www/kbin.melroy.org/html/vendor/symfony/http-kernel/Controller/TraceableArgumentResolver.php(33): Symfony\\Component\\HttpKernel\\Controller\\ArgumentResolver->getArguments()
#3 /var/www/kbin.melroy.org/html/vendor/symfony/http-kernel/HttpKernel.php(175): Symfony\\Component\\HttpKernel\\Controller\\TraceableArgumentResolver->getArguments()
#4 /var/www/kbin.melroy.org/html/vendor/symfony/http-kernel/HttpKernel.php(76): Symfony\\Component\\HttpKernel\\HttpKernel->handleRaw()
#5 /var/www/kbin.melroy.org/html/vendor/symfony/http-kernel/Kernel.php(182): Symfony\\Component\\HttpKernel\\HttpKernel->handle()
#6 /var/www/kbin.melroy.org/html/vendor/symfony/runtime/Runner/Symfony/HttpKernelRunner.php(35): Symfony\\Component\\HttpKernel\\Kernel->handle()
#7 /var/www/kbin.melroy.org/html/vendor/autoload_runtime.php(29): Symfony\\Component\\Runtime\\Runner\\Symfony\\HttpKernelRunner->run()
#8 /var/www/kbin.melroy.org/html/public/index.php(7): require_once('...')
#9 {main}
"} []
[2024-09-12T20:35:55.620106+02:00] request.ERROR: Uncaught PHP Exception Symfony\Component\HttpKernel\Exception\NotFoundHttpException: ""App\Entity\Entry" object not found by "Symfony\Bridge\Doctrine\ArgumentResolver\EntityValueResolver"." at EntityValueResolver.php line 76 {"exception":"[object] (Symfony\\Component\\HttpKernel\\Exception\\NotFoundHttpException(code: 0): \"App\\Entity\\Entry\" object not found by \"Symfony\\Bridge\\Doctrine\\ArgumentResolver\\EntityValueResolver\". at /var/www/kbin.melroy.org/html/vendor/symfony/doctrine-bridge/ArgumentResolver/EntityValueResolver.php:76)
[stacktrace]
#0 /var/www/kbin.melroy.org/html/vendor/symfony/http-kernel/Controller/ArgumentResolver/TraceableValueResolver.php(37): Symfony\\Bridge\\Doctrine\\ArgumentResolver\\EntityValueResolver->resolve()
#1 /var/www/kbin.melroy.org/html/vendor/symfony/http-kernel/Controller/ArgumentResolver.php(90): Symfony\\Component\\HttpKernel\\Controller\\ArgumentResolver\\TraceableValueResolver->resolve()
#2 /var/www/kbin.melroy.org/html/vendor/symfony/http-kernel/Controller/TraceableArgumentResolver.php(33): Symfony\\Component\\HttpKernel\\Controller\\ArgumentResolver->getArguments()
#3 /var/www/kbin.melroy.org/html/vendor/symfony/http-kernel/HttpKernel.php(175): Symfony\\Component\\HttpKernel\\Controller\\TraceableArgumentResolver->getArguments()
#4 /var/www/kbin.melroy.org/html/vendor/symfony/http-kernel/HttpKernel.php(76): Symfony\\Component\\HttpKernel\\HttpKernel->handleRaw()
#5 /var/www/kbin.melroy.org/html/vendor/symfony/http-kernel/Kernel.php(182): Symfony\\Component\\HttpKernel\\HttpKernel->handle()
#6 /var/www/kbin.melroy.org/html/vendor/symfony/runtime/Runner/Symfony/HttpKernelRunner.php(35): Symfony\\Component\\HttpKernel\\Kernel->handle()
#7 /var/www/kbin.melroy.org/html/vendor/autoload_runtime.php(29): Symfony\\Component\\Runtime\\Runner\\Symfony\\HttpKernelRunner->run()
#8 /var/www/kbin.melroy.org/html/public/index.php(7): require_once('...')
#9 {main}
And also seems to be related:
[2024-09-12T20:50:38.827422+02:00] request.ERROR: Uncaught PHP Exception Symfony\Component\HttpKernel\Exception\NotFoundHttpException: ""App\Entity\Post" object not found by "Symfony\Bridge\Doctrine\ArgumentResolver\EntityValueResolver"." at EntityValueResolver.php line 76 {"exception":"[object] (Symfony\\Component\\HttpKernel\\Exception\\NotFoundHttpException(code: 0): \"App\\Entity\\Post\" object not found by \"Symfony\\Bridge\\Doctrine\\ArgumentResolver\\EntityValueResolver\". at /var/www/kbin.melroy.org/html/vendor/symfony/doctrine-bridge/ArgumentResolver/EntityValueResolver.php:76)
[stacktrace]
#0 /var/www/kbin.melroy.org/html/vendor/symfony/http-kernel/Controller/ArgumentResolver/TraceableValueResolver.php(37): Symfony\\Bridge\\Doctrine\\ArgumentResolver\\EntityValueResolver->resolve()
#1 /var/www/kbin.melroy.org/html/vendor/symfony/http-kernel/Controller/ArgumentResolver.php(90): Symfony\\Component\\HttpKernel\\Controller\\ArgumentResolver\\TraceableValueResolver->resolve()
#2 /var/www/kbin.melroy.org/html/vendor/symfony/http-kernel/Controller/TraceableArgumentResolver.php(33): Symfony\\Component\\HttpKernel\\Controller\\ArgumentResolver->getArguments()
#3 /var/www/kbin.melroy.org/html/vendor/symfony/http-kernel/HttpKernel.php(175): Symfony\\Component\\HttpKernel\\Controller\\TraceableArgumentResolver->getArguments()
#4 /var/www/kbin.melroy.org/html/vendor/symfony/http-kernel/HttpKernel.php(76): Symfony\\Component\\HttpKernel\\HttpKernel->handleRaw()
#5 /var/www/kbin.melroy.org/html/vendor/symfony/http-kernel/Kernel.php(182): Symfony\\Component\\HttpKernel\\HttpKernel->handle()
#6 /var/www/kbin.melroy.org/html/vendor/symfony/runtime/Runner/Symfony/HttpKernelRunner.php(35): Symfony\\Component\\HttpKernel\\Kernel->handle()
#7 /var/www/kbin.melroy.org/html/vendor/autoload_runtime.php(29): Symfony\\Component\\Runtime\\Runner\\Symfony\\HttpKernelRunner->run()
#8 /var/www/kbin.melroy.org/html/public/index.php(7): require_once('...')
#9 {main}
"} []
14th - NotFoundHttpException at EntryCommentRepository.php:
17th - User already deleted errors.. Why is this an error? We could just check if the user is present or not, before trying to delete a user which isn't present anyways.
{"message":"Error thrown while handling message App\\Message\\DeleteUserMessage. Removing from transport after 0 retries. Error: \"Handling \"App\\Message\\DeleteUserMessage\" failed: User already deleted\"","context":{"class":"App\\Message\\DeleteUserMessage","message_id":null,"retryCount":0,"error":"Handling \"App\\Message\\DeleteUserMessage\" failed: User already deleted","exception":{"class":"Symfony\\Component\\Messenger\\Exception\\HandlerFailedException","message":"Handling \"App\\Message\\DeleteUserMessage\" failed: User already deleted","code":0,"file":"/var/www/kbin.melroy.org/html/vendor/symfony/messenger/Middleware/HandleMessageMiddleware.php:124","previous":{"class":"Symfony\\Component\\Messenger\\Exception\\UnrecoverableMessageHandlingException","message":"User already deleted","code":0,"file":"/var/www/kbin.melroy.org/html/src/MessageHandler/DeleteUserHandler.php:53"}}},"level":500,"level_name":"CRITICAL","channel":"messenger","datetime":"2024-09-13T12:57:13.578196+02:00","extra":{}}
18th - (running now on the latest main) I still see getActivityObject failed on a domain name that I blocked (because kbin.social is down). I despite I this domain name is part of the federation block list, it still give me 503 errors:
19th - The famous couldnt_find_person. Can we try to debug what this means? Solve the problem? Or handle the error better..?
{"message":"ApHttpClient:getActivityObject get fail: https://lemmy.ca/post/28871883, ex: App\\Exception\\InvalidApPostException: Invalid status code while getting: https://lemmy.ca/post/28871883 : 400, {\"error\":\"couldnt_find_person\"} - {\"error\":\"couldnt_find_person\"}","context":{"type":"ApHttpClient:getActivityObject","address":"https://lemmy.ca/post/28871883","e":"App\\Exception\\InvalidApPostException","msg":"Invalid status code while getting: https://lemmy.ca/post/28871883 : 400, {\"error\":\"couldnt_find_person\"}","content":"{\"error\":\"couldnt_find_person\"}"},"level":400,"level_name":"ERROR","channel":"app","datetime":"2024-09-14T13:05:02.098634+02:00","extra":{}}
20th - Invalid text representation invalid input syntax for type integer: \"{user_id}\":
{"message":"Uncaught PHP Exception Doctrine\\DBAL\\Exception\\DriverException: \"An exception occurred while executing a query: SQLSTATE[22P02]: Invalid text representation: 7 ERROR: invalid input syntax for type integer: \"{user_id}\"\nCONTEXT: unnamed portal parameter $1 = '...'\" at ExceptionConverter.php line 87","context":{"exception":{"class":"Doctrine\\DBAL\\Exception\\DriverException","message":"An exception occurred while executing a query: SQLSTATE[22P02]: Invalid text representation: 7 ERROR: invalid input syntax for type integer: \"{user_id}\"\nCONTEXT: unnamed portal parameter $1 = '...'","code":7,"file":"/var/www/kbin.melroy.org/html/vendor/doctrine/dbal/src/Driver/API/PostgreSQL/ExceptionConverter.php:87","previous":{"class":"Doctrine\\DBAL\\Driver\\PDO\\Exception","message":"SQLSTATE[22P02]: Invalid text representation: 7 ERROR: invalid input syntax for type integer: \"{user_id}\"\nCONTEXT: unnamed portal parameter $1 = '...'","code":7,"file":"/var/www/kbin.melroy.org/html/vendor/doctrine/dbal/src/Driver/PDO/Exception.php:28","previous":{"class":"PDOException","message":"SQLSTATE[22P02]: Invalid text representation: 7 ERROR: invalid input syntax for type integer: \"{user_id}\"\nCONTEXT: unnamed portal parameter $1 = '...'","code":22,"file":"/var/www/kbin.melroy.org/html/vendor/doctrine/dbal/src/Driver/PDO/Statement.php:130"}}}},"level":500,"level_name":"CRITICAL","channel":"request","datetime":"2024-09-16T22:22:06.622198+02:00","extra":{}}
This error also can be seens if the email confirmation URL is wrong or wrongly interpreted by the client or whatever is wrong.. (using the verify/email/.. path):
{"message":"Uncaught PHP Exception Doctrine\\DBAL\\Exception\\DriverException: \"An exception occurred while executing a query: SQLSTATE[22P02]: Invalid text representation: 7 ERROR: invalid input syntax for type integer: \"3D628316\"\nCONTEXT: unnamed portal parameter $1 = '...'\" at ExceptionConverter.php line 87","context":{"exception":{"class":"Doctrine\\DBAL\\Exception\\DriverException","message":"An exception occurred while executing a query: SQLSTATE[22P02]: Invalid text representation: 7 ERROR: invalid input syntax for type integer: \"3D628316\"\nCONTEXT: unnamed portal parameter $1 = '...'","code":7,"file":"/var/www/kbin.melroy.org/html/vendor/doctrine/dbal/src/Driver/API/PostgreSQL/ExceptionConverter.php:87","previous":{"class":"Doctrine\\DBAL\\Driver\\PDO\\Exception","message":"SQLSTATE[22P02]: Invalid text representation: 7 ERROR: invalid input syntax for type integer: \"3D628316\"\nCONTEXT: unnamed portal parameter $1 = '...'","code":7,"file":"/var/www/kbin.melroy.org/html/vendor/doctrine/dbal/src/Driver/PDO/Exception.php:28","previous":{"class":"PDOException","message":"SQLSTATE[22P02]: Invalid text representation: 7 ERROR: invalid input syntax for type integer: \"3D628316\"\nCONTEXT: unnamed portal parameter $1 = '...'","code":22,"file":"/var/www/kbin.melroy.org/html/vendor/doctrine/dbal/src/Driver/PDO/Statement.php:130"}}}},"level":500,"level_name":"CRITICAL","channel":"request","datetime":"2024-09-19T17:08:55.106238+02:00","extra":{}}
The logs are missing a request URL (path) for sure, otherwise it's very hard to debug the above messages, without any clue which kind of request we are talking about. But for sure it could trigger this error above by the confirmation email link.
21st - could not retrieve all the dependencies
{"message":"could not retrieve all the dependencies of https://lemmy.world/comment/12469030","context":{"o":"https://lemmy.world/comment/12469030"},"level":400,"level_name":"ERROR","channel":"app","datetime":"2024-09-20T17:20:25.864264+02:00","extra":{}}
22nd - Fetched remote object https://kbin.melroy.org/u/RmDebArk_5 which was deleted. Is this is error? Can we fix it in a better way? If not, why do we log it?
{"message":"ApHttpClient:post failed: https://sh.itjust.works/inbox, ex: App\\Exception\\InvalidApPostException: Post failed: https://sh.itjust.works/inbox, status code: 400, request body: {\"@context\":\"https:\\/\\/www.w3.org\\/ns\\/activitystreams\",\"id\":\"https:\\/\\/kbin.melroy.org\\/f\\/object\\/a89df69f-9296-4c6d-9f27-085ccfc90539\",\"type\":\"Delete\",\"actor\":\"https:\\/\\/kbin.melroy.org\\/u\\/RmDebArk_5\",\"object\":\"https:\\/\\/kbin.melroy.org\\/u\\/RmDebArk_5\",\"to\":[\"https:\\/\\/www.w3.org\\/ns\\/activitystreams#Public\"],\"cc\":[\"https:\\/\\/kbin.melroy.org\\/u\\/RmDebArk_5\\/followers\"],\"removeData\":true}. Truncated content: {\"error\":\"unknown\",\"message\":\"Fetched remote object https://kbin.melroy.org/u/RmDebArk_5 which was deleted\"}","context":{"type":"ApHttpClient:post","address":"https://sh.itjust.works/inbox","e":"App\\Exception\\InvalidApPostException","msg":"Post failed: https://sh.itjust.works/inbox, status code: 400, request body: {\"@context\":\"https:\\/\\/www.w3.org\\/ns\\/activitystreams\",\"id\":\"https:\\/\\/kbin.melroy.org\\/f\\/object\\/a89df69f-9296-4c6d-9f27-085ccfc90539\",\"type\":\"Delete\",\"actor\":\"https:\\/\\/kbin.melroy.org\\/u\\/RmDebArk_5\",\"object\":\"https:\\/\\/kbin.melroy.org\\/u\\/RmDebArk_5\",\"to\":[\"https:\\/\\/www.w3.org\\/ns\\/activitystreams#Public\"],\"cc\":[\"https:\\/\\/kbin.melroy.org\\/u\\/RmDebArk_5\\/followers\"],\"removeData\":true}","content":"{\"error\":\"unknown\",\"message\":\"Fetched remote object https://kbin.melroy.org/u/RmDebArk_5 which was deleted\"}"},"level":400,"level_name":"ERROR","channel":"app","datetime":"2024-09-20T17:18:18.702108+02:00","extra":{}}
Related:
{"message":"Error thrown while handling message App\\Message\\ActivityPub\\Outbox\\DeliverMessage. Sending for retry #3 using 4800000 ms delay. Error: \"Handling \"App\\Message\\ActivityPub\\Outbox\\DeliverMessage\" failed: Post failed: https://sh.itjust.works/inbox, status code: 400, request body: {\"@context\":\"https:\\/\\/www.w3.org\\/ns\\/activitystreams\",\"id\":\"https:\\/\\/kbin.melroy.org\\/f\\/object\\/a89df69f-9296-4c6d-9f27-085ccfc90539\",\"type\":\"Delete\",\"actor\":\"https:\\/\\/kbin.melroy.org\\/u\\/RmDebArk_5\",\"object\":\"https:\\/\\/kbin.melroy.org\\/u\\/RmDebArk_5\",\"to\":[\"https:\\/\\/www.w3.org\\/ns\\/activitystreams#Public\"],\"cc\":[\"https:\\/\\/kbin.melroy.org\\/u\\/RmDebArk_5\\/followers\"],\"removeData\":true}\"","context":{"class":"App\\Message\\ActivityPub\\Outbox\\DeliverMessage","message_id":null,"retryCount":3,"delay":4800000,"error":"Handling \"App\\Message\\ActivityPub\\Outbox\\DeliverMessage\" failed: Post failed: https://sh.itjust.works/inbox, status code: 400, request body: {\"@context\":\"https:\\/\\/www.w3.org\\/ns\\/activitystreams\",\"id\":\"https:\\/\\/kbin.melroy.org\\/f\\/object\\/a89df69f-9296-4c6d-9f27-085ccfc90539\",\"type\":\"Delete\",\"actor\":\"https:\\/\\/kbin.melroy.org\\/u\\/RmDebArk_5\",\"object\":\"https:\\/\\/kbin.melroy.org\\/u\\/RmDebArk_5\",\"to\":[\"https:\\/\\/www.w3.org\\/ns\\/activitystreams#Public\"],\"cc\":[\"https:\\/\\/kbin.melroy.org\\/u\\/RmDebArk_5\\/followers\"],\"removeData\":true}","exception":{"class":"Symfony\\Component\\Messenger\\Exception\\HandlerFailedException","message":"Handling \"App\\Message\\ActivityPub\\Outbox\\DeliverMessage\" failed: Post failed: https://sh.itjust.works/inbox, status code: 400, request body: {\"@context\":\"https:\\/\\/www.w3.org\\/ns\\/activitystreams\",\"id\":\"https:\\/\\/kbin.melroy.org\\/f\\/object\\/a89df69f-9296-4c6d-9f27-085ccfc90539\",\"type\":\"Delete\",\"actor\":\"https:\\/\\/kbin.melroy.org\\/u\\/RmDebArk_5\",\"object\":\"https:\\/\\/kbin.melroy.org\\/u\\/RmDebArk_5\",\"to\":[\"https:\\/\\/www.w3.org\\/ns\\/activitystreams#Public\"],\"cc\":[\"https:\\/\\/kbin.melroy.org\\/u\\/RmDebArk_5\\/followers\"],\"removeData\":true}","code":0,"file":"/var/www/kbin.melroy.org/html/vendor/symfony/messenger/Middleware/HandleMessageMiddleware.php:124","previous":{"class":"App\\Exception\\InvalidApPostException","message":"Post failed: https://sh.itjust.works/inbox, status code: 400, request body: {\"@context\":\"https:\\/\\/www.w3.org\\/ns\\/activitystreams\",\"id\":\"https:\\/\\/kbin.melroy.org\\/f\\/object\\/a89df69f-9296-4c6d-9f27-085ccfc90539\",\"type\":\"Delete\",\"actor\":\"https:\\/\\/kbin.melroy.org\\/u\\/RmDebArk_5\",\"object\":\"https:\\/\\/kbin.melroy.org\\/u\\/RmDebArk_5\",\"to\":[\"https:\\/\\/www.w3.org\\/ns\\/activitystreams#Public\"],\"cc\":[\"https:\\/\\/kbin.melroy.org\\/u\\/RmDebArk_5\\/followers\"],\"removeData\":true}","code":0,"file":"/var/www/kbin.melroy.org/html/src/Service/ActivityPub/ApHttpClient.php:410"}}},"level":300,"level_name":"WARNING","channel":"messenger","datetime":"2024-09-20T17:18:18.704266+02:00","extra":{}}
24th - Still seeing duplicate key value violates unique constraint issues:
Describe the bug
I see a long list of errors and warnings in the prod log.
We really need to clean-up some of those and try to fix the PHP warning or PHP or Twig errors we see... This is a follow-up issue of: #1119
On which Mbin instance did you find the bug?
kbin.melroy.org
Which Mbin version was running on the instance?
1.7.2
To Reproduce
Steps to reproduce the behavior:
tail -f var/log/prod-xx-x-x-x.log
Expected behavior
Minimal amount of errors and warnings in the production log. Only some error messages make sense if there is a real error and we still want to keep reporting it to admin of the instance.
Let's just see how much we can resolve in the upcoming 1.7.3 milestone..
List of issues
Full stack trace
And also seems to be related:
Full stack trace
We need to add more blocked domain checks (isBannedInstance) where there are getActivityObject calls. In the following files:
invalid input syntax for type integer: \"{user_id}\"
:This error also can be seens if the email confirmation URL is wrong or wrongly interpreted by the client or whatever is wrong.. (using the
verify/email/..
path):The logs are missing a request URL (path) for sure, otherwise it's very hard to debug the above messages, without any clue which kind of request we are talking about. But for sure it could trigger this error above by the confirmation email link.
Fetched remote object https://kbin.melroy.org/u/RmDebArk_5 which was deleted
. Is this is error? Can we fix it in a better way? If not, why do we log it?Related:
The text was updated successfully, but these errors were encountered: