[bug] Unusually large amount of "Object has been deleted" errors #355

Closed
opened 2022-12-09 07:54:10 +00:00 by Ghost · 7 comments

Your setup

Source

Extra details

Arch Linux amd64

Version

3.4.0-194-g59fde45b-develop

PostgreSQL version

14.6

What were you trying to do?

n/a

What did you expect to happen?

Don't think this should be semi-spammed in my logs every few seconds as this seems to be a new issue.

What actually happened?

Noticed in Akkoma logs a significant amount of [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} being logged every few seconds for what seems like hours on end?

Memory and CPU usage seems fine, I don't do fancy monitoring so I can't tell if I see any kind of spikes. Postgres logs don't report any errors or anything concerning associated as far as I can tell.

Restarting Akkoma doesn't seem to reliably solve this weird issue.

Logs

# (snippet)

Dec 09 07:19:47 plem.sapphic.site pleroma[18015]: 07:19:47.576 request_id=Fy8O6pSWOROx39wAGoUx [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:19:47 plem.sapphic.site pleroma[18015]: 07:19:47.698 request_id=Fy8O6pSWOROx39wAGoUx [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:19:48 plem.sapphic.site pleroma[18015]: 07:19:48.023 request_id=Fy8O6piUDdsxAMQAGoVB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:19:48 plem.sapphic.site pleroma[18015]: 07:19:48.165 request_id=Fy8O6piUDdsxAMQAGoVB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:20:07 plem.sapphic.site pleroma[18015]: 07:20:07.604 request_id=Fy8O7z3TVH43cPYAGoXR [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:20:07 plem.sapphic.site pleroma[18015]: 07:20:07.734 request_id=Fy8O7z3TVH43cPYAGoXR [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:20:13 plem.sapphic.site pleroma[18015]: 07:20:13.088 request_id=Fy8O8IUeTioVz1UAGoaB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:20:13 plem.sapphic.site pleroma[18015]: 07:20:13.209 request_id=Fy8O8IUeTioVz1UAGoaB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:20:33 plem.sapphic.site pleroma[18015]: 07:20:33.630 request_id=Fy8O9U2PT1Yl9pYAGodR [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:20:33 plem.sapphic.site pleroma[18015]: 07:20:33.751 request_id=Fy8O9U2PT1Yl9pYAGodR [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:20:35 plem.sapphic.site pleroma[18015]: 07:20:35.270 request_id=Fy8O9a7t-XjiohkAGodh [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:20:35 plem.sapphic.site pleroma[18015]: 07:20:35.401 request_id=Fy8O9a7t-XjiohkAGodh [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:20:40 plem.sapphic.site pleroma[18015]: 07:20:40.379 request_id=Fy8O9sDgehtIk04AGodx [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:20:40 plem.sapphic.site pleroma[18015]: 07:20:40.519 request_id=Fy8O9sDgehtIk04AGodx [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:20:54 plem.sapphic.site pleroma[18015]: 07:20:54.992 request_id=Fy8O-kbbq0nHHREAGoix [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:20:55 plem.sapphic.site pleroma[18015]: 07:20:55.114 request_id=Fy8O-kbbq0nHHREAGoix [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:20:56 plem.sapphic.site pleroma[18015]: 07:20:56.642 request_id=Fy8O-pbDNh9F0-YAGojB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:20:56 plem.sapphic.site pleroma[18015]: 07:20:56.791 request_id=Fy8O-pbDNh9F0-YAGojB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:21:13 plem.sapphic.site pleroma[18015]: 07:21:13.343 request_id=Fy8O_oypjAJa-mcAGorx [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:21:13 plem.sapphic.site pleroma[18015]: 07:21:13.483 request_id=Fy8O_oypjAJa-mcAGorx [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:21:16 plem.sapphic.site pleroma[18015]: 07:21:16.111 request_id=Fy8O_y8LMTQ-X0MAGosB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:21:16 plem.sapphic.site pleroma[18015]: 07:21:16.258 request_id=Fy8O_y8LMTQ-X0MAGosB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:21:19 plem.sapphic.site pleroma[18015]: 07:21:19.593 request_id=Fy8PAAE3i9eS8xkAGosh [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:21:19 plem.sapphic.site pleroma[18015]: 07:21:19.733 request_id=Fy8PAAE3i9eS8xkAGosh [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:21:20 plem.sapphic.site pleroma[18015]: 07:21:20.258 request_id=Fy8PACiC1oLFx9kAGosx [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:21:20 plem.sapphic.site pleroma[18015]: 07:21:20.380 request_id=Fy8PACiC1oLFx9kAGosx [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:21:22 plem.sapphic.site pleroma[18015]: 07:21:22.033 request_id=Fy8PAJIz6KgrzoMAGotB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:21:22 plem.sapphic.site pleroma[18015]: 07:21:22.164 request_id=Fy8PAJIz6KgrzoMAGotB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:21:23 plem.sapphic.site pleroma[18015]: 07:21:23.143 request_id=Fy8PANQqwTixmokAGouB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:21:23 plem.sapphic.site pleroma[18015]: 07:21:23.281 request_id=Fy8PANQqwTixmokAGouB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:21:23 plem.sapphic.site pleroma[18015]: 07:21:23.834 request_id=Fy8PAONeAPMNLd4AGouR [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}
Dec 09 07:21:24 plem.sapphic.site pleroma[18015]: 07:21:24.014 request_id=Fy8PAONeAPMNLd4AGouR [error] Could not fetch user, {nil, {:error, "Object has been deleted"}}

Severity

I can manage

Have you searched for this issue?

  • I have double-checked and have not found this issue mentioned anywhere.
### Your setup Source ### Extra details Arch Linux amd64 ### Version 3.4.0-194-g59fde45b-develop ### PostgreSQL version 14.6 ### What were you trying to do? n/a ### What did you expect to happen? Don't think this should be semi-spammed in my logs every few seconds as this seems to be a new issue. ### What actually happened? Noticed in Akkoma logs a significant amount of `[error] Could not fetch user, {nil, {:error, "Object has been deleted"}}` being logged every few seconds for what seems like hours on end? Memory and CPU usage seems fine, I don't do fancy monitoring so I can't tell if I see any kind of spikes. Postgres logs don't report any errors or anything concerning associated as far as I can tell. Restarting Akkoma doesn't seem to reliably solve this weird issue. ### Logs ```shell # (snippet) Dec 09 07:19:47 plem.sapphic.site pleroma[18015]: 07:19:47.576 request_id=Fy8O6pSWOROx39wAGoUx [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:19:47 plem.sapphic.site pleroma[18015]: 07:19:47.698 request_id=Fy8O6pSWOROx39wAGoUx [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:19:48 plem.sapphic.site pleroma[18015]: 07:19:48.023 request_id=Fy8O6piUDdsxAMQAGoVB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:19:48 plem.sapphic.site pleroma[18015]: 07:19:48.165 request_id=Fy8O6piUDdsxAMQAGoVB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:20:07 plem.sapphic.site pleroma[18015]: 07:20:07.604 request_id=Fy8O7z3TVH43cPYAGoXR [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:20:07 plem.sapphic.site pleroma[18015]: 07:20:07.734 request_id=Fy8O7z3TVH43cPYAGoXR [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:20:13 plem.sapphic.site pleroma[18015]: 07:20:13.088 request_id=Fy8O8IUeTioVz1UAGoaB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:20:13 plem.sapphic.site pleroma[18015]: 07:20:13.209 request_id=Fy8O8IUeTioVz1UAGoaB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:20:33 plem.sapphic.site pleroma[18015]: 07:20:33.630 request_id=Fy8O9U2PT1Yl9pYAGodR [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:20:33 plem.sapphic.site pleroma[18015]: 07:20:33.751 request_id=Fy8O9U2PT1Yl9pYAGodR [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:20:35 plem.sapphic.site pleroma[18015]: 07:20:35.270 request_id=Fy8O9a7t-XjiohkAGodh [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:20:35 plem.sapphic.site pleroma[18015]: 07:20:35.401 request_id=Fy8O9a7t-XjiohkAGodh [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:20:40 plem.sapphic.site pleroma[18015]: 07:20:40.379 request_id=Fy8O9sDgehtIk04AGodx [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:20:40 plem.sapphic.site pleroma[18015]: 07:20:40.519 request_id=Fy8O9sDgehtIk04AGodx [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:20:54 plem.sapphic.site pleroma[18015]: 07:20:54.992 request_id=Fy8O-kbbq0nHHREAGoix [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:20:55 plem.sapphic.site pleroma[18015]: 07:20:55.114 request_id=Fy8O-kbbq0nHHREAGoix [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:20:56 plem.sapphic.site pleroma[18015]: 07:20:56.642 request_id=Fy8O-pbDNh9F0-YAGojB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:20:56 plem.sapphic.site pleroma[18015]: 07:20:56.791 request_id=Fy8O-pbDNh9F0-YAGojB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:21:13 plem.sapphic.site pleroma[18015]: 07:21:13.343 request_id=Fy8O_oypjAJa-mcAGorx [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:21:13 plem.sapphic.site pleroma[18015]: 07:21:13.483 request_id=Fy8O_oypjAJa-mcAGorx [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:21:16 plem.sapphic.site pleroma[18015]: 07:21:16.111 request_id=Fy8O_y8LMTQ-X0MAGosB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:21:16 plem.sapphic.site pleroma[18015]: 07:21:16.258 request_id=Fy8O_y8LMTQ-X0MAGosB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:21:19 plem.sapphic.site pleroma[18015]: 07:21:19.593 request_id=Fy8PAAE3i9eS8xkAGosh [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:21:19 plem.sapphic.site pleroma[18015]: 07:21:19.733 request_id=Fy8PAAE3i9eS8xkAGosh [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:21:20 plem.sapphic.site pleroma[18015]: 07:21:20.258 request_id=Fy8PACiC1oLFx9kAGosx [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:21:20 plem.sapphic.site pleroma[18015]: 07:21:20.380 request_id=Fy8PACiC1oLFx9kAGosx [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:21:22 plem.sapphic.site pleroma[18015]: 07:21:22.033 request_id=Fy8PAJIz6KgrzoMAGotB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:21:22 plem.sapphic.site pleroma[18015]: 07:21:22.164 request_id=Fy8PAJIz6KgrzoMAGotB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:21:23 plem.sapphic.site pleroma[18015]: 07:21:23.143 request_id=Fy8PANQqwTixmokAGouB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:21:23 plem.sapphic.site pleroma[18015]: 07:21:23.281 request_id=Fy8PANQqwTixmokAGouB [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:21:23 plem.sapphic.site pleroma[18015]: 07:21:23.834 request_id=Fy8PAONeAPMNLd4AGouR [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} Dec 09 07:21:24 plem.sapphic.site pleroma[18015]: 07:21:24.014 request_id=Fy8PAONeAPMNLd4AGouR [error] Could not fetch user, {nil, {:error, "Object has been deleted"}} ``` ### Severity I can manage ### Have you searched for this issue? - [x] I have double-checked and have not found this issue mentioned anywhere.
Author

Following the commits that provide more logging in this case, it seems that all of these deleted objects are deleted (?) accounts?

Paste because too much text wrapping: https://paste.rs/YAj

All of the said accounts in every log of this error leads to a 404.

But I have no idea why this is happening so much every few seconds?

Also side note: I switched to source install, still on latest develop branch.

Following the commits that provide more logging in this case, it seems that all of these deleted objects are deleted (?) accounts? Paste because too much text wrapping: https://paste.rs/YAj All of the said accounts in *every* log of this error leads to a 404. But I have no idea why this is happening so much every few seconds? Also side note: I switched to source install, still on latest develop branch.

is that the right link? it goes to some datastudio stuff

is that the right link? it goes to some datastudio stuff
Author

@floatingghost Looks like the paste expired and the paste ID got taken by something else.

Here's a new paste from a more reliable site: 0ea80966b1/gistfile1.txt

@floatingghost Looks like the paste expired and the paste ID got taken by something else. Here's a new paste from a more reliable site: https://gist.githubusercontent.com/r3g-5z/bc21259150ce5de9fb37f290044435f9/raw/0ea80966b126f02285ff35b990dc8d1607645e3d/gistfile1.txt

that looks normal

mastodon[.]technology has gone offline, so after a few days your instance should notice that and stop bothering them

otherwise nothing seems too amiss there

that looks normal mastodon[.]technology has gone offline, so after a few days your instance should notice that and stop bothering them otherwise nothing seems too amiss there
Author

Is it really normal for "Object has been deleted" to be nearly spammed in my server logs? I get this message almost every 3 seconds.

Is it really normal for "Object has been deleted" to be nearly spammed in my server logs? I get this message almost every 3 seconds.

yes

yes
Author

Guess I'll just have to ignore this and filter out these messages when I need to get logs.

Guess I'll just have to ignore this and filter out these messages when I need to get logs.
Ghost closed this issue 2022-12-14 13:37:58 +00:00
Sign in to join this conversation.
No milestone
No project
No assignees
2 participants
Notifications
Due date
The due date is invalid or out of range. Please use the format "yyyy-mm-dd".

No due date set.

Dependencies

No dependencies set.

Reference: AkkomaGang/akkoma#355
No description provided.