2023-07-09, 11:39 PM
(This post was last modified: 2023-07-10, 12:39 AM by Stumbling8734. Edited 1 time in total.)
Hello team!
Server Details
Server: PC
Version: 10.8.9
Operating System: Windows
Architecture: X64
16gb ram
Movies stored on HDD
WIFI speed 1gb/s
Device details experiencing issues
iPad OS 16.5.1
Swiftfin Version 1.0.1
Description of issue:
On the iPad, I will start Mission Impossible and every 5-10 seconds, the video and audio stops for 5-10 seconds and will then continue. Constantly in an on and off again pattern.
I would interpret this as a buffering issue. However, I bought 1gbps wifi router and this is the only device using that bandwidth, and the computer where jellyfin is installed has a dedicated GPU thats sitting at a 26% utilization.
Jellyfin is set up with Nvidia NVENC transcoding, but according to these logs this specific show is direct playing.
When I attempt to play the video on infuse, it prompts me to purchase infuse pro and wont play
When I watch the video locally on the server it plays without issue.
Under the user profile for playback, I have the Home network quality set to 720p - 6mbps and Maximum Allowed Video Transcoding Resolution set to Auto, though I have tried other configurations as well without success.
Below are the logs
Logs
[2023-07-09 17:25:41.176 -06:00] [INF] [76] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTA5LjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTE0LjB8MTY4ODc4NDYwNjg0Ng11-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810"
[2023-07-09 17:25:47.141 -06:00] [INF] [235] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Existing tracker found! : iOS_1547ADC3-F668-4F95-9281-00D1C40FEDA4_1688943810.144201-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810
[2023-07-09 17:25:47.142 -06:00] [INF] [235] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Saving existing playback tracking activity in DB
[2023-07-09 17:25:47.149 -06:00] [INF] [235] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Removing existing tracker : iOS_1547ADC3-F668-4F95-9281-00D1C40FEDA4_1688943810.144201-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810
[2023-07-09 17:25:47.150 -06:00] [INF] [235] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Adding playback tracker : iOS_1547ADC3-F668-4F95-9281-00D1C40FEDA4_1688943810.144201-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810
[2023-07-09 17:25:47.150 -06:00] [INF] [235] Jellyfin.Plugin.PlaybackReporting.Data.PlaybackTracker: PlaybackTracker : Adding Start Event : 07/09/2023 17:25:47
[2023-07-09 17:25:47.151 -06:00] [INF] [235] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Creating StartPlaybackTimer Task
[2023-07-09 17:25:47.152 -06:00] [INF] [186] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : Entered
[2023-07-09 17:25:48.125 -06:00] [INF] [45] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "iOS_1547ADC3-F668-4F95-9281-00D1C40FEDA4_1688943810.144201-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810"
[2023-07-09 17:26:01.197 -06:00] [INF] [76] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTA5LjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTE0LjB8MTY4ODc4NDYwNjg0Ng11-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810"
[2023-07-09 17:26:07.155 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: session.RemoteEndPoint : "192.168.0.55"
[2023-07-09 17:26:07.156 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : event_playing_id = "be87d3db114680146af5508bd90a9810"
[2023-07-09 17:26:07.157 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : event_user_id = "8837540dee9c48dbacbfdcc2911bc903"
[2023-07-09 17:26:07.158 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : event_user_id_int = 1
[2023-07-09 17:26:07.158 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : session_playing_id = "be87d3db114680146af5508bd90a9810"
[2023-07-09 17:26:07.159 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : session_user_id = "8837540dee9c48dbacbfdcc2911bc903"
[2023-07-09 17:26:07.160 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : play_method = "DirectPlay"
[2023-07-09 17:26:07.160 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : e.ClientName = "Jellyfin iOS"
[2023-07-09 17:26:07.161 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : e.DeviceName = "iPad"
[2023-07-09 17:26:07.161 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : ItemName = "Mission: Impossible - Fallout"
[2023-07-09 17:26:07.162 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : ItemId = "be87d3db114680146af5508bd90a9810"
[2023-07-09 17:26:07.163 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : ItemType = "Movie"
[2023-07-09 17:26:07.163 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : All matches, playback registered
[2023-07-09 17:26:07.164 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Playback tracker found, adding playback info : "iOS_1547ADC3-F668-4F95-9281-00D1C40FEDA4_1688943810.144201-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810"
[2023-07-09 17:26:07.165 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Saving playback tracking activity in DB
[2023-07-09 17:26:07.172 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : Exited
[2023-07-09 17:26:08.404 -06:00] [INF] [186] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "iOS_1547ADC3-F668-4F95-9281-00D1C40FEDA4_1688943810.144201-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810"
[2023-07-09 17:26:08.706 -06:00] [INF] [76] Jellyfin.Api.Helpers.TranscodingJobHelper: Stopping ffmpeg process with q command for "C:\ProgramData\Jellyfin\Server\transcodes\dbccce8f2e14be6a2229d30c5ac7dc4d.m3u8"
[2023-07-09 17:26:08.890 -06:00] [INF] [76] Jellyfin.Api.Helpers.TranscodingJobHelper: FFmpeg exited with code 0
[2023-07-09 17:26:08.891 -06:00] [INF] [76] Jellyfin.Api.Helpers.TranscodingJobHelper: Deleting partial stream file(s) "C:\ProgramData\Jellyfin\Server\transcodes\dbccce8f2e14be6a2229d30c5ac7dc4d.m3u8"
[2023-07-09 17:26:10.436 -06:00] [INF] [160] Emby.Server.Implementations.Session.SessionManager: Playback stopped reported by app "Jellyfin Web" "10.8.9" playing "Mission: Impossible - Fallout". Stopped at "1743941" ms
[2023-07-09 17:26:11.462 -06:00] [WRN] [235] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "http://localhost:8096/Users/8837540dee9c48dbacbfdcc2911bc903/Items/Resume?Limit=12&Recursive=true&Fields=PrimaryImageAspectRatio%2CBasicSyncInfo&ImageTypeLimit=1&EnableImageTypes=Primary%2CBackdrop%2CThumb&EnableTotalRecordCount=false&MediaTypes=Audio" to "127.0.0.1" in 0:00:02.7272928 with Status Code 200
[2023-07-09 17:26:11.462 -06:00] [WRN] [81] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "http://localhost:8096/Users/8837540dee9c48dbacbfdcc2911bc903/Items/Resume?Limit=12&Recursive=true&Fields=PrimaryImageAspectRatio%2CBasicSyncInfo&ImageTypeLimit=1&EnableImageTypes=Primary%2CBackdrop%2CThumb&EnableTotalRecordCount=false&MediaTypes=Book" to "127.0.0.1" in 0:00:02.727354 with Status Code 200
[2023-07-09 17:26:12.134 -06:00] [WRN] [170] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "http://localhost:8096/Users/8837540dee9c48dbacbfdcc2911bc903/Items/Resume?Limit=12&Recursive=true&Fields=PrimaryImageAspectRatio%2CBasicSyncInfo&ImageTypeLimit=1&EnableImageTypes=Primary%2CBackdrop%2CThumb&EnableTotalRecordCount=false&MediaTypes=Video" to "127.0.0.1" in 0:00:03.4012068 with Status Code 200
[2023-07-09 17:26:12.186 -06:00] [INF] [61] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Playback stop tracker found, processing stop : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTA5LjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTE0LjB8MTY4ODc4NDYwNjg0Ng11-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810"
[2023-07-09 17:26:12.186 -06:00] [WRN] [160] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "http://localhost:8096/Sessions/Playing/Stopped" to "127.0.0.1" in 0:00:03.4854953 with Status Code 204
[2023-07-09 17:26:12.187 -06:00] [INF] [61] Jellyfin.Plugin.PlaybackReporting.Data.PlaybackTracker: PlaybackTracker : Adding Stop Event : 07/09/2023 17:26:12
[2023-07-09 17:26:12.188 -06:00] [INF] [61] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Saving playback tracking activity in DB
[2023-07-09 17:26:12.230 -06:00] [WRN] [235] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "http://192.168.0.108:8096/Sessions/Playing/Progress" to "192.168.0.55" in 0:00:00.6718421 with Status Code 204
[2023-07-09 17:26:12.266 -06:00] [WRN] [186] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "http://localhost:8096/Shows/NextUp?Limit=24&Fields=PrimaryImageAspectRatio%2CDateCreated%2CBasicSyncInfo%2CPath%2CMediaSourceCount&UserId=8837540dee9c48dbacbfdcc2911bc903&ImageTypeLimit=1&EnableImageTypes=Primary%2CBackdrop%2CBanner%2CThumb&EnableTotalRecordCount=false&DisableFirstEpisode=false&NextUpDateCutoff=2022-07-09T23%3A26%3A08.728Z&EnableRewatching=false" to "127.0.0.1" in 0:00:03.5331049 with Status Code 200
[2023-07-09 17:26:19.230 -06:00] [WRN] [186] Trakt.ServerMediator: Received playback progress from user "PC" but initial state was never set - setting it now!
[2023-07-09 17:26:28.855 -06:00] [INF] [160] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "iOS_1547ADC3-F668-4F95-9281-00D1C40FEDA4_1688943810.144201-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810"
[2023-07-09 17:26:30.048 -06:00] [ERR] [146] Emby.Dlna.Main.DlnaEntryPoint: Error sending socket message from "172.30.91.19" to "239.255.255.250:1900"
System.Net.Sockets.SocketException (10049): The requested address is not valid in its context.
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error, CancellationToken cancellationToken)
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.System.Threading.Tasks.Sources.IValueTaskSource<System.Int32>.GetResult(Int16 token)
at System.Threading.Tasks.ValueTask
--- End of stack trace from previous location ---
at System.Threading.Tasks.TaskToApm.End[TResult](IAsyncResult asyncResult)
at Emby.Server.Implementations.Net.UdpSocket.<>c__DisplayClass21_0.<SendToAsync>b__0(IAsyncResult callbackResult)
--- End of stack trace from previous location ---
at Rssdp.Infrastructure.SsdpCommunicationsServer.SendFromSocket(ISocket socket, Byte[] messageData, IPEndPoint destination, CancellationToken cancellationToken)
[2023-07-09 17:26:30.170 -06:00] [ERR] [170] Emby.Dlna.Main.DlnaEntryPoint: Error sending socket message from "172.30.91.19" to "239.255.255.250:1900"
System.Net.Sockets.SocketException (10049): The requested address is not valid in its context.
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error, CancellationToken cancellationToken)
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.System.Threading.Tasks.Sources.IValueTaskSource<System.Int32>.GetResult(Int16 token)
at System.Threading.Tasks.ValueTask
--- End of stack trace from previous location ---
at System.Threading.Tasks.TaskToApm.End[TResult](IAsyncResult asyncResult)
at Emby.Server.Implementations.Net.UdpSocket.<>c__DisplayClass21_0.<SendToAsync>b__0(IAsyncResult callbackResult)
--- End of stack trace from previous location ---
at Rssdp.Infrastructure.SsdpCommunicationsServer.SendFromSocket(ISocket socket, Byte[] messageData, IPEndPoint destination, CancellationToken cancellationToken)
[2023-07-09 17:26:49.096 -06:00] [INF] [61] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "iOS_1547ADC3-F668-4F95-9281-00D1C40FEDA4_1688943810.144201-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810"
[2023-07-09 17:27:09.237 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "iOS_1547ADC3-F668-4F95-9281-00D1C40FEDA4_1688943810.144201-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810"
[2023-07-09 17:27:11.715 -06:00] [INF] [61] Emby.Server.Implementations.IO.LibraryMonitor: "Movies" ("F:\Movies") will be refreshed.
[2023-07-09 17:27:17.441 -06:00] [INF] [14] Jellyfin.Plugin.OpenSubtitles.OpenSubtitleDownloader: Daily download limit reached, returning no results for automated task
[2023-07-09 17:27:26.778 -06:00] [INF] [230] Trakt.Helpers.LibraryManagerEventsHelper: Timer elapsed - processing queued items
[2023-07-09 17:27:26.778 -06:00] [INF] [230] Trakt.Helpers.LibraryManagerEventsHelper: No events... stopping queue timer
[2023-07-09 17:27:29.485 -06:00] [INF] [51] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "iOS_1547ADC3-F668-4F95-9281-00D1C40FEDA4_1688943810.144201-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810"
[2023-07-09 17:27:30.041 -06:00] [ERR] [50] Emby.Dlna.Main.DlnaEntryPoint: Error sending socket message from "172.30.91.19" to "239.255.255.250:1900"
System.Net.Sockets.SocketException (10049): The requested address is not valid in its context.
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error, CancellationToken cancellationToken)
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.System.Threading.Tasks.Sources.IValueTaskSource<System.Int32>.GetResult(Int16 token)
at System.Threading.Tasks.ValueTask
--- End of stack trace from previous location ---
at System.Threading.Tasks.TaskToApm.End[TResult](IAsyncResult asyncResult)
at Emby.Server.Implementations.Net.UdpSocket.<>c__DisplayClass21_0.<SendToAsync>b__0(IAsyncResult callbackResult)
--- End of stack trace from previous location ---
at Rssdp.Infrastructure.SsdpCommunicationsServer.SendFromSocket(ISocket socket, Byte[] messageData, IPEndPoint destination, CancellationToken cancellationToken)
[2023-07-09 17:27:30.258 -06:00] [ERR] [230] Emby.Dlna.Main.DlnaEntryPoint: Error sending socket message from "172.30.91.19" to "239.255.255.250:1900"
System.Net.Sockets.SocketException (10049): The requested address is not valid in its context.
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error, CancellationToken cancellationToken)
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.System.Threading.Tasks.Sources.IValueTaskSource<System.Int32>.GetResult(Int16 token)
at System.Threading.Tasks.ValueTask`1.ValueTaskSourceAsTask.<>c.<.cctor>b__4_0(Object state)
--- End of stack trace from previous location ---
at System.Threading.Tasks.TaskToApm.End[TResult](IAsyncResult asyncResult)
at Emby.Server.Implementations.Net.UdpSocket.<>c__DisplayClass21_0.<SendToAsync>b__0(IAsyncResult callbackResult)
--- End of stack trace from previous location ---
at Rssdp.Infrastructure.SsdpCommunicationsServer.SendFromSocket(ISocket socket, Byte[] messageData, IPEndPoint destination, CancellationToken cancellationToken)
Server Details
Server: PC
Version: 10.8.9
Operating System: Windows
Architecture: X64
16gb ram
Movies stored on HDD
WIFI speed 1gb/s
Device details experiencing issues
iPad OS 16.5.1
Swiftfin Version 1.0.1
Description of issue:
On the iPad, I will start Mission Impossible and every 5-10 seconds, the video and audio stops for 5-10 seconds and will then continue. Constantly in an on and off again pattern.
I would interpret this as a buffering issue. However, I bought 1gbps wifi router and this is the only device using that bandwidth, and the computer where jellyfin is installed has a dedicated GPU thats sitting at a 26% utilization.
Jellyfin is set up with Nvidia NVENC transcoding, but according to these logs this specific show is direct playing.
When I attempt to play the video on infuse, it prompts me to purchase infuse pro and wont play
When I watch the video locally on the server it plays without issue.
Under the user profile for playback, I have the Home network quality set to 720p - 6mbps and Maximum Allowed Video Transcoding Resolution set to Auto, though I have tried other configurations as well without success.
Below are the logs
Logs
[2023-07-09 17:25:41.176 -06:00] [INF] [76] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTA5LjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTE0LjB8MTY4ODc4NDYwNjg0Ng11-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810"
[2023-07-09 17:25:47.141 -06:00] [INF] [235] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Existing tracker found! : iOS_1547ADC3-F668-4F95-9281-00D1C40FEDA4_1688943810.144201-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810
[2023-07-09 17:25:47.142 -06:00] [INF] [235] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Saving existing playback tracking activity in DB
[2023-07-09 17:25:47.149 -06:00] [INF] [235] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Removing existing tracker : iOS_1547ADC3-F668-4F95-9281-00D1C40FEDA4_1688943810.144201-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810
[2023-07-09 17:25:47.150 -06:00] [INF] [235] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Adding playback tracker : iOS_1547ADC3-F668-4F95-9281-00D1C40FEDA4_1688943810.144201-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810
[2023-07-09 17:25:47.150 -06:00] [INF] [235] Jellyfin.Plugin.PlaybackReporting.Data.PlaybackTracker: PlaybackTracker : Adding Start Event : 07/09/2023 17:25:47
[2023-07-09 17:25:47.151 -06:00] [INF] [235] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Creating StartPlaybackTimer Task
[2023-07-09 17:25:47.152 -06:00] [INF] [186] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : Entered
[2023-07-09 17:25:48.125 -06:00] [INF] [45] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "iOS_1547ADC3-F668-4F95-9281-00D1C40FEDA4_1688943810.144201-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810"
[2023-07-09 17:26:01.197 -06:00] [INF] [76] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTA5LjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTE0LjB8MTY4ODc4NDYwNjg0Ng11-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810"
[2023-07-09 17:26:07.155 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: session.RemoteEndPoint : "192.168.0.55"
[2023-07-09 17:26:07.156 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : event_playing_id = "be87d3db114680146af5508bd90a9810"
[2023-07-09 17:26:07.157 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : event_user_id = "8837540dee9c48dbacbfdcc2911bc903"
[2023-07-09 17:26:07.158 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : event_user_id_int = 1
[2023-07-09 17:26:07.158 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : session_playing_id = "be87d3db114680146af5508bd90a9810"
[2023-07-09 17:26:07.159 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : session_user_id = "8837540dee9c48dbacbfdcc2911bc903"
[2023-07-09 17:26:07.160 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : play_method = "DirectPlay"
[2023-07-09 17:26:07.160 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : e.ClientName = "Jellyfin iOS"
[2023-07-09 17:26:07.161 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : e.DeviceName = "iPad"
[2023-07-09 17:26:07.161 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : ItemName = "Mission: Impossible - Fallout"
[2023-07-09 17:26:07.162 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : ItemId = "be87d3db114680146af5508bd90a9810"
[2023-07-09 17:26:07.163 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : ItemType = "Movie"
[2023-07-09 17:26:07.163 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : All matches, playback registered
[2023-07-09 17:26:07.164 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Playback tracker found, adding playback info : "iOS_1547ADC3-F668-4F95-9281-00D1C40FEDA4_1688943810.144201-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810"
[2023-07-09 17:26:07.165 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Saving playback tracking activity in DB
[2023-07-09 17:26:07.172 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : Exited
[2023-07-09 17:26:08.404 -06:00] [INF] [186] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "iOS_1547ADC3-F668-4F95-9281-00D1C40FEDA4_1688943810.144201-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810"
[2023-07-09 17:26:08.706 -06:00] [INF] [76] Jellyfin.Api.Helpers.TranscodingJobHelper: Stopping ffmpeg process with q command for "C:\ProgramData\Jellyfin\Server\transcodes\dbccce8f2e14be6a2229d30c5ac7dc4d.m3u8"
[2023-07-09 17:26:08.890 -06:00] [INF] [76] Jellyfin.Api.Helpers.TranscodingJobHelper: FFmpeg exited with code 0
[2023-07-09 17:26:08.891 -06:00] [INF] [76] Jellyfin.Api.Helpers.TranscodingJobHelper: Deleting partial stream file(s) "C:\ProgramData\Jellyfin\Server\transcodes\dbccce8f2e14be6a2229d30c5ac7dc4d.m3u8"
[2023-07-09 17:26:10.436 -06:00] [INF] [160] Emby.Server.Implementations.Session.SessionManager: Playback stopped reported by app "Jellyfin Web" "10.8.9" playing "Mission: Impossible - Fallout". Stopped at "1743941" ms
[2023-07-09 17:26:11.462 -06:00] [WRN] [235] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "http://localhost:8096/Users/8837540dee9c48dbacbfdcc2911bc903/Items/Resume?Limit=12&Recursive=true&Fields=PrimaryImageAspectRatio%2CBasicSyncInfo&ImageTypeLimit=1&EnableImageTypes=Primary%2CBackdrop%2CThumb&EnableTotalRecordCount=false&MediaTypes=Audio" to "127.0.0.1" in 0:00:02.7272928 with Status Code 200
[2023-07-09 17:26:11.462 -06:00] [WRN] [81] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "http://localhost:8096/Users/8837540dee9c48dbacbfdcc2911bc903/Items/Resume?Limit=12&Recursive=true&Fields=PrimaryImageAspectRatio%2CBasicSyncInfo&ImageTypeLimit=1&EnableImageTypes=Primary%2CBackdrop%2CThumb&EnableTotalRecordCount=false&MediaTypes=Book" to "127.0.0.1" in 0:00:02.727354 with Status Code 200
[2023-07-09 17:26:12.134 -06:00] [WRN] [170] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "http://localhost:8096/Users/8837540dee9c48dbacbfdcc2911bc903/Items/Resume?Limit=12&Recursive=true&Fields=PrimaryImageAspectRatio%2CBasicSyncInfo&ImageTypeLimit=1&EnableImageTypes=Primary%2CBackdrop%2CThumb&EnableTotalRecordCount=false&MediaTypes=Video" to "127.0.0.1" in 0:00:03.4012068 with Status Code 200
[2023-07-09 17:26:12.186 -06:00] [INF] [61] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Playback stop tracker found, processing stop : "TW96aWxsYS81LjAgKFdpbmRvd3MgTlQgMTAuMDsgV2luNjQ7IHg2NDsgcnY6MTA5LjApIEdlY2tvLzIwMTAwMTAxIEZpcmVmb3gvMTE0LjB8MTY4ODc4NDYwNjg0Ng11-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810"
[2023-07-09 17:26:12.186 -06:00] [WRN] [160] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "http://localhost:8096/Sessions/Playing/Stopped" to "127.0.0.1" in 0:00:03.4854953 with Status Code 204
[2023-07-09 17:26:12.187 -06:00] [INF] [61] Jellyfin.Plugin.PlaybackReporting.Data.PlaybackTracker: PlaybackTracker : Adding Stop Event : 07/09/2023 17:26:12
[2023-07-09 17:26:12.188 -06:00] [INF] [61] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Saving playback tracking activity in DB
[2023-07-09 17:26:12.230 -06:00] [WRN] [235] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "http://192.168.0.108:8096/Sessions/Playing/Progress" to "192.168.0.55" in 0:00:00.6718421 with Status Code 204
[2023-07-09 17:26:12.266 -06:00] [WRN] [186] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "http://localhost:8096/Shows/NextUp?Limit=24&Fields=PrimaryImageAspectRatio%2CDateCreated%2CBasicSyncInfo%2CPath%2CMediaSourceCount&UserId=8837540dee9c48dbacbfdcc2911bc903&ImageTypeLimit=1&EnableImageTypes=Primary%2CBackdrop%2CBanner%2CThumb&EnableTotalRecordCount=false&DisableFirstEpisode=false&NextUpDateCutoff=2022-07-09T23%3A26%3A08.728Z&EnableRewatching=false" to "127.0.0.1" in 0:00:03.5331049 with Status Code 200
[2023-07-09 17:26:19.230 -06:00] [WRN] [186] Trakt.ServerMediator: Received playback progress from user "PC" but initial state was never set - setting it now!
[2023-07-09 17:26:28.855 -06:00] [INF] [160] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "iOS_1547ADC3-F668-4F95-9281-00D1C40FEDA4_1688943810.144201-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810"
[2023-07-09 17:26:30.048 -06:00] [ERR] [146] Emby.Dlna.Main.DlnaEntryPoint: Error sending socket message from "172.30.91.19" to "239.255.255.250:1900"
System.Net.Sockets.SocketException (10049): The requested address is not valid in its context.
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error, CancellationToken cancellationToken)
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.System.Threading.Tasks.Sources.IValueTaskSource<System.Int32>.GetResult(Int16 token)
at System.Threading.Tasks.ValueTask
1.GetTaskForValueTaskSource(IValueTaskSource1 t)--- End of stack trace from previous location ---
at System.Threading.Tasks.TaskToApm.End[TResult](IAsyncResult asyncResult)
at Emby.Server.Implementations.Net.UdpSocket.<>c__DisplayClass21_0.<SendToAsync>b__0(IAsyncResult callbackResult)
--- End of stack trace from previous location ---
at Rssdp.Infrastructure.SsdpCommunicationsServer.SendFromSocket(ISocket socket, Byte[] messageData, IPEndPoint destination, CancellationToken cancellationToken)
[2023-07-09 17:26:30.170 -06:00] [ERR] [170] Emby.Dlna.Main.DlnaEntryPoint: Error sending socket message from "172.30.91.19" to "239.255.255.250:1900"
System.Net.Sockets.SocketException (10049): The requested address is not valid in its context.
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error, CancellationToken cancellationToken)
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.System.Threading.Tasks.Sources.IValueTaskSource<System.Int32>.GetResult(Int16 token)
at System.Threading.Tasks.ValueTask
1.ValueTaskSourceAsTask.<>c.<.cctor>b__4_0(Object state)
--- End of stack trace from previous location ---
at System.Threading.Tasks.TaskToApm.End[TResult](IAsyncResult asyncResult)
at Emby.Server.Implementations.Net.UdpSocket.<>c__DisplayClass21_0.<SendToAsync>b__0(IAsyncResult callbackResult)
--- End of stack trace from previous location ---
at Rssdp.Infrastructure.SsdpCommunicationsServer.SendFromSocket(ISocket socket, Byte[] messageData, IPEndPoint destination, CancellationToken cancellationToken)
[2023-07-09 17:26:30.281 -06:00] [ERR] [160] Emby.Dlna.Main.DlnaEntryPoint: Error sending socket message from "172.30.91.19" to "239.255.255.250:1900"
System.Net.Sockets.SocketException (10049): The requested address is not valid in its context.
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error, CancellationToken cancellationToken)
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.System.Threading.Tasks.Sources.IValueTaskSource<System.Int32>.GetResult(Int16 token)
at System.Threading.Tasks.ValueTask1.ValueTaskSourceAsTask.<>c.<.cctor>b__4_0(Object state)--- End of stack trace from previous location ---
at System.Threading.Tasks.TaskToApm.End[TResult](IAsyncResult asyncResult)
at Emby.Server.Implementations.Net.UdpSocket.<>c__DisplayClass21_0.<SendToAsync>b__0(IAsyncResult callbackResult)
--- End of stack trace from previous location ---
at Rssdp.Infrastructure.SsdpCommunicationsServer.SendFromSocket(ISocket socket, Byte[] messageData, IPEndPoint destination, CancellationToken cancellationToken)
[2023-07-09 17:26:49.096 -06:00] [INF] [61] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "iOS_1547ADC3-F668-4F95-9281-00D1C40FEDA4_1688943810.144201-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810"
[2023-07-09 17:27:09.237 -06:00] [INF] [170] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "iOS_1547ADC3-F668-4F95-9281-00D1C40FEDA4_1688943810.144201-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810"
[2023-07-09 17:27:11.715 -06:00] [INF] [61] Emby.Server.Implementations.IO.LibraryMonitor: "Movies" ("F:\Movies") will be refreshed.
[2023-07-09 17:27:17.441 -06:00] [INF] [14] Jellyfin.Plugin.OpenSubtitles.OpenSubtitleDownloader: Daily download limit reached, returning no results for automated task
[2023-07-09 17:27:26.778 -06:00] [INF] [230] Trakt.Helpers.LibraryManagerEventsHelper: Timer elapsed - processing queued items
[2023-07-09 17:27:26.778 -06:00] [INF] [230] Trakt.Helpers.LibraryManagerEventsHelper: No events... stopping queue timer
[2023-07-09 17:27:29.485 -06:00] [INF] [51] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "iOS_1547ADC3-F668-4F95-9281-00D1C40FEDA4_1688943810.144201-8837540dee9c48dbacbfdcc2911bc903-be87d3db114680146af5508bd90a9810"
[2023-07-09 17:27:30.041 -06:00] [ERR] [50] Emby.Dlna.Main.DlnaEntryPoint: Error sending socket message from "172.30.91.19" to "239.255.255.250:1900"
System.Net.Sockets.SocketException (10049): The requested address is not valid in its context.
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error, CancellationToken cancellationToken)
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.System.Threading.Tasks.Sources.IValueTaskSource<System.Int32>.GetResult(Int16 token)
at System.Threading.Tasks.ValueTask
1.ValueTaskSourceAsTask.<>c.<.cctor>b__4_0(Object state)
--- End of stack trace from previous location ---
at System.Threading.Tasks.TaskToApm.End[TResult](IAsyncResult asyncResult)
at Emby.Server.Implementations.Net.UdpSocket.<>c__DisplayClass21_0.<SendToAsync>b__0(IAsyncResult callbackResult)
--- End of stack trace from previous location ---
at Rssdp.Infrastructure.SsdpCommunicationsServer.SendFromSocket(ISocket socket, Byte[] messageData, IPEndPoint destination, CancellationToken cancellationToken)
[2023-07-09 17:27:30.149 -06:00] [ERR] [51] Emby.Dlna.Main.DlnaEntryPoint: Error sending socket message from "172.30.91.19" to "239.255.255.250:1900"
System.Net.Sockets.SocketException (10049): The requested address is not valid in its context.
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error, CancellationToken cancellationToken)
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.System.Threading.Tasks.Sources.IValueTaskSource<System.Int32>.GetResult(Int16 token)
at System.Threading.Tasks.ValueTask1.ValueTaskSourceAsTask.<>c.<.cctor>b__4_0(Object state)--- End of stack trace from previous location ---
at System.Threading.Tasks.TaskToApm.End[TResult](IAsyncResult asyncResult)
at Emby.Server.Implementations.Net.UdpSocket.<>c__DisplayClass21_0.<SendToAsync>b__0(IAsyncResult callbackResult)
--- End of stack trace from previous location ---
at Rssdp.Infrastructure.SsdpCommunicationsServer.SendFromSocket(ISocket socket, Byte[] messageData, IPEndPoint destination, CancellationToken cancellationToken)
[2023-07-09 17:27:30.258 -06:00] [ERR] [230] Emby.Dlna.Main.DlnaEntryPoint: Error sending socket message from "172.30.91.19" to "239.255.255.250:1900"
System.Net.Sockets.SocketException (10049): The requested address is not valid in its context.
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error, CancellationToken cancellationToken)
at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.System.Threading.Tasks.Sources.IValueTaskSource<System.Int32>.GetResult(Int16 token)
at System.Threading.Tasks.ValueTask`1.ValueTaskSourceAsTask.<>c.<.cctor>b__4_0(Object state)
--- End of stack trace from previous location ---
at System.Threading.Tasks.TaskToApm.End[TResult](IAsyncResult asyncResult)
at Emby.Server.Implementations.Net.UdpSocket.<>c__DisplayClass21_0.<SendToAsync>b__0(IAsyncResult callbackResult)
--- End of stack trace from previous location ---
at Rssdp.Infrastructure.SsdpCommunicationsServer.SendFromSocket(ISocket socket, Byte[] messageData, IPEndPoint destination, CancellationToken cancellationToken)