Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

[Issue]: Cannot update refresh progress of item 'Folder' (...) because a refresh for this item is not running #11264

Closed
1 task done
sjorge opened this issue Mar 30, 2024 · 5 comments · Fixed by #11341
Closed
1 task done
Labels
bug Something isn't working confirmed This issue has been reviewed and confirmed regression Regression from previous build

Comments

@sjorge
Copy link

sjorge commented Mar 30, 2024

Please describe your bug

After upgrading from 10.8.13 -> 10.9.0 while running a Scan all libraries (After updating all plugins) it seems this never really finishes and I get a lot of:

Mar 29 15:03:08 artemis.acheron.be jellyfin[236568]: [15:03:08] [ERR] Error refreshing folder
Mar 29 15:03:08 artemis.acheron.be jellyfin[236568]: System.InvalidOperationException: Cannot update refresh progress of item 'Folder' (ed4b05e7fae6991b5d3a95b1bf5bb510) because a refresh for this item is not running
Mar 29 15:03:08 artemis.acheron.be jellyfin[236568]:    at MediaBrowser.Providers.Manager.ProviderManager.<>c__DisplayClass69_0.<OnRefreshProgress>b__0(Guid _)
Mar 29 15:03:08 artemis.acheron.be jellyfin[236568]:    at MediaBrowser.Providers.Manager.ProviderManager.OnRefreshProgress(BaseItem item, Double progress)
Mar 29 15:03:08 artemis.acheron.be jellyfin[236568]:    at MediaBrowser.Controller.Entities.Folder.<>c__DisplayClass64_2.<ValidateChildrenInternal2>b__1(Double innerPercent)

I left it running for a nigh and it still never finished. Deleting the library to start fresh also doesn't help as the library never seems to delete.

However nuking /var/lib/jellyfin, /var/cache/jellyfinand/etc/jellyfin` and going through the setup wizard again on 10.9.0 and adding a new library does seem to work and I do not get these errors. (I only did a partial movie library)

Reproduction Steps

  1. install unstable (20240325) over an existing stable
  2. Swap the plugin repo to the unstable one
  3. trigger the plugin update schedule task
  4. restart to load the updated plugins
  5. trigger a scan all libraries

Jellyfin Version

Unstable (master branch)

if other:

10.9.0

Environment

- OS: Ubuntu 22.04.4 LTS
- Linux Kernel: GNU/Linux 6.5.0-26-generic x86_64 (for ARC support)
- Virtualization: BHYVE
- Clients: Browser
- Browser: Firefox 124.0b9 (64-bit)
- FFmpeg Version: jellyfin-ffmpeg6 - 6.0.1-5-jammy
- Playback Method: n/a
- Hardware Acceleration: QSV (also tried without, no difference)
- GPU Model: Intel Arc A310 Low Profile 4GB (ASRock)
- Plugins: AniDB, AniList, AudioDB, Cover Art Archive, Fanart, MusicBrainz, OMDb, Session Cleaner, Studio Images, TMDb, TMDb Box Sets, Trakt, Webhook
- Reverse Proxy: apache
- Base URL: /jellyfin
- Networking: vnic
- Storage: local (config) + NFS (media)

Jellyfin logs

Lots of these:

Mar 29 15:03:08 artemis.acheron.be jellyfin[236568]: [15:03:08] [ERR] Error refreshing folder
Mar 29 15:03:08 artemis.acheron.be jellyfin[236568]: System.InvalidOperationException: Cannot update refresh progress of item 'Folder' (ed4b05e7fae6991b5d3a95b1bf5bb510) because a refresh for this item is not running
Mar 29 15:03:08 artemis.acheron.be jellyfin[236568]:    at MediaBrowser.Providers.Manager.ProviderManager.<>c__DisplayClass69_0.<OnRefreshProgress>b__0(Guid _)
Mar 29 15:03:08 artemis.acheron.be jellyfin[236568]:    at MediaBrowser.Providers.Manager.ProviderManager.OnRefreshProgress(BaseItem item, Double progress)
Mar 29 15:03:08 artemis.acheron.be jellyfin[236568]:    at MediaBrowser.Controller.Entities.Folder.<>c__DisplayClass64_2.<ValidateChildrenInternal2>b__1(Double innerPercent)

Also lots of

Mar 29 14:50:33 artemis.acheron.be jellyfin[236568]: [14:50:33] [ERR] Cannot get image dimensions for /var/lib/jellyfin/metadata/People/J/Jessica Raine/folder.jpg
Mar 29 14:50:33 artemis.acheron.be jellyfin[236568]: System.IO.FileNotFoundException: File not found
Mar 29 14:50:33 artemis.acheron.be jellyfin[236568]: File name: '/var/lib/jellyfin/metadata/People/J/Jessica Raine/folder.jpg'
Mar 29 14:50:33 artemis.acheron.be jellyfin[236568]:    at Jellyfin.Drawing.Skia.SkiaEncoder.GetImageSize(String path)
Mar 29 14:50:33 artemis.acheron.be jellyfin[236568]:    at Jellyfin.Drawing.ImageProcessor.GetImageDimensions(BaseItem item, ItemImageInfo info)
Mar 29 14:50:33 artemis.acheron.be jellyfin[236568]:    at Emby.Server.Implementations.Library.LibraryManager.UpdateImagesAsync(BaseItem item, Boolean forceUpdate)

I'm assuming they are not related as folder.jpg does indeed not exist for those people. I'm pretty sure I've seen a similar error for the missing people images on stable too at some point. But included it just in case as this is the only other ERR that I could spot while skimming the logs.



### FFmpeg logs

```shell
n/a

Please attach any browser or client logs here

n/a

Please attach any screenshots here

n/a

Code of Conduct

  • I agree to follow this project's Code of Conduct
@sjorge sjorge added the bug Something isn't working label Mar 30, 2024
@jellyfin-bot
Copy link
Contributor

Hi, it seems like your issue report has the following item(s) that need to be addressed:

  • The title of this issue report is empty. Please change it to something descriptive.
  • You have not provided a sufficient amount of logs. Please provide every line during the time of the event plus 10 lines before and after.
  • You have not provided the ffmpeg log unaltered from the server. Please provide a valid ffmpeg log.

This is an automated message, currently under testing. Please file an issue here if you encounter any problems.

@sjorge sjorge changed the title [Issue]: [Issue]: Cannot update refresh progress of item 'Folder' (...) because a refresh for this item is not running Mar 30, 2024
@sjorge
Copy link
Author

sjorge commented Mar 30, 2024

I did a tiny bit of digging, the message comes from

"Cannot update refresh progress of item '{0}' ({1}) because a refresh for this item is not running",

And a bit above it we have:

// TODO: Need to hunt down the conditions for this happening

So it might be some weird thing that is hard to trigger or maybe only triggerable when upgrading a library from stable -> unstable.

Edit: I only have them for type Folder.

Edit 2: I looked up 8 (picked at random) of those ItemId's, they are all Folder that happen to be in the Folders list under the library. If it somehow can't start the refresh of those that could indeed explain why it never completes a refresh.

@solidsnake1298
Copy link
Member

I was able to replicate when I migrated my 10.8.13 testing instance to 10.9. But it happens on both an upgraded 10.8 -> 10.9 /config and a "clean" unstable instance.

As far as I can tell, there is no harm in this message, though.

@jellyfin-bot jellyfin-bot added this to Needs triage in Issue Triage for Main Repo Apr 1, 2024
@solidsnake1298 solidsnake1298 added the confirmed This issue has been reviewed and confirmed label Apr 1, 2024
@fabwa
Copy link

fabwa commented Apr 3, 2024

Experience the same. I want to add that it freezes the controls on the dashboard

"Scan All Libraries", "Restart", "Shutdown"

for me. So seems there is some harm beyond just showing a message in the logs.

@nielsvanvelzen
Copy link
Member

nielsvanvelzen commented Apr 8, 2024

This is a regression from #10969. We previously implemented an extremely simple IProgress that directly called its event listeners. The System.Progress implementation is more "advanced" may call the event listeners after the actual task we're waiting for is finished. This is documented in the .NET docs.

Also relevant; the runtime implementation

@nielsvanvelzen nielsvanvelzen added this to To do in Release 10.9.z via automation Apr 8, 2024
@felix920506 felix920506 added the regression Regression from previous build label Apr 8, 2024
Issue Triage for Main Repo automation moved this from Needs triage to Closed/Done Apr 12, 2024
Release 10.9.z automation moved this from To do to Done Apr 12, 2024
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
bug Something isn't working confirmed This issue has been reviewed and confirmed regression Regression from previous build
Projects
Archived in project
Development

Successfully merging a pull request may close this issue.

6 participants