Jellyfin crashes during playback with no evidence in logs

[hugefinfan]

Hi, all. 

I've been troubleshooting this for over a week now and I could definitely use some help.

I'm on version 10.9.11 of the docker container running on debain in a proxmox host. I'm playing back media and the playback crashes consistently; the video will play for 2 seconds, 30 seconds, minutes, or tens of minutes, but every video will crash multiple times. I need to restart the playback each time to complete an episode or movie.

A few things I've tried:

• clearing browsing data
  
• different computers
  
• different browsers (firefox and chrome)
  
• reinstalling jellyfin docker container (I'm using jellyfin/jellyfin:latest)
  
• giving the host more RAM and CPU (8 cores i5 10500 and 64 GB RAM)
  

A few notes:

• buffer from admin console always seems fine (doesn't appear to be a buffering issue)
  
• doesn't seem to matter if it's transcoding or doing direct playback
  
• this happens on every video file type
  
• video files play flawlessly directly from NAS using VLC
  
• playing from roku streaming device doesn't appear to have this issue for tv episodes
  
• playback also crashes from live TV streaming (using ersatztv) all browsers and roku, etc.
  
• when playback crashes, series or move title art is displayed for about 5-60 seconds and then the browser redirects back to the jellyfin home page
  
• when playback crashes, viewing progress is not retained
  

I'll add the logs at the points where the playback crashed. I really appreciate the help as everything was great until a week or so ago!!


ffmpeg log:

Code:

frame=18385 fps=1261 q=-1.0 size=N/A time=00:12:46.87 bitrate=N/A speed=52.6x   
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332214.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332215.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332216.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332217.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332218.mp4' for writing
frame=19025 fps=1262 q=-1.0 size=N/A time=00:13:13.77 bitrate=N/A speed=52.7x   
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332219.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332220.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332221.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332222.mp4' for writing
frame=19619 fps=1260 q=-1.0 size=N/A time=00:13:38.47 bitrate=N/A speed=52.5x   
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332223.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332224.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332225.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332226.mp4' for writing
frame=20177 fps=1255 q=-1.0 size=N/A time=00:14:01.88 bitrate=N/A speed=52.4x   
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332227.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332228.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332229.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332230.mp4' for writing
frame=20768 fps=1253 q=-1.0 size=N/A time=00:14:26.41 bitrate=N/A speed=52.3x   
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332231.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332232.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332233.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332234.mp4' for writing
frame=21388 fps=1252 q=-1.0 size=N/A time=00:14:52.27 bitrate=N/A speed=52.2x   
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332235.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332236.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332237.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332238.mp4' for writing
frame=22022 fps=1253 q=-1.0 size=N/A time=00:15:18.63 bitrate=N/A speed=52.3x   
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332239.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332240.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332241.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332242.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332243.mp4' for writing
frame=22684 fps=1255 q=-1.0 size=N/A time=00:15:46.45 bitrate=N/A speed=52.4x   
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332244.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332245.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332246.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332247.mp4' for writing
frame=23321 fps=1255 q=-1.0 size=N/A time=00:16:12.84 bitrate=N/A speed=52.4x   
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332248.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332249.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332250.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332251.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332252.mp4' for writing
frame=23976 fps=1257 q=-1.0 size=N/A time=00:16:40.11 bitrate=N/A speed=52.4x   
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332253.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332254.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332255.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332256.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332257.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332258.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332259.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332260.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332261.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332262.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332263.mp4' for writing
frame=25493 fps=1302 q=-1.0 size=N/A time=00:17:43.51 bitrate=N/A speed=54.3x   
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332264.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332265.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332266.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332267.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332268.mp4' for writing
[hls @ 0x5572dcf91b40] Opening '/config/transcodes/1adb555134477413d7bb3bc200a93332269.mp4' for writing
frame=26337 fps=1333 q=-1.0 Lsize=N/A time=00:18:18.56 bitrate=N/A speed=55.6x   
video:1223889kB audio:34329kB subtitle:0kB other streams:0kB global headers:0kB muxing overhead: unknown

system log:

Code:

[2024-10-24 19:51:56.774 -04:00] [INF] [92] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFgxMTsgVWJ1bnR1OyBMaW51eCB4ODZfNjQ7IHJ2OjEyNS4wKSBHZWNrby8yMDEwMDEwMSBGaXJlZm94LzEyNS4wfDE3MTQ4MzM5MzkwMzA1-a910f988efb64f979a4325741fed5d30-2127433beff623955976b4b37210b9b9"
[2024-10-24 19:52:02.130 -04:00] [INF] [92] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTA5LjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTE4LjB8MTY5NzMwMTI1NjM5Mw11-a910f988efb64f979a4325741fed5d30-3bc81a1d41a37e3b2695845110c6222b"
[2024-10-24 19:52:16.779 -04:00] [INF] [94] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFgxMTsgVWJ1bnR1OyBMaW51eCB4ODZfNjQ7IHJ2OjEyNS4wKSBHZWNrby8yMDEwMDEwMSBGaXJlZm94LzEyNS4wfDE3MTQ4MzM5MzkwMzA1-a910f988efb64f979a4325741fed5d30-2127433beff623955976b4b37210b9b9"
[2024-10-24 19:52:22.349 -04:00] [INF] [10] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTA5LjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTE4LjB8MTY5NzMwMTI1NjM5Mw11-a910f988efb64f979a4325741fed5d30-3bc81a1d41a37e3b2695845110c6222b"
[2024-10-24 19:52:37.780 -04:00] [INF] [16] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFgxMTsgVWJ1bnR1OyBMaW51eCB4ODZfNjQ7IHJ2OjEyNS4wKSBHZWNrby8yMDEwMDEwMSBGaXJlZm94LzEyNS4wfDE3MTQ4MzM5MzkwMzA1-a910f988efb64f979a4325741fed5d30-2127433beff623955976b4b37210b9b9"
[2024-10-24 19:52:42.708 -04:00] [INF] [94] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTA5LjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTE4LjB8MTY5NzMwMTI1NjM5Mw11-a910f988efb64f979a4325741fed5d30-3bc81a1d41a37e3b2695845110c6222b"
[2024-10-24 19:52:49.864 -04:00] [INF] [16] Emby.Server.Implementations.Session.SessionManager: Playback stopped reported by app "Jellyfin Web" "10.9.11" playing "Adios Cowboy". Stopped at "218106" ms
[2024-10-24 19:52:49.868 -04:00] [INF] [92] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Playback stop tracker found, processing stop : "TW96aWxsYS81LjAgKFgxMTsgVWJ1bnR1OyBMaW51eCB4ODZfNjQ7IHJ2OjEyNS4wKSBHZWNrby8yMDEwMDEwMSBGaXJlZm94LzEyNS4wfDE3MTQ4MzM5MzkwMzA1-a910f988efb64f979a4325741fed5d30-2127433beff623955976b4b37210b9b9"
[2024-10-24 19:52:49.868 -04:00] [INF] [92] Jellyfin.Plugin.PlaybackReporting.Data.PlaybackTracker: PlaybackTracker : Adding Stop Event : 10/24/2024 19:52:49
[2024-10-24 19:52:49.868 -04:00] [INF] [92] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Saving playback tracking activity in DB
[2024-10-24 19:53:02.905 -04:00] [INF] [16] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTA5LjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTE4LjB8MTY5NzMwMTI1NjM5Mw11-a910f988efb64f979a4325741fed5d30-3bc81a1d41a37e3b2695845110c6222b"
[2024-10-24 19:53:22.303 -04:00] [INF] [36] Jellyfin.Server.Implementations.Users.UserManager: Authentication request for "rob" has succeeded.
[2024-10-24 19:53:22.303 -04:00] [INF] [36] Emby.Server.Implementations.Session.SessionManager: Current/Max sessions for user "rob": 4/0
[2024-10-24 19:53:22.303 -04:00] [INF] [36] Emby.Server.Implementations.Session.SessionManager: Logging out access token "e1652a6191624a7b95e4c2f3de572973"
[2024-10-24 19:53:22.307 -04:00] [INF] [36] Emby.Server.Implementations.Session.SessionManager: Creating new access token for user a910f988-efb6-4f97-9a43-25741fed5d30
[2024-10-24 19:53:23.102 -04:00] [INF] [92] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTA5LjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTE4LjB8MTY5NzMwMTI1NjM5Mw11-a910f988efb64f979a4325741fed5d30-3bc81a1d41a37e3b2695845110c6222b"
[2024-10-24 19:53:43.114 -04:00] [INF] [10] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTA5LjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTE4LjB8MTY5NzMwMTI1NjM5Mw11-a910f988efb64f979a4325741fed5d30-3bc81a1d41a37e3b2695845110c6222b"
[2024-10-24 19:54:03.135 -04:00] [INF] [10] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTA5LjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTE4LjB8MTY5NzMwMTI1NjM5Mw11-a910f988efb64f979a4325741fed5d30-3bc81a1d41a37e3b2695845110c6222b"
[2024-10-24 19:54:23.171 -04:00] [INF] [10] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTA5LjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTE4LjB8MTY5NzMwMTI1NjM5Mw11-a910f988efb64f979a4325741fed5d30-3bc81a1d41a37e3b2695845110c6222b"
[2024-10-24 19:54:44.160 -04:00] [INF] [17] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTA5LjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTE4LjB8MTY5NzMwMTI1NjM5Mw11-a910f988efb64f979a4325741fed5d30-3bc81a1d41a37e3b2695845110c6222b"
[2024-10-24 19:55:04.165 -04:00] [INF] [17] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTA5LjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTE4LjB8MTY5NzMwMTI1NjM5Mw11-a910f988efb64f979a4325741fed5d30-3bc81a1d41a37e3b2695845110c6222b"
[2024-10-24 19:55:24.210 -04:00] [INF] [24] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTA5LjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTE4LjB8MTY5NzMwMTI1NjM5Mw11-a910f988efb64f979a4325741fed5d30-3bc81a1d41a37e3b2695845110c6222b"
[2024-10-24 19:55:44.233 -04:00] [INF] [94] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTA5LjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTE4LjB8MTY5NzMwMTI1NjM5Mw11-a910f988efb64f979a4325741fed5d30-3bc81a1d41a37e3b2695845110c6222b"
[2024-10-24 19:56:04.242 -04:00] [INF] [36] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTA5LjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTE4LjB8MTY5NzMwMTI1NjM5Mw11-a910f988efb64f979a4325741fed5d30-3bc81a1d41a37e3b2695845110c6222b"
[2024-10-24 19:56:23.099 -04:00] [INF] [94] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "162.158.10.187" request
[2024-10-24 19:56:23.456 -04:00] [ERR] [24] TVHeadEnd.HTSConnectionHandler: [TVHclient] HTSConnectionHandler.ensureConnection: username must be configured
[2024-10-24 19:56:23.456 -04:00] [ERR] [24] Jellyfin.LiveTv.LiveTvManager: Error getting recordings
System.InvalidOperationException: [TVHclient] HTSConnectionHandler.ensureConnection: username must be configured
  at TVHeadEnd.HTSConnectionHandler.init()
  at TVHeadEnd.HTSConnectionHandler.ensureConnection()
  at TVHeadEnd.HTSConnectionHandler.WaitForInitialLoad(CancellationToken cancellationToken)
  at System.Threading.Tasks.Task`1.InnerInvoke()
  at System.Threading.ExecutionContext.RunFromThreadPoolDispatchLoop(Thread threadPoolThread, ExecutionContext executionContext, ContextCallback callback, Object state)
--- End of stack trace from previous location ---
  at System.Threading.ExecutionContext.RunFromThreadPoolDispatchLoop(Thread threadPoolThread, ExecutionContext executionContext, ContextCallback callback, Object state)
  at System.Threading.Tasks.Task.ExecuteWithThreadLocal(Task& currentTaskSlot, Thread threadPoolThread)
--- End of stack trace from previous location ---
  at TVHeadEnd.LiveTvService.GetTimersAsync(CancellationToken cancellationToken)
  at Jellyfin.LiveTv.LiveTvManager.<>c__DisplayClass42_0.<<GetTimersInternal>b__0>d.MoveNext()
[2024-10-24 19:56:24.156 -04:00] [ERR] [100] TVHeadEnd.HTSConnectionHandler: [TVHclient] HTSConnectionHandler.ensureConnection: username must be configured
[2024-10-24 19:56:24.156 -04:00] [ERR] [100] Jellyfin.LiveTv.LiveTvManager: Error getting recordings
System.InvalidOperationException: [TVHclient] HTSConnectionHandler.ensureConnection: username must be configured
  at TVHeadEnd.HTSConnectionHandler.init()
  at TVHeadEnd.HTSConnectionHandler.ensureConnection()
  at TVHeadEnd.HTSConnectionHandler.WaitForInitialLoad(CancellationToken cancellationToken)
  at System.Threading.Tasks.Task`1.InnerInvoke()
  at System.Threading.ExecutionContext.RunFromThreadPoolDispatchLoop(Thread threadPoolThread, ExecutionContext executionContext, ContextCallback callback, Object state)
--- End of stack trace from previous location ---
  at System.Threading.ExecutionContext.RunFromThreadPoolDispatchLoop(Thread threadPoolThread, ExecutionContext executionContext, ContextCallback callback, Object state)
  at System.Threading.Tasks.Task.ExecuteWithThreadLocal(Task& currentTaskSlot, Thread threadPoolThread)
--- End of stack trace from previous location ---
  at TVHeadEnd.LiveTvService.GetTimersAsync(CancellationToken cancellationToken)
  at Jellyfin.LiveTv.LiveTvManager.<>c__DisplayClass42_0.<<GetTimersInternal>b__0>d.MoveNext()
[2024-10-24 19:56:27.410 -04:00] [ERR] [53] TVHeadEnd.HTSConnectionHandler: [TVHclient] HTSConnectionHandler.ensureConnection: username must be configured
[2024-10-24 19:56:27.412 -04:00] [ERR] [53] Jellyfin.Api.Middleware.ExceptionMiddleware: Error processing request. URL "GET" "/LiveTv/Recordings".
System.InvalidOperationException: [TVHclient] HTSConnectionHandler.ensureConnection: username must be configured
  at TVHeadEnd.HTSConnectionHandler.init()
  at TVHeadEnd.HTSConnectionHandler.ensureConnection()
  at TVHeadEnd.HTSConnectionHandler.WaitForInitialLoad(CancellationToken cancellationToken)
  at System.Threading.Tasks.Task`1.InnerInvoke()
  at System.Threading.ExecutionContext.RunFromThreadPoolDispatchLoop(Thread threadPoolThread, ExecutionContext executionContext, ContextCallback callback, Object state)
--- End of stack trace from previous location ---
  at System.Threading.ExecutionContext.RunFromThreadPoolDispatchLoop(Thread threadPoolThread, ExecutionContext executionContext, ContextCallback callback, Object state)
  at System.Threading.Tasks.Task.ExecuteWithThreadLocal(Task& currentTaskSlot, Thread threadPoolThread)
--- End of stack trace from previous location ---
  at TVHeadEnd.RecordingsChannel.GetAllRecordingsAsync(CancellationToken cancellationToken)
  at TVHeadEnd.RecordingsChannel.GetRecordingGroups(InternalChannelItemQuery query, CancellationToken cancellationToken)
  at Jellyfin.LiveTv.Channels.ChannelManager.GetChannelItems(IChannel channel, User user, String externalFolderId, Nullable`1 sortField, Boolean sortDescending, CancellationToken cancellationToken)
  at Jellyfin.LiveTv.Channels.ChannelManager.GetChannelItemsInternal(InternalItemsQuery query, IProgress`1 progress, CancellationToken cancellationToken)
  at Jellyfin.LiveTv.Channels.ChannelManager.RefreshLatestChannelItems(IChannel channel, CancellationToken cancellationToken)
  at Jellyfin.LiveTv.Channels.ChannelManager.GetChannelsInternalAsync(ChannelQuery query)
  at Jellyfin.LiveTv.LiveTvManager.GetRecordingFoldersAsync(User user, Boolean refreshChannels)
  at Jellyfin.LiveTv.LiveTvManager.GetEmbyRecordingsAsync(RecordingQuery query, DtoOptions dtoOptions, User user)
  at Jellyfin.LiveTv.LiveTvManager.GetRecordingsAsync(RecordingQuery query, DtoOptions options)
  at Jellyfin.Api.Controllers.LiveTvController.GetRecordings(String channelId, Nullable`1 userId, Nullable`1 startIndex, Nullable`1 limit, Nullable`1 status, Nullable`1 isInProgress, String seriesTimerId, Nullable`1 enableImages, Nullable`1 imageTypeLimit, ImageType[] enableImageTypes, ItemFields[] fields, Nullable`1 enableUserData, Nullable`1 isMovie, Nullable`1 isSeries, Nullable`1 isKids, Nullable`1 isSports, Nullable`1 isNews, Nullable`1 isLibraryItem, Boolean enableTotalRecordCount)
  at lambda_method1651(Closure, Object)
  at Microsoft.AspNetCore.Mvc.Infrastructure.ActionMethodExecutor.AwaitableObjectResultExecutor.Execute(ActionContext actionContext, IActionResultTypeMapper mapper, ObjectMethodExecutor executor, Object controller, Object[] arguments)
  at Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker.<InvokeActionMethodAsync>g__Awaited|12_0(ControllerActionInvoker invoker, ValueTask`1 actionResultValueTask)
  at Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker.<InvokeNextActionFilterAsync>g__Awaited|10_0(ControllerActionInvoker invoker, Task lastTask, State next, Scope scope, Object state, Boolean isCompleted)
  at Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker.Rethrow(ActionExecutedContextSealed context)
  at Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker.Next(State& next, Scope& scope, Object& state, Boolean& isCompleted)
  at Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker.<InvokeInnerFilterAsync>g__Awaited|13_0(ControllerActionInvoker invoker, Task lastTask, State next, Scope scope, Object state, Boolean isCompleted)
  at Microsoft.AspNetCore.Mvc.Infrastructure.ResourceInvoker.<InvokeNextResourceFilter>g__Awaited|25_0(ResourceInvoker invoker, Task lastTask, State next, Scope scope, Object state, Boolean isCompleted)
  at Microsoft.AspNetCore.Mvc.Infrastructure.ResourceInvoker.Rethrow(ResourceExecutedContextSealed context)
  at Microsoft.AspNetCore.Mvc.Infrastructure.ResourceInvoker.Next(State& next, Scope& scope, Object& state, Boolean& isCompleted)
  at Microsoft.AspNetCore.Mvc.Infrastructure.ResourceInvoker.<InvokeFilterPipelineAsync>g__Awaited|20_0(ResourceInvoker invoker, Task lastTask, State next, Scope scope, Object state, Boolean isCompleted)
  at Microsoft.AspNetCore.Mvc.Infrastructure.ResourceInvoker.<InvokeAsync>g__Awaited|17_0(ResourceInvoker invoker, Task task, IDisposable scope)
  at Microsoft.AspNetCore.Mvc.Infrastructure.ResourceInvoker.<InvokeAsync>g__Awaited|17_0(ResourceInvoker invoker, Task task, IDisposable scope)
  at Jellyfin.Api.Middleware.ServerStartupMessageMiddleware.Invoke(HttpContext httpContext, IServerApplicationHost serverApplicationHost, ILocalizationManager localizationManager)
  at Jellyfin.Api.Middleware.WebSocketHandlerMiddleware.Invoke(HttpContext httpContext, IWebSocketManager webSocketManager)
  at Jellyfin.Api.Middleware.IPBasedAccessValidationMiddleware.Invoke(HttpContext httpContext, INetworkManager networkManager)
  at Jellyfin.Api.Middleware.LanFilteringMiddleware.Invoke(HttpContext httpContext, INetworkManager networkManager, IServerConfigurationManager serverConfigurationManager)
  at Microsoft.AspNetCore.Authorization.AuthorizationMiddleware.Invoke(HttpContext context)
  at Jellyfin.Api.Middleware.QueryStringDecodingMiddleware.Invoke(HttpContext httpContext)
  at Swashbuckle.AspNetCore.ReDoc.ReDocMiddleware.Invoke(HttpContext httpContext)
  at Swashbuckle.AspNetCore.SwaggerUI.SwaggerUIMiddleware.Invoke(HttpContext httpContext)
  at Swashbuckle.AspNetCore.Swagger.SwaggerMiddleware.Invoke(HttpContext httpContext, ISwaggerProvider swaggerProvider)
  at Microsoft.AspNetCore.Authentication.AuthenticationMiddleware.Invoke(HttpContext context)
  at Jellyfin.Api.Middleware.RobotsRedirectionMiddleware.Invoke(HttpContext httpContext)
  at Jellyfin.Api.Middleware.LegacyEmbyRouteRewriteMiddleware.Invoke(HttpContext httpContext)
  at Microsoft.AspNetCore.ResponseCompression.ResponseCompressionMiddleware.InvokeCore(HttpContext context)
  at Jellyfin.Api.Middleware.ResponseTimeMiddleware.Invoke(HttpContext context, IServerConfigurationManager serverConfigurationManager)
  at Jellyfin.Api.Middleware.ExceptionMiddleware.Invoke(HttpContext context)
[2024-10-24 19:56:32.231 -04:00] [INF] [75] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTA5LjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTE4LjB8MTY5NzMwMTI1NjM5Mw11-a910f988efb64f979a4325741fed5d30-3bc81a1d41a37e3b2695845110c6222b"

[theguymadmax]

There's several issues related to the TVheadend plugin in the log. Is LiveTv working for you? Try disabling the plugin and see if that helps.

[hugefinfan]

There's several issues related to the TVheadend plugin in the log. Is LiveTv working for you? Try disabling the plugin and see if that helps.

Thank you! I'm still testing this but so far so good. This seems to have sped up the interface and resolved a few other odd issues as well but so far no crashes!

I can't remember why I installed TVHeadend in the first place so I just removed the plugin entirely.

[hugefinfan]

Well spoke too soon. The crashing is certainly still there. I will post more logs when I can get a clean log output but from looking through them quickly again no errors jumped out at me.

[hugefinfan]

Well spoke too soon. The crashing is certainly still there. I will post more logs when I can get a clean log output but from looking through them quickly again no errors jumped out at me.

[hugefinfan]

Latest logs from a crash today:

main log:

Code:

[2024-10-28 13:51:48.250 -04:00] [INF] [84] Emby.Server.Implementations.IO.LibraryMonitor: Watching directory "/mnt/media/music"
[2024-10-28 13:52:08.202 -04:00] [INF] [41] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 13:52:29.029 -04:00] [INF] [64] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 13:52:49.050 -04:00] [INF] [64] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 13:53:09.053 -04:00] [INF] [36] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 13:53:29.081 -04:00] [INF] [44] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 13:53:49.110 -04:00] [INF] [36] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 13:54:09.114 -04:00] [INF] [36] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 13:54:25.175 -04:00] [INF] [98] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "xxx.xxx.xxx.xxx" request
[2024-10-28 13:54:26.388 -04:00] [INF] [36] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "xxx.xxx.xxx.xxx" closed
[2024-10-28 13:54:27.002 -04:00] [INF] [98] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "yyy.yyy.yyy.yyy" request
[2024-10-28 13:54:37.077 -04:00] [INF] [98] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"

ffmpeg log:

Code:

[hls @ 0x55ea8927a540] Opening '/config/transcodes/008460535d23f6092c8e68eb8d32fd62653.mp4' for writing
frame=47114 fps= 88 q=19.0 size=N/A time=00:32:45.20 bitrate=N/A speed=3.65x   
frame=47158 fps= 88 q=19.0 size=N/A time=00:32:47.10 bitrate=N/A speed=3.65x   
[hls @ 0x55ea8927a540] Opening '/config/transcodes/008460535d23f6092c8e68eb8d32fd62654.mp4' for writing
frame=47202 fps= 88 q=18.0 size=N/A time=00:32:49.08 bitrate=N/A speed=3.65x   
[hls @ 0x55ea8927a540] Opening '/config/transcodes/008460535d23f6092c8e68eb8d32fd62655.mp4' for writing
frame=47246 fps= 88 q=18.0 size=N/A time=00:32:50.68 bitrate=N/A speed=3.65x   
frame=47291 fps= 88 q=18.0 size=N/A time=00:32:52.75 bitrate=N/A speed=3.65x   
[hls @ 0x55ea8927a540] Opening '/config/transcodes/008460535d23f6092c8e68eb8d32fd62656.mp4' for writing
frame=47335 fps= 88 q=22.0 size=N/A time=00:32:54.54 bitrate=N/A speed=3.65x   
[hls @ 0x55ea8927a540] Opening '/config/transcodes/008460535d23f6092c8e68eb8d32fd62657.mp4' for writing
frame=47379 fps= 88 q=19.0 size=N/A time=00:32:56.32 bitrate=N/A speed=3.65x   
frame=47423 fps= 88 q=20.0 size=N/A time=00:32:58.04 bitrate=N/A speed=3.65x   
[hls @ 0x55ea8927a540] Opening '/config/transcodes/008460535d23f6092c8e68eb8d32fd62658.mp4' for writing
frame=47466 fps= 88 q=16.0 size=N/A time=00:32:59.86 bitrate=N/A speed=3.65x   
frame=47511 fps= 88 q=20.0 size=N/A time=00:33:01.95 bitrate=N/A speed=3.65x   
[hls @ 0x55ea8927a540] Opening '/config/transcodes/008460535d23f6092c8e68eb8d32fd62659.mp4' for writing
frame=47555 fps= 88 q=19.0 size=N/A time=00:33:03.74 bitrate=N/A speed=3.65x   
[hls @ 0x55ea8927a540] Opening '/config/transcodes/008460535d23f6092c8e68eb8d32fd62660.mp4' for writing
frame=47599 fps= 88 q=18.0 size=N/A time=00:33:05.47 bitrate=N/A speed=3.65x   
frame=47643 fps= 88 q=19.0 size=N/A time=00:33:07.22 bitrate=N/A speed=3.65x   
[hls @ 0x55ea8927a540] Opening '/config/transcodes/008460535d23f6092c8e68eb8d32fd62661.mp4' for writing
frame=47687 fps= 88 q=19.0 size=N/A time=00:33:09.20 bitrate=N/A speed=3.65x   
frame=47730 fps= 88 q=19.0 size=N/A time=00:33:10.84 bitrate=N/A speed=3.65x   
[hls @ 0x55ea8927a540] Opening '/config/transcodes/008460535d23f6092c8e68eb8d32fd62662.mp4' for writing
frame=47774 fps= 88 q=19.0 size=N/A time=00:33:12.83 bitrate=N/A speed=3.65x   
[hls @ 0x55ea8927a540] Opening '/config/transcodes/008460535d23f6092c8e68eb8d32fd62663.mp4' for writing
frame=47818 fps= 88 q=20.0 size=N/A time=00:33:14.75 bitrate=N/A speed=3.65x   
frame=47863 fps= 88 q=20.0 size=N/A time=00:33:16.62 bitrate=N/A speed=3.65x   
[hls @ 0x55ea8927a540] Opening '/config/transcodes/008460535d23f6092c8e68eb8d32fd62664.mp4' for writing
frame=47907 fps= 88 q=21.0 size=N/A time=00:33:18.54 bitrate=N/A speed=3.65x   
frame=47951 fps= 88 q=19.0 size=N/A time=00:33:20.25 bitrate=N/A speed=3.65x   
[hls @ 0x55ea8927a540] Opening '/config/transcodes/008460535d23f6092c8e68eb8d32fd62665.mp4' for writing
frame=47995 fps= 88 q=19.0 size=N/A time=00:33:21.92 bitrate=N/A speed=3.65x   
[hls @ 0x55ea8927a540] Opening '/config/transcodes/008460535d23f6092c8e68eb8d32fd62666.mp4' for writing
frame=48039 fps= 88 q=19.0 size=N/A time=00:33:23.84 bitrate=N/A speed=3.65x   
frame=48084 fps= 88 q=19.0 size=N/A time=00:33:25.76 bitrate=N/A speed=3.65x   
[hls @ 0x55ea8927a540] Opening '/config/transcodes/008460535d23f6092c8e68eb8d32fd62667.mp4' for writing
frame=48127 fps= 88 q=18.0 size=N/A time=00:33:27.50 bitrate=N/A speed=3.65x   
[hls @ 0x55ea8927a540] Opening '/config/transcodes/008460535d23f6092c8e68eb8d32fd62668.mp4' for writing
frame=48171 fps= 88 q=19.0 size=N/A time=00:33:29.23 bitrate=N/A speed=3.65x   
frame=48215 fps= 88 q=19.0 size=N/A time=00:33:31.13 bitrate=N/A speed=3.65x   
[hls @ 0x55ea8927a540] Opening '/config/transcodes/008460535d23f6092c8e68eb8d32fd62669.mp4' for writing
frame=48259 fps= 88 q=18.0 size=N/A time=00:33:33.07 bitrate=N/A speed=3.65x   
frame=48303 fps= 88 q=19.0 size=N/A time=00:33:34.86 bitrate=N/A speed=3.65x   
[hls @ 0x55ea8927a540] Opening '/config/transcodes/008460535d23f6092c8e68eb8d32fd62670.mp4' for writing

[hugefinfan]

Upgraded to 10.10.0 and the problem persists. Here are the logs from after the upgrade:

main logs:

Code:

[2024-10-28 14:00:21.709 -04:00] [INF] [13] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 14:00:41.742 -04:00] [INF] [34] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 14:01:01.761 -04:00] [INF] [35] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 14:01:21.777 -04:00] [INF] [34] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 14:01:41.794 -04:00] [INF] [21] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 14:02:01.809 -04:00] [INF] [11] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 14:02:21.813 -04:00] [INF] [35] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 14:02:41.814 -04:00] [INF] [34] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 14:03:01.833 -04:00] [INF] [38] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 14:03:21.846 -04:00] [INF] [39] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 14:03:41.855 -04:00] [INF] [11] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 14:03:55.251 -04:00] [INF] [11] Emby.Server.Implementations.Session.SessionWebSocketListener: Sending ForceKeepAlive message to 1 inactive WebSockets.
[2024-10-28 14:04:02.054 -04:00] [INF] [11] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 14:04:06.182 -04:00] [INF] [38] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "aaa.aaa.aaa.aaa" request
[2024-10-28 14:04:08.484 -04:00] [INF] [13] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "aaa.aaa.aaa.aaa" closed
[2024-10-28 14:04:22.091 -04:00] [INF] [37] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 14:04:42.094 -04:00] [INF] [13] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 14:05:02.099 -04:00] [INF] [11] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 14:05:22.127 -04:00] [INF] [38] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 14:05:42.136 -04:00] [INF] [37] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 14:06:02.154 -04:00] [INF] [27] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 14:06:26.210 -04:00] [INF] [37] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTMxLjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTMxLjB8MTcyOTgxNDY4NDI1MQ11-a910f988efb64f979a4325741fed5d30-db274103c86922023fd6c9844e29f5f2"
[2024-10-28 14:06:30.217 -04:00] [INF] [29] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "aaa.aaa.aaa.aaa" closed
[2024-10-28 14:06:31.177 -04:00] [INF] [38] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "bbb.bbb.bbb.bbb" request

and ffmpeg:

Code:

[hls @ 0x55e08e8b1b00] Opening '/config/transcodes/7509b468f8610181a41aa6628acf141d498.mp4' for writing
frame=35954 fps= 83 q=16.0 size=N/A time=00:24:58.49 bitrate=N/A speed=3.47x   
frame=35998 fps= 83 q=15.0 size=N/A time=00:25:00.33 bitrate=N/A speed=3.47x   
[hls @ 0x55e08e8b1b00] Opening '/config/transcodes/7509b468f8610181a41aa6628acf141d499.mp4' for writing
frame=36041 fps= 83 q=18.0 size=N/A time=00:25:02.12 bitrate=N/A speed=3.47x   
[hls @ 0x55e08e8b1b00] Opening '/config/transcodes/7509b468f8610181a41aa6628acf141d500.mp4' for writing
frame=36086 fps= 83 q=17.0 size=N/A time=00:25:04.00 bitrate=N/A speed=3.47x   
frame=36131 fps= 83 q=22.0 size=N/A time=00:25:05.87 bitrate=N/A speed=3.47x   
[hls @ 0x55e08e8b1b00] Opening '/config/transcodes/7509b468f8610181a41aa6628acf141d501.mp4' for writing
frame=36174 fps= 83 q=14.0 size=N/A time=00:25:07.67 bitrate=N/A speed=3.47x   
[hls @ 0x55e08e8b1b00] Opening '/config/transcodes/7509b468f8610181a41aa6628acf141d502.mp4' for writing
frame=36217 fps= 83 q=14.0 size=N/A time=00:25:09.50 bitrate=N/A speed=3.47x   
frame=36261 fps= 83 q=19.0 size=N/A time=00:25:11.30 bitrate=N/A speed=3.47x   
[hls @ 0x55e08e8b1b00] Opening '/config/transcodes/7509b468f8610181a41aa6628acf141d503.mp4' for writing
frame=36305 fps= 83 q=18.0 size=N/A time=00:25:13.13 bitrate=N/A speed=3.47x   
frame=36349 fps= 83 q=17.0 size=N/A time=00:25:14.97 bitrate=N/A speed=3.47x   
[hls @ 0x55e08e8b1b00] Opening '/config/transcodes/7509b468f8610181a41aa6628acf141d504.mp4' for writing
frame=36393 fps= 83 q=22.0 size=N/A time=00:25:16.80 bitrate=N/A speed=3.47x   
[hls @ 0x55e08e8b1b00] Opening '/config/transcodes/7509b468f8610181a41aa6628acf141d505.mp4' for writing
frame=36436 fps= 83 q=19.0 size=N/A time=00:25:18.60 bitrate=N/A speed=3.47x   
frame=36480 fps= 83 q=18.0 size=N/A time=00:25:20.43 bitrate=N/A speed=3.47x   
[hls @ 0x55e08e8b1b00] Opening '/config/transcodes/7509b468f8610181a41aa6628acf141d506.mp4' for writing
frame=36522 fps= 83 q=14.0 size=N/A time=00:25:22.18 bitrate=N/A speed=3.47x   
frame=36566 fps= 83 q=15.0 size=N/A time=00:25:24.02 bitrate=N/A speed=3.47x   
[hls @ 0x55e08e8b1b00] Opening '/config/transcodes/7509b468f8610181a41aa6628acf141d507.mp4' for writing
frame=36609 fps= 83 q=17.0 size=N/A time=00:25:25.81 bitrate=N/A speed=3.47x   
[hls @ 0x55e08e8b1b00] Opening '/config/transcodes/7509b468f8610181a41aa6628acf141d508.mp4' for writing
frame=36651 fps= 83 q=17.0 size=N/A time=00:25:27.56 bitrate=N/A speed=3.47x   
frame=36693 fps= 83 q=18.0 size=N/A time=00:25:29.31 bitrate=N/A speed=3.47x   
[hls @ 0x55e08e8b1b00] Opening '/config/transcodes/7509b468f8610181a41aa6628acf141d509.mp4' for writing
frame=36737 fps= 83 q=16.0 size=N/A time=00:25:31.15 bitrate=N/A speed=3.47x   
frame=36779 fps= 83 q=26.0 size=N/A time=00:25:32.90 bitrate=N/A speed=3.47x   
[hls @ 0x55e08e8b1b00] Opening '/config/transcodes/7509b468f8610181a41aa6628acf141d510.mp4' for writing
frame=36822 fps= 83 q=22.0 size=N/A time=00:25:34.69 bitrate=N/A speed=3.47x   
[hls @ 0x55e08e8b1b00] Opening '/config/transcodes/7509b468f8610181a41aa6628acf141d511.mp4' for writing
frame=36865 fps= 83 q=9.0 size=N/A time=00:25:36.49 bitrate=N/A speed=3.47x   
frame=36907 fps= 83 q=16.0 size=N/A time=00:25:38.24 bitrate=N/A speed=3.47x   
[hls @ 0x55e08e8b1b00] Opening '/config/transcodes/7509b468f8610181a41aa6628acf141d512.mp4' for writing
frame=36950 fps= 83 q=18.0 size=N/A time=00:25:40.08 bitrate=N/A speed=3.47x   
frame=36995 fps= 83 q=18.0 size=N/A time=00:25:41.91 bitrate=N/A speed=3.47x   
[hls @ 0x55e08e8b1b00] Opening '/config/transcodes/7509b468f8610181a41aa6628acf141d513.mp4' for writing
frame=37037 fps= 83 q=18.0 size=N/A time=00:25:43.66 bitrate=N/A speed=3.47x   
frame=37080 fps= 83 q=18.0 size=N/A time=00:25:45.46 bitrate=N/A speed=3.47x   
[hls @ 0x55e08e8b1b00] Opening '/config/transcodes/7509b468f8610181a41aa6628acf141d514.mp4' for writing

[hugefinfan]

Interestingly this crashed twice again and this message was in the FFMPEG logs both times:

Code:

[q] command received. Exiting.

[hls @ 0x5613acc23d40] Opening '/config/transcodes/559a92871c73f7fc41fbc60eeb20218235.mp4' for writing
[out#0/hls @ 0x5613acc24140] video:207407KiB audio:6733KiB subtitle:0KiB other streams:0KiB global headers:0KiB muxing overhead: unknown
size=N/A time=00:02:58.81 bitrate=N/A speed=59.3x   

[TheDreadPirate]

Those logs are normal and happen when ffmpeg is commanded to stop gracefully.

[hugefinfan]

Those logs are normal and happen when ffmpeg is commanded to stop gracefully.

Thank you for taking a look! This is what happens when Jellyfin abruptly stops playing in the middle of an episode. I find it strange that FFMPEG logs are telling me that something is commanding it to stop and the global logs are normal. This crashing might be due to something telling the transcoding to halt, but I am unsure why or how.

Is there a deeper log level I can look at or something I am missing here?

Also interesting is that sometimes that [q] command error is sometimes present and sometimes not in the FFMPEG logs when the playback crashes.