SOLVED: Scan media library stuck at 0.0% and playback stops at random. - Printable Version +- Jellyfin Forum (https://forum.jellyfin.org) +-- Forum: Support (https://forum.jellyfin.org/f-support) +--- Forum: Troubleshooting (https://forum.jellyfin.org/f-troubleshooting) +--- Thread: SOLVED: Scan media library stuck at 0.0% and playback stops at random. (/t-solved-scan-media-library-stuck-at-0-0-and-playback-stops-at-random) Pages:
1
2
|
Scan media library stuck at 0.0% and playback stops at random. - Nihalvm - 2024-04-08 I am running 10.9.0 build 2024040106 on Debian 12. The only reason I am running 10.9.0 is I wanted to use hardware transcoding and tone mapping on my AMD 5700g, which is not yet available on 10.8.x This setup has been working fine for the past few months (with an older build ofcourse). However over the last week I am having issues with the server. There were no config changes over the last few months. 1. Scan media library is just stuck at 0.0% and the logs don't show any error or success message other wise. New media doesn't get scanned until I restart the whole machine 2. Having playback and crashes. I have posted a few logs below. Hoping someone can help me debug the issues. timestamp of issues around [2024-04-07 15:52:15.659 +10:00] in the jellyfin log file. RE: Scan media library stuck at 0.0% and playback stops at random. - TheDreadPirate - 2024-04-08 Your image is a week out of date. Pull the current image and try again. RE: Scan media library stuck at 0.0% and playback stops at random. - Nihalvm - 2024-04-08 there is no new image for Debian 12? https://repo.jellyfin.org/?path=/server/debian/latest-unstable/amd64 apt update says all packages are upto date. RE: Scan media library stuck at 0.0% and playback stops at random. - Nihalvm - 2024-04-08 (2024-04-08, 12:53 AM)TheDreadPirate Wrote: Your image is a week out of date. Pull the current image and try again. Just got the new build as an update. but its the same. scan media library scheduled task just stays at 0.0%. however if i go the library and manually scan Movies or TV it completes. however the "Scan All Libraries" or the scheduled task just stays at 0.0%. new logs if you are interested after updating to 2024040805 and rebooting. [2024-04-08 19:14:54.144 +10:00] [INF] Jellyfin version: "10.9.0" [2024-04-08 19:14:54.174 +10:00] [INF] Environment Variables: ["[JELLYFIN_FFMPEG_OPT, --ffmpeg=/usr/lib/jellyfin-ffmpeg/ffmpeg]", "[JELLYFIN_DATA_DIR, /var/lib/jellyfin]", "[JELLYFIN_WEB_OPT, --webdir=/usr/share/jellyfin/web]", "[JELLYFIN_ARGS, $JELLYFIN_WEB_OPT $JELLYFIN_FFMPEG_OPT $JELLYFIN_SERVICE_OPT $JELLYFIN_NOWEBAPP_OPT $JELLFIN_ADDITIONAL_OPTS --datadir $JELLYFIN_DATA_DIR --configdir $JELLYFIN_CONFIG_DIR --logdir $JELLYFIN_LOG_DIR --cachedir $JELLYFIN_CACHE_DIR]", "[JELLYFIN_CACHE_DIR, /var/cache/jellyfin]", "[JELLYFIN_CONFIG_DIR, /etc/jellyfin]", "[JELLYFIN_LOG_DIR, /var/log/jellyfin]", "[JELLYFIN_USER, jellyfin]", "[JELLYFIN_ADDITIONAL_OPTS, ]"] [2024-04-08 19:14:54.175 +10:00] [INF] Arguments: ["/usr/lib/jellyfin/bin/jellyfin.dll", "--webdir=/usr/share/jellyfin/web", "--ffmpeg=/usr/lib/jellyfin-ffmpeg/ffmpeg"] [2024-04-08 19:14:54.175 +10:00] [INF] Operating system: "Debian GNU/Linux 12 (bookworm)" [2024-04-08 19:14:54.175 +10:00] [INF] Architecture: X64 [2024-04-08 19:14:54.176 +10:00] [INF] 64-Bit Process: True [2024-04-08 19:14:54.176 +10:00] [INF] User Interactive: True [2024-04-08 19:14:54.176 +10:00] [INF] Processor count: 2 [2024-04-08 19:14:54.176 +10:00] [INF] Program data path: "/var/lib/jellyfin" [2024-04-08 19:14:54.176 +10:00] [INF] Log directory path: "/var/log/jellyfin" [2024-04-08 19:14:54.176 +10:00] [INF] Config directory path: "/etc/jellyfin" [2024-04-08 19:14:54.176 +10:00] [INF] Cache path: "/var/cache/jellyfin" [2024-04-08 19:14:54.177 +10:00] [INF] Web resources path: "/usr/share/jellyfin/web" [2024-04-08 19:14:54.177 +10:00] [INF] Application directory: "/usr/lib/jellyfin/bin/" [2024-04-08 19:14:54.286 +10:00] [INF] Setting cache path: "/mnt/truenas/jellyfin/cache" [2024-04-08 19:14:54.393 +10:00] [INF] Loading assemblies [2024-04-08 19:14:54.433 +10:00] [INF] Loaded assembly "Nick.Plugin.Jellyscrub, Version=2.0.0.0, Culture=neutral, PublicKeyToken=null" from "/var/lib/jellyfin/plugins/Jellyscrub_2.0.0.0/Nick.Plugin.Jellyscrub.dll" [2024-04-08 19:14:54.438 +10:00] [INF] Loaded assembly "Jellyfin.Plugin.OpenSubtitles, Version=19.0.0.0, Culture=neutral, PublicKeyToken=null" from "/var/lib/jellyfin/plugins/Open Subtitles_19.0.0.0/Jellyfin.Plugin.OpenSubtitles.dll" [2024-04-08 19:14:54.440 +10:00] [ERR] Failed to load assembly "/var/lib/jellyfin/plugins/Playback Reporting_13.0.0.0/Jellyfin.Plugin.PlaybackReporting.dll". This error occurs when a plugin references an incompatible version of one of the shared libraries. Disabling plugin System.Reflection.ReflectionTypeLoadException: Unable to load one or more of the requested types. Could not load type 'MediaBrowser.Controller.Plugins.IServerEntryPoint' from assembly 'MediaBrowser.Controller, Version=10.9.0.0, Culture=neutral, PublicKeyToken=null'. Could not load file or assembly 'SQLitePCL.pretty, Version=1.0.0.0, Culture=neutral, PublicKeyToken=null'. The system cannot find the file specified. at System.Reflection.RuntimeModule.GetTypes(RuntimeModule module) at System.Reflection.RuntimeModule.GetTypes() at Emby.Server.Implementations.Plugins.PluginManager.LoadAssemblies()+MoveNext() System.TypeLoadException: Could not load type 'MediaBrowser.Controller.Plugins.IServerEntryPoint' from assembly 'MediaBrowser.Controller, Version=10.9.0.0, Culture=neutral, PublicKeyToken=null'. System.IO.FileNotFoundException: Could not load file or assembly 'SQLitePCL.pretty, Version=1.0.0.0, Culture=neutral, PublicKeyToken=null'. The system cannot find the file specified. File name: 'SQLitePCL.pretty, Version=1.0.0.0, Culture=neutral, PublicKeyToken=null' [2024-04-08 19:14:54.450 +10:00] [INF] Loaded assembly "Jellyfin.Plugin.Reports, Version=15.0.0.0, Culture=neutral, PublicKeyToken=null" from "/var/lib/jellyfin/plugins/Reports_15.0.0.0/Jellyfin.Plugin.Reports.dll" [2024-04-08 19:14:54.452 +10:00] [INF] Loaded assembly "TvDbSharper, Version=3.2.2.0, Culture=neutral, PublicKeyToken=null" from "/var/lib/jellyfin/plugins/TheTVDB_10.0.0.0/TvDbSharper.dll" [2024-04-08 19:14:54.454 +10:00] [ERR] Failed to load assembly "/var/lib/jellyfin/plugins/TheTVDB_10.0.0.0/Jellyfin.Plugin.Tvdb.dll". This error occurs when a plugin references an incompatible version of one of the shared libraries. Disabling plugin System.Reflection.ReflectionTypeLoadException: Unable to load one or more of the requested types. Could not load type 'MediaBrowser.Common.Plugins.IPluginServiceRegistrator' from assembly 'MediaBrowser.Common, Version=10.9.0.0, Culture=neutral, PublicKeyToken=null'. Could not load type 'MediaBrowser.Controller.Plugins.IServerEntryPoint' from assembly 'MediaBrowser.Controller, Version=10.9.0.0, Culture=neutral, PublicKeyToken=null'. at System.Reflection.RuntimeModule.GetTypes(RuntimeModule module) at System.Reflection.RuntimeModule.GetTypes() at Emby.Server.Implementations.Plugins.PluginManager.LoadAssemblies()+MoveNext() System.TypeLoadException: Could not load type 'MediaBrowser.Common.Plugins.IPluginServiceRegistrator' from assembly 'MediaBrowser.Common, Version=10.9.0.0, Culture=neutral, PublicKeyToken=null'. System.TypeLoadException: Could not load type 'MediaBrowser.Controller.Plugins.IServerEntryPoint' from assembly 'MediaBrowser.Controller, Version=10.9.0.0, Culture=neutral, PublicKeyToken=null'. [2024-04-08 19:14:54.498 +10:00] [INF] Defined LAN addresses: ["127.0.0.1/8", "10.0.0.0/8", "172.16.0.0/12", "192.168.0.0/16"] [2024-04-08 19:14:54.498 +10:00] [INF] Defined LAN exclusions: [] [2024-04-08 19:14:54.498 +10:00] [INF] Using LAN addresses: ["127.0.0.1/8", "10.0.0.0/8", "172.16.0.0/12", "192.168.0.0/16"] [2024-04-08 19:14:54.499 +10:00] [INF] Using bind addresses: ["127.0.0.1", "10.0.0.108"] [2024-04-08 19:14:54.499 +10:00] [INF] Remote IP filter is "Allowlist" [2024-04-08 19:14:54.500 +10:00] [INF] Filter list: [] [2024-04-08 19:14:56.202 +10:00] [WRN] !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! [2024-04-08 19:14:56.202 +10:00] [WRN] !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! [2024-04-08 19:14:56.202 +10:00] [WRN] PLEASE VISIT THE JELLYSCRUB PLUGIN CONFIG PAGE TO CONVERT TRICKPLAY FILES [2024-04-08 19:14:56.202 +10:00] [WRN] !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! [2024-04-08 19:14:56.203 +10:00] [WRN] !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! [2024-04-08 19:14:56.212 +10:00] [INF] Attempting to inject trickplay script code in "/usr/share/jellyfin/web/index.html" [2024-04-08 19:14:56.214 +10:00] [ERR] Encountered exception while writing to "/usr/share/jellyfin/web/index.html": "System.UnauthorizedAccessException: Access to the path '/usr/share/jellyfin/web/index.html' is denied. ---> System.IO.IOException: Permission denied --- End of inner exception stack trace --- at Interop.ThrowExceptionForIoErrno(ErrorInfo errorInfo, String path, Boolean isDirError) at Microsoft.Win32.SafeHandles.SafeFileHandle.Open(String path, OpenFlags flags, Int32 mode, Boolean failForSymlink, Boolean& wasSymlink, Func 4 createOpenException) 4 createOpenException)at System.IO.File.OpenHandle(String path, FileMode mode, FileAccess access, FileShare share, FileOptions options, Int64 preallocationSize) at System.IO.File.WriteToFile(String path, FileMode mode, String contents, Encoding encoding) at Nick.Plugin.Jellyscrub.JellyscrubPlugin..ctor(IApplicationPaths applicationPaths, IXmlSerializer xmlSerializer, ILogger`1 logger, IServerConfigurationManager configurationManager)" [2024-04-08 19:14:56.214 +10:00] [INF] Loaded plugin: "Jellyscrub" "2.0.0.0" [2024-04-08 19:14:56.215 +10:00] [INF] Loaded plugin: "Open Subtitles" "19.0.0.0" [2024-04-08 19:14:56.216 +10:00] [INF] Loaded plugin: "Reports" "15.0.0.0" [2024-04-08 19:14:56.216 +10:00] [INF] Loaded plugin: "TMDb" "10.9.0.0" [2024-04-08 19:14:56.216 +10:00] [INF] Loaded plugin: "Studio Images" "10.9.0.0" [2024-04-08 19:14:56.216 +10:00] [INF] Loaded plugin: "OMDb" "10.9.0.0" [2024-04-08 19:14:56.229 +10:00] [INF] Loaded plugin: "MusicBrainz" "10.9.0.0" [2024-04-08 19:14:56.229 +10:00] [INF] Loaded plugin: "AudioDB" "10.9.0.0" [2024-04-08 19:14:56.272 +10:00] [INF] Kestrel is listening on "127.0.0.1" [2024-04-08 19:14:56.273 +10:00] [INF] Kestrel is listening on "10.0.0.108" [2024-04-08 19:14:56.704 +10:00] [WRN] The WebRootPath was not found: "/var/lib/jellyfin/wwwroot". Static files may be unavailable. [2024-04-08 19:14:56.730 +10:00] [INF] Running startup tasks [2024-04-08 19:14:56.744 +10:00] [INF] Daily trigger for "Generate Trickplay Images" set to fire at 2024-04-09 03:00:00.000 +10:00, which is 07:45:03.2556010 from now. [2024-04-08 19:14:56.747 +10:00] [INF] Daily trigger for "Extract Chapter Images" set to fire at 2024-04-09 02:00:00.000 +10:00, which is 06:45:03.2530077 from now. [2024-04-08 19:14:56.846 +10:00] [INF] Found ffmpeg version "6.0.1" [2024-04-08 19:14:56.886 +10:00] [INF] Available "decoders": ["libdav1d", "av1", "av1_cuvid", "av1_qsv", "h264", "h264_qsv", "h264_cuvid", "hevc", "hevc_qsv", "hevc_cuvid", "mpeg2video", "mpeg2_qsv", "mpeg2_cuvid", "mpeg4", "mpeg4_cuvid", "msmpeg4", "vc1_qsv", "vc1_cuvid", "vp8", "libvpx", "vp8_cuvid", "vp8_qsv", "vp9", "libvpx-vp9", "vp9_cuvid", "vp9_qsv", "aac", "ac3", "dca", "flac", "mp3", "truehd"] [2024-04-08 19:14:56.903 +10:00] [INF] Available "encoders": ["libsvtav1", "av1_nvenc", "av1_qsv", "av1_amf", "av1_vaapi", "libx264", "h264_amf", "h264_nvenc", "h264_qsv", "h264_v4l2m2m", "h264_vaapi", "libx265", "hevc_amf", "hevc_nvenc", "hevc_qsv", "hevc_vaapi", "mpeg4", "msmpeg4", "libvpx", "libvpx-vp9", "aac", "libfdk_aac", "ac3", "dca", "flac", "libmp3lame", "libopus", "truehd", "libvorbis", "srt"] [2024-04-08 19:14:56.921 +10:00] [INF] Available filters: ["deinterlace_qsv", "deinterlace_vaapi", "hwupload_cuda", "hwupload_vaapi", "libplacebo", "overlay_opencl", "overlay_qsv", "overlay_vaapi", "overlay_vulkan", "overlay_cuda", "procamp_vaapi", "scale_cuda", "scale_opencl", "scale_qsv", "scale_vaapi", "scale_vulkan", "tonemap_cuda", "tonemap_opencl", "tonemap_vaapi", "vpp_qsv", "yadif_cuda", "zscale", "alphasrc"] [2024-04-08 19:14:57.019 +10:00] [INF] Available hwaccel types: ["cuda", "vaapi", "qsv", "drm", "opencl", "vulkan"] [2024-04-08 19:14:57.698 +10:00] [INF] VAAPI device "/dev/dri/renderD128" is AMD GPU [2024-04-08 19:14:57.698 +10:00] [INF] VAAPI device "/dev/dri/renderD128" supports Vulkan DRM interop [2024-04-08 19:14:57.698 +10:00] [INF] FFmpeg: "/usr/lib/jellyfin-ffmpeg/ffmpeg" [2024-04-08 19:14:57.699 +10:00] [INF] ServerId: "09e71f321e5b4ed3b0315b58d4386cd4" [2024-04-08 19:14:57.699 +10:00] [INF] Core startup complete [2024-04-08 19:14:57.699 +10:00] [INF] Startup complete 0:00:03.7564609 [2024-04-08 19:15:04.283 +10:00] [INF] "Clean up collections and playlists" Completed after 0 minute(s) and 4 seconds [2024-04-08 19:15:04.778 +10:00] [INF] "Update Plugins" Completed after 0 minute(s) and 5 seconds [2024-04-08 19:15:12.007 +10:00] [INF] Watching directory "/mnt/truenas/media/tvshows" [2024-04-08 19:15:12.068 +10:00] [INF] Watching directory "/mnt/truenas/media/Movies" [2024-04-08 19:15:56.754 +10:00] [INF] Stopping directory watching for path "/mnt/truenas/media/Movies" [2024-04-08 19:15:56.755 +10:00] [INF] Stopping directory watching for path "/mnt/truenas/media/tvshows" [2024-04-08 19:15:56.756 +10:00] [INF] Validating media library [2024-04-08 19:15:57.256 +10:00] [INF] "Clean Cache Directory" Completed after 0 minute(s) and 0 seconds [2024-04-08 19:22:22.058 +10:00] [INF] WS "10.0.0.23" request [2024-04-08 19:22:31.622 +10:00] [INF] WS "10.0.0.23" closed [2024-04-08 19:22:33.602 +10:00] [INF] WS "10.0.0.23" request [2024-04-08 19:24:58.184 +10:00] [INF] Sending ForceKeepAlive message to 1 inactive WebSockets. [2024-04-08 19:25:46.189 +10:00] [INF] Sending ForceKeepAlive message to 1 inactive WebSockets. [2024-04-08 19:26:34.188 +10:00] [INF] Sending ForceKeepAlive message to 1 inactive WebSockets. [2024-04-08 19:27:22.189 +10:00] [INF] Sending ForceKeepAlive message to 1 inactive WebSockets. [2024-04-08 19:28:10.189 +10:00] [INF] Sending ForceKeepAlive message to 1 inactive WebSockets. [2024-04-08 19:28:58.188 +10:00] [INF] Sending ForceKeepAlive message to 1 inactive WebSockets. [2024-04-08 19:28:58.861 +10:00] [WRN] WS "10.0.0.23" error receiving data: "The remote party closed the WebSocket connection without completing the close handshake." [2024-04-08 19:28:58.863 +10:00] [INF] WS "10.0.0.23" closed [2024-04-08 19:30:42.426 +10:00] [INF] WS "10.0.0.23" request [2024-04-08 19:30:52.150 +10:00] [INF] WS "10.0.0.23" closed [2024-04-08 19:30:53.516 +10:00] [INF] WS "10.0.0.23" request [2024-04-08 19:32:51.184 +10:00] [INF] WS "10.0.0.23" closed [2024-04-08 19:53:04.870 +10:00] [INF] WS "10.0.0.207" request [2024-04-08 20:01:41.045 +10:00] [INF] Sending ForceKeepAlive message to 1 inactive WebSockets. [2024-04-08 20:04:17.050 +10:00] [INF] Sending ForceKeepAlive message to 1 inactive WebSockets. [2024-04-08 20:04:29.049 +10:00] [INF] Lost 1 WebSockets. [2024-04-08 20:21:06.760 +10:00] [WRN] WS "10.0.0.207" error receiving data: "The remote party closed the WebSocket connection without completing the close handshake." [2024-04-08 20:21:06.762 +10:00] [INF] WS "10.0.0.207" closed [2024-04-08 20:37:23.451 +10:00] [INF] WS "10.0.0.207" request [2024-04-08 20:43:06.461 +10:00] [INF] WS "10.0.0.23" request [2024-04-08 20:44:31.946 +10:00] [INF] App needs to be restarted. [2024-04-08 20:44:57.345 +10:00] [INF] WS "10.0.0.23" closed [2024-04-08 20:44:58.786 +10:00] [INF] WS "10.0.0.23" request RE: Scan media library stuck at 0.0% and playback stops at random. - TheDreadPirate - 2024-04-08 Can you enable debug logging and replicate the problem? https://jellyfin.org/docs/general/administration/troubleshooting/#debug-logging Stop jellyfin, rename your current jellyfin log, enable debug logging, replicate the problem, post to our git hub. https://github.com/jellyfin/jellyfin/issues I don't see an issue that is an exact match for yours, but I'm aware of other library scan related bugs. RE: Scan media library stuck at 0.0% and playback stops at random. - Nihalvm - 2024-04-09 (2024-04-08, 01:20 PM)TheDreadPirate Wrote: Can you enable debug logging and replicate the problem? Thanks. The last library scan completed after a reboot and installing the new image. Will see if I can replicate the issue again. RE: Scan media library stuck at 0.0% and playback stops at random. - Nihalvm - 2024-04-25 (2024-04-08, 01:20 PM)TheDreadPirate Wrote: Can you enable debug logging and replicate the problem? @TheDreadPirate Not sure if you get a notification for a thread this old OR should I create a new thread. The scans are completing as per normal now, but the playback issue still remains. A couple of changes since the last message. 1. I have enabled debug logging. How do I know if its active or not? 2. I wasn't sure if it was my system that was the issue. I moved to a new system with i5 9500T processor and started using Intel QSV. However the random stops still remains. This was not a new install, but just a migration of a backup. 3. When the playback stops on TV, I cannot access the web UI either. I sometimes get a error page saying server cannot be accessed. However a ping/SSH to the server address works. WebUI starts to respond after a couple of mins and playback also resumes. Jellyfin log around the time when the playback stops and UI is inaccessible: Code: [2024-04-25 20:35:33.970 +10:00] [INF] Sending ForceKeepAlive message to 1 inactive WebSockets. RE: Scan media library stuck at 0.0% and playback stops at random. - Nihalvm - 2024-04-25 I see a lot of [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Progress". Not sure what this error means. but everytime the playback stops etc, I see this error. RE: Scan media library stuck at 0.0% and playback stops at random. - TheDreadPirate - 2024-04-25 I don't see any issues on the server side. What is the WIFI signal strength where your TV is located? Have you tried rebooting your router? RE: Scan media library stuck at 0.0% and playback stops at random. - Nihalvm - 2024-04-26 (2024-04-25, 08:11 PM)TheDreadPirate Wrote: I don't see any issues on the server side. What is the WIFI signal strength where your TV is located? Have you tried rebooting your router? This is not solved. I accidently marked your reply as a solution. Can you please mark it as not solved OR let me know how i can revert it back? WiFi signal is very good. I can use my tab with and browse other websites without any issues right next to the TV when i have these playback issues. As noted in my previous reply, there are two error messages that come up in the log everytime i have these issues: Code: [2024-04-26 21:10:30.921 +10:00] [WRN] Library folder "/var/lib/jellyfin/data/playlists" is inaccessible or empty, skipping AND Code: [2024-04-26 21:19:29.564 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Progress". When i try to access the webUI i get the below error message (see attached image ). asthough the server is not reachable. however ping and SSH works and checkings systemctl status jellyfin says that the service is running. |