[2024-04-07 18:48:01.486 +10:00] [INF] Jellyfin version: "10.9.0" [2024-04-07 18:48:01.501 +10:00] [INF] Environment Variables: ["[JELLYFIN_WEB_OPT, --webdir=/usr/share/jellyfin/web]", "[JELLYFIN_LOG_DIR, /var/log/jellyfin]", "[JELLYFIN_ADDITIONAL_OPTS, ]", "[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_FFMPEG_OPT, --ffmpeg=/usr/lib/jellyfin-ffmpeg/ffmpeg]", "[JELLYFIN_USER, jellyfin]", "[JELLYFIN_CACHE_DIR, /var/cache/jellyfin]", "[JELLYFIN_CONFIG_DIR, /etc/jellyfin]", "[JELLYFIN_DATA_DIR, /var/lib/jellyfin]"] [2024-04-07 18:48:01.502 +10:00] [INF] Arguments: ["/usr/lib/jellyfin/bin/jellyfin.dll", "--webdir=/usr/share/jellyfin/web", "--ffmpeg=/usr/lib/jellyfin-ffmpeg/ffmpeg"] [2024-04-07 18:48:01.502 +10:00] [INF] Operating system: "Debian GNU/Linux 12 (bookworm)" [2024-04-07 18:48:01.502 +10:00] [INF] Architecture: X64 [2024-04-07 18:48:01.503 +10:00] [INF] 64-Bit Process: True [2024-04-07 18:48:01.503 +10:00] [INF] User Interactive: True [2024-04-07 18:48:01.503 +10:00] [INF] Processor count: 2 [2024-04-07 18:48:01.503 +10:00] [INF] Program data path: "/var/lib/jellyfin" [2024-04-07 18:48:01.503 +10:00] [INF] Log directory path: "/var/log/jellyfin" [2024-04-07 18:48:01.503 +10:00] [INF] Config directory path: "/etc/jellyfin" [2024-04-07 18:48:01.504 +10:00] [INF] Cache path: "/var/cache/jellyfin" [2024-04-07 18:48:01.504 +10:00] [INF] Web resources path: "/usr/share/jellyfin/web" [2024-04-07 18:48:01.504 +10:00] [INF] Application directory: "/usr/lib/jellyfin/bin/" [2024-04-07 18:48:01.583 +10:00] [INF] Setting cache path: "/mnt/truenas/jellyfin/cache" [2024-04-07 18:48:01.641 +10:00] [INF] Loading assemblies [2024-04-07 18:48:01.675 +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-07 18:48:01.677 +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-07 18:48:01.678 +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-07 18:48:01.686 +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-07 18:48:01.688 +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-07 18:48:01.690 +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-07 18:48:01.729 +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-07 18:48:01.729 +10:00] [INF] Defined LAN exclusions: [] [2024-04-07 18:48:01.729 +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-07 18:48:01.730 +10:00] [INF] Using bind addresses: ["127.0.0.1", "10.0.0.108"] [2024-04-07 18:48:01.730 +10:00] [INF] Remote IP filter is "Allowlist" [2024-04-07 18:48:01.730 +10:00] [INF] Filter list: [] [2024-04-07 18:48:03.249 +10:00] [WRN] !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! [2024-04-07 18:48:03.249 +10:00] [WRN] !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! [2024-04-07 18:48:03.249 +10:00] [WRN] PLEASE VISIT THE JELLYSCRUB PLUGIN CONFIG PAGE TO CONVERT TRICKPLAY FILES [2024-04-07 18:48:03.249 +10:00] [WRN] !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! [2024-04-07 18:48:03.249 +10:00] [WRN] !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! [2024-04-07 18:48:03.259 +10:00] [INF] Attempting to inject trickplay script code in "/usr/share/jellyfin/web/index.html" [2024-04-07 18:48:03.260 +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) at Microsoft.Win32.SafeHandles.SafeFileHandle.Open(String fullPath, FileMode mode, FileAccess access, FileShare share, FileOptions options, Int64 preallocationSize, UnixFileMode openPermissions, Int64& fileLength, UnixFileMode& filePermissions, Boolean failForSymlink, Boolean& wasSymlink, Func`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-07 18:48:03.261 +10:00] [INF] Loaded plugin: "Jellyscrub" "2.0.0.0" [2024-04-07 18:48:03.261 +10:00] [INF] Loaded plugin: "Open Subtitles" "19.0.0.0" [2024-04-07 18:48:03.262 +10:00] [INF] Loaded plugin: "Reports" "15.0.0.0" [2024-04-07 18:48:03.262 +10:00] [INF] Loaded plugin: "TMDb" "10.9.0.0" [2024-04-07 18:48:03.262 +10:00] [INF] Loaded plugin: "Studio Images" "10.9.0.0" [2024-04-07 18:48:03.263 +10:00] [INF] Loaded plugin: "OMDb" "10.9.0.0" [2024-04-07 18:48:03.276 +10:00] [INF] Loaded plugin: "MusicBrainz" "10.9.0.0" [2024-04-07 18:48:03.276 +10:00] [INF] Loaded plugin: "AudioDB" "10.9.0.0" [2024-04-07 18:48:03.317 +10:00] [INF] Kestrel is listening on "127.0.0.1" [2024-04-07 18:48:03.318 +10:00] [INF] Kestrel is listening on "10.0.0.108" [2024-04-07 18:48:03.455 +10:00] [ERR] Unable to bind to "255.255.255.255":7359 System.Net.Sockets.SocketException (98): Address already in use at System.Net.Sockets.Socket.DoBind(EndPoint endPointSnapshot, SocketAddress socketAddress) at System.Net.Sockets.Socket.Bind(EndPoint localEP) at Jellyfin.Networking.AutoDiscoveryHost.ListenForAutoDiscoveryMessage(IPAddress address, CancellationToken cancellationToken) [2024-04-07 18:48:03.456 +10:00] [ERR] Unable to bind to "127.255.255.255":7359 System.Net.Sockets.SocketException (98): Address already in use at System.Net.Sockets.Socket.DoBind(EndPoint endPointSnapshot, SocketAddress socketAddress) at System.Net.Sockets.Socket.Bind(EndPoint localEP) at Jellyfin.Networking.AutoDiscoveryHost.ListenForAutoDiscoveryMessage(IPAddress address, CancellationToken cancellationToken) [2024-04-07 18:48:03.456 +10:00] [ERR] Unable to bind to "10.0.0.255":7359 System.Net.Sockets.SocketException (98): Address already in use at System.Net.Sockets.Socket.DoBind(EndPoint endPointSnapshot, SocketAddress socketAddress) at System.Net.Sockets.Socket.Bind(EndPoint localEP) at Jellyfin.Networking.AutoDiscoveryHost.ListenForAutoDiscoveryMessage(IPAddress address, CancellationToken cancellationToken) [2024-04-07 18:48:03.755 +10:00] [WRN] The WebRootPath was not found: "/var/lib/jellyfin/wwwroot". Static files may be unavailable. [2024-04-07 18:48:03.780 +10:00] [INF] Running startup tasks [2024-04-07 18:48:03.794 +10:00] [INF] Daily trigger for "Generate Trickplay Images" set to fire at 2024-04-08 03:00:00.000 +10:00, which is 08:11:56.2053821 from now. [2024-04-07 18:48:03.797 +10:00] [INF] Daily trigger for "Extract Chapter Images" set to fire at 2024-04-08 02:00:00.000 +10:00, which is 07:11:56.2028193 from now. [2024-04-07 18:48:03.823 +10:00] [INF] Found ffmpeg version "6.0.1" [2024-04-07 18:48:03.861 +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-07 18:48:03.877 +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-07 18:48:03.893 +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-07 18:48:03.991 +10:00] [INF] Available hwaccel types: ["cuda", "vaapi", "qsv", "drm", "opencl", "vulkan"] [2024-04-07 18:48:04.340 +10:00] [INF] VAAPI device "/dev/dri/renderD128" is AMD GPU [2024-04-07 18:48:04.340 +10:00] [INF] VAAPI device "/dev/dri/renderD128" supports Vulkan DRM interop [2024-04-07 18:48:04.340 +10:00] [INF] FFmpeg: "/usr/lib/jellyfin-ffmpeg/ffmpeg" [2024-04-07 18:48:04.341 +10:00] [INF] ServerId: "09e71f321e5b4ed3b0315b58d4386cd4" [2024-04-07 18:48:04.341 +10:00] [INF] Core startup complete [2024-04-07 18:48:04.341 +10:00] [INF] Startup complete 0:00:03.0092766 [2024-04-07 18:48:04.572 +10:00] [INF] Watching directory "/mnt/truenas/media/Movies" [2024-04-07 18:48:04.635 +10:00] [INF] Watching directory "/mnt/truenas/media/tvshows" [2024-04-07 18:48:06.912 +10:00] [INF] "Clean up collections and playlists" Completed after 0 minute(s) and 0 seconds [2024-04-07 18:48:09.538 +10:00] [INF] "Update Plugins" Completed after 0 minute(s) and 2 seconds [2024-04-07 18:48:17.126 +10:00] [INF] WS "10.0.0.158" request [2024-04-07 18:48:24.520 +10:00] [INF] User policy for "nihalvm". EnablePlaybackRemuxing: True EnableVideoPlaybackTranscoding: True EnableAudioPlaybackTranscoding: True [2024-04-07 18:48:24.778 +10:00] [INF] User policy for "nihalvm". EnablePlaybackRemuxing: True EnableVideoPlaybackTranscoding: True EnableAudioPlaybackTranscoding: True [2024-04-07 18:48:25.689 +10:00] [INF] "/usr/lib/jellyfin-ffmpeg/ffmpeg" "-analyzeduration 200M -probesize 1G -init_hw_device drm=dr:/dev/dri/renderD128 -init_hw_device vaapi=va@dr -init_hw_device vulkan=vk@dr -filter_hw_device vk -hwaccel vaapi -hwaccel_output_format vaapi -noautorotate -i file:\"/mnt/truenas/media/Movies/Cars (2006)/Cars (2006) Bluray-2160p.mkv\" -noautoscale -map_metadata -1 -map_chapters -1 -threads 0 -map 0:0 -map 0:1 -map -0:s -codec:v:0 hevc_vaapi -tag:v:0 hvc1 -rc_mode VBR -b:v 5872109 -maxrate 5872109 -bufsize 11744218 -profile:v:0 main -sei -a53_cc -force_key_frames:0 \"expr:gte(t,n_forced*3)\" -flags:v -global_header -vf \"setparams=color_primaries=bt2020:color_trc=smpte2084:colorspace=bt2020nc,hwmap=derive_device=vulkan,format=vulkan,libplacebo=upscaler=none:downscaler=none:w=1920:h=804:format=bgra:tonemapping=bt.2390:peak_detect=0:color_primaries=bt709:color_trc=bt709:colorspace=bt709:range=tv,hwmap=derive_device=vaapi,format=vaapi,scale_vaapi=format=nv12\" -codec:a:0 libfdk_aac -ac 2 -ab 384000 -ar 48000 -af \"volume=2\" -copyts -avoid_negative_ts disabled -max_muxing_queue_size 2048 -f hls -max_delay 5000000 -hls_time 3 -hls_segment_type mpegts -start_number 0 -hls_segment_filename \"/mnt/truenas/jellyfin/transcodes/1f8222f29afd635d4c1a0613334461f8%d.ts\" -hls_playlist_type vod -hls_list_size 0 -y \"/mnt/truenas/jellyfin/transcodes/1f8222f29afd635d4c1a0613334461f8.m3u8\"" [2024-04-07 18:48:28.089 +10:00] [INF] Stopping ffmpeg process with q command for "/mnt/truenas/jellyfin/transcodes/1f8222f29afd635d4c1a0613334461f8.m3u8" [2024-04-07 18:48:28.259 +10:00] [INF] FFmpeg exited with code 0 [2024-04-07 18:48:28.264 +10:00] [INF] "/usr/lib/jellyfin-ffmpeg/ffmpeg" "-analyzeduration 200M -probesize 1G -ss 00:12:09.000 -init_hw_device drm=dr:/dev/dri/renderD128 -init_hw_device vaapi=va@dr -init_hw_device vulkan=vk@dr -filter_hw_device vk -hwaccel vaapi -hwaccel_output_format vaapi -noautorotate -i file:\"/mnt/truenas/media/Movies/Cars (2006)/Cars (2006) Bluray-2160p.mkv\" -noautoscale -map_metadata -1 -map_chapters -1 -threads 0 -map 0:0 -map 0:1 -map -0:s -codec:v:0 hevc_vaapi -tag:v:0 hvc1 -rc_mode VBR -b:v 5872109 -maxrate 5872109 -bufsize 11744218 -profile:v:0 main -sei -a53_cc -force_key_frames:0 \"expr:gte(t,n_forced*3)\" -flags:v -global_header -vf \"setparams=color_primaries=bt2020:color_trc=smpte2084:colorspace=bt2020nc,hwmap=derive_device=vulkan,format=vulkan,libplacebo=upscaler=none:downscaler=none:w=1920:h=804:format=bgra:tonemapping=bt.2390:peak_detect=0:color_primaries=bt709:color_trc=bt709:colorspace=bt709:range=tv,hwmap=derive_device=vaapi,format=vaapi,scale_vaapi=format=nv12\" -codec:a:0 libfdk_aac -ac 2 -ab 384000 -ar 48000 -af \"volume=2\" -copyts -avoid_negative_ts disabled -max_muxing_queue_size 2048 -f hls -max_delay 5000000 -hls_time 3 -hls_segment_type mpegts -start_number 243 -hls_segment_filename \"/mnt/truenas/jellyfin/transcodes/1f8222f29afd635d4c1a0613334461f8%d.ts\" -hls_playlist_type vod -hls_list_size 0 -y \"/mnt/truenas/jellyfin/transcodes/1f8222f29afd635d4c1a0613334461f8.m3u8\"" [2024-04-07 18:49:03.808 +10:00] [INF] Stopping directory watching for path "/mnt/truenas/media/Movies" [2024-04-07 18:49:03.809 +10:00] [INF] Stopping directory watching for path "/mnt/truenas/media/tvshows" [2024-04-07 18:49:03.810 +10:00] [INF] Validating media library [2024-04-07 18:49:29.856 +10:00] [INF] WS "10.0.0.23" request [2024-04-07 18:52:12.651 +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-07 18:52:12.657 +10:00] [INF] WS "10.0.0.23" closed [2024-04-07 18:56:03.123 +10:00] [INF] Transcoding kill timer stopped for JobId "c1c59e5cdf2e4944a5d0b47911413a48" PlaySessionId "67d81e33445b45ff97d5d7d0159e2ea5". Killing transcoding [2024-04-07 18:56:03.123 +10:00] [INF] Stopping ffmpeg process with q command for "/mnt/truenas/jellyfin/transcodes/1f8222f29afd635d4c1a0613334461f8.m3u8" [2024-04-07 18:56:03.264 +10:00] [INF] FFmpeg exited with code 0 [2024-04-07 18:56:03.265 +10:00] [INF] Deleting partial stream file(s) "/mnt/truenas/jellyfin/transcodes/1f8222f29afd635d4c1a0613334461f8.m3u8" [2024-04-07 18:58:48.867 +10:00] [WRN] WS "10.0.0.158" error receiving data: "The remote party closed the WebSocket connection without completing the close handshake." [2024-04-07 18:58:49.868 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:58:50.868 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:58:51.370 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:58:52.382 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:58:53.374 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:58:54.378 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:58:55.377 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:58:56.381 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:58:57.381 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:58:58.382 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:58:59.386 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:59:00.390 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:59:01.390 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:59:02.391 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:59:03.386 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:59:04.894 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:59:05.895 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:59:06.899 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:59:07.899 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:59:08.899 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:59:09.898 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:59:10.898 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:59:11.898 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:59:12.899 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:59:13.902 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:59:14.902 +10:00] [ERR] Error reporting playback progress MediaBrowser.Common.Extensions.ResourceNotFoundException: Session d497262383626f226f80a53459b8102d not found. at Emby.Server.Implementations.Session.SessionManager.OnPlaybackProgress(PlaybackProgressInfo info, Boolean isAutomated) at MediaBrowser.Controller.Session.SessionInfo.OnProgressTimerCallback(Object state) [2024-04-07 18:59:14.907 +10:00] [INF] WS "10.0.0.158" closed [2024-04-07 18:59:15.117 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Progress". [2024-04-07 18:59:15.125 +10:00] [INF] "/usr/lib/jellyfin-ffmpeg/ffmpeg" "-analyzeduration 200M -probesize 1G -ss 00:19:33.000 -init_hw_device drm=dr:/dev/dri/renderD128 -init_hw_device vaapi=va@dr -init_hw_device vulkan=vk@dr -filter_hw_device vk -hwaccel vaapi -hwaccel_output_format vaapi -noautorotate -i file:\"/mnt/truenas/media/Movies/Cars (2006)/Cars (2006) Bluray-2160p.mkv\" -noautoscale -map_metadata -1 -map_chapters -1 -threads 0 -map 0:0 -map 0:1 -map -0:s -codec:v:0 hevc_vaapi -tag:v:0 hvc1 -rc_mode VBR -b:v 5872109 -maxrate 5872109 -bufsize 11744218 -profile:v:0 main -sei -a53_cc -force_key_frames:0 \"expr:gte(t,n_forced*3)\" -flags:v -global_header -vf \"setparams=color_primaries=bt2020:color_trc=smpte2084:colorspace=bt2020nc,hwmap=derive_device=vulkan,format=vulkan,libplacebo=upscaler=none:downscaler=none:w=1920:h=804:format=bgra:tonemapping=bt.2390:peak_detect=0:color_primaries=bt709:color_trc=bt709:colorspace=bt709:range=tv,hwmap=derive_device=vaapi,format=vaapi,scale_vaapi=format=nv12\" -codec:a:0 libfdk_aac -ac 2 -ab 384000 -ar 48000 -af \"volume=2\" -copyts -avoid_negative_ts disabled -max_muxing_queue_size 2048 -f hls -max_delay 5000000 -hls_time 3 -hls_segment_type mpegts -start_number 391 -hls_segment_filename \"/mnt/truenas/jellyfin/transcodes/1f8222f29afd635d4c1a0613334461f8%d.ts\" -hls_playlist_type vod -hls_list_size 0 -y \"/mnt/truenas/jellyfin/transcodes/1f8222f29afd635d4c1a0613334461f8.m3u8\"" [2024-04-07 18:59:15.253 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Progress". [2024-04-07 18:59:15.259 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Items/a72cbdd6-f370-7d6e-5cf3-50147adc72e2/PlaybackInfo". [2024-04-07 18:59:15.265 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Progress". [2024-04-07 18:59:15.274 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Stopped". [2024-04-07 18:59:15.404 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Progress". [2024-04-07 18:59:15.407 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Progress". [2024-04-07 18:59:15.410 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Progress". [2024-04-07 18:59:15.416 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Stopped". [2024-04-07 18:59:15.553 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Progress". [2024-04-07 18:59:15.558 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Progress". [2024-04-07 18:59:15.563 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Progress". [2024-04-07 18:59:15.708 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Progress". [2024-04-07 18:59:15.711 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Progress". [2024-04-07 18:59:15.854 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Progress". [2024-04-07 18:59:16.004 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Progress". [2024-04-07 18:59:16.012 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Progress". [2024-04-07 18:59:16.158 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Progress". [2024-04-07 18:59:16.162 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Progress". [2024-04-07 18:59:16.309 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Progress". [2024-04-07 18:59:16.312 +10:00] [ERR] Error processing request: "Unexpected end of request content". URL "POST" "/Sessions/Playing/Progress". [2024-04-07 19:01:17.760 +10:00] [INF] Transcoding kill timer stopped for JobId "d601150added4de1b60c3afa18397684" PlaySessionId "67d81e33445b45ff97d5d7d0159e2ea5". Killing transcoding [2024-04-07 19:01:17.760 +10:00] [INF] Stopping ffmpeg process with q command for "/mnt/truenas/jellyfin/transcodes/1f8222f29afd635d4c1a0613334461f8.m3u8" [2024-04-07 19:01:17.920 +10:00] [INF] FFmpeg exited with code 0 [2024-04-07 19:01:17.920 +10:00] [INF] Deleting partial stream file(s) "/mnt/truenas/jellyfin/transcodes/1f8222f29afd635d4c1a0613334461f8.m3u8" [2024-04-07 19:02:47.119 +10:00] [INF] WS "10.0.0.23" request [2024-04-07 19:04:55.257 +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-07 19:04:55.261 +10:00] [INF] WS "10.0.0.23" closed [2024-04-07 19:05:24.933 +10:00] [INF] WS "10.0.0.21" request [2024-04-07 19:05:26.511 +10:00] [INF] WS "10.0.0.21" closed [2024-04-07 19:24:56.237 +10:00] [INF] WS "10.0.0.23" request [2024-04-07 19:25:19.602 +10:00] [INF] WS "10.0.0.23" closed [2024-04-07 19:43:44.641 +10:00] [INF] WS "10.0.0.23" request [2024-04-07 19:46:44.653 +10:00] [INF] Sending ForceKeepAlive message to 1 inactive WebSockets. [2024-04-07 19:47:32.658 +10:00] [INF] Sending ForceKeepAlive message to 1 inactive WebSockets. [2024-04-07 19:48:20.661 +10:00] [INF] Sending ForceKeepAlive message to 1 inactive WebSockets. [2024-04-07 19:49:04.699 +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-07 19:49:04.702 +10:00] [INF] WS "10.0.0.23" closed [2024-04-07 20:44:42.138 +10:00] [INF] WS "10.0.0.23" request [2024-04-07 20:44:44.950 +10:00] [INF] WS "10.0.0.23" closed