[Issue]: Very slow to load initially, and also dashboard components #4062

Closed
opened 2026-02-07 00:21:05 +03:00 by OVERLORD · 30 comments
Owner

Originally created by @ragebflame on GitHub (Jul 26, 2022).

Please describe your bug

When initially accessing Jellyfin via the Browser or Android app, it is very slow to load any content. It is also very slow for some of the Dashboard components to be populated. The Jellyfin logs below are from navigating to the Dashboard page. The server Info is very slow to load.

Server: Jellyfin-laptop
Version: 10.8.1
Operating System: Linux
Architecture: X64

From the Debug level logs I can see that the request is taking 4 seconds to complete:

[20:49:47] [INF] [21] Microsoft.AspNetCore.Hosting.Diagnostics: Request finished HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/System/Info - - - 200 - application/json;+charset=utf-8 4043.0172ms

Browsing media and playback is all working without issue. This has only started occurring since upgrading to 10.8.0.

Steps to reproduce:

  1. Visit the Dashboard via a browser / Android app
  2. Wait for the information to be displayed

Jellyfin Version

Other

if other:

10.8.1

Environment

- OS: Ubuntu 20.04.4 LTS x86_64 (Host)
- Virtualization: Docker
- Clients: Browser
- Browser: Chrome 103.0.5060.134
- Hardware Acceleration: 
- Plugins: none
- Reverse Proxy: none
- Base URL: /jellyfin-laptop
- Networking: Bridge
- Storage: Local

Jellyfin logs

[20:49:43] [INF] [21] Microsoft.AspNetCore.Hosting.Diagnostics: Request starting HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/System/Info - -
[20:49:43] [INF] [28] Microsoft.AspNetCore.Hosting.Diagnostics: Request starting HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/ScheduledTasks - -
[20:49:43] [DBG] [21] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path /System/Info does not match a supported file type
[20:49:43] [DBG] [28] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path /ScheduledTasks does not match a supported file type
[20:49:43] [DBG] [21] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[20:49:43] [DBG] [28] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[20:49:43] [INF] [23] Microsoft.AspNetCore.Hosting.Diagnostics: Request starting HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2022-07-25T19%3A49%3A43.019Z&hasUserId=true - -
[20:49:43] [DBG] [23] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path /System/ActivityLog/Entries does not match a supported file type
[20:49:43] [DBG] [23] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[20:49:43] [DBG] [28] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was successfully authenticated.
[20:49:43] [INF] [11] Microsoft.AspNetCore.Hosting.Diagnostics: Request starting HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2022-07-19T19%3A49%3A43.020Z&hasUserId=false - -
[20:49:43] [DBG] [28] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[20:49:43] [DBG] [11] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path /System/ActivityLog/Entries does not match a supported file type
[20:49:43] [DBG] [28] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[20:49:43] [DBG] [11] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[20:49:43] [DBG] [21] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was successfully authenticated.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: 1 candidate(s) found for the request path '/ScheduledTasks'
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: Endpoint 'Jellyfin.Api.Controllers.ScheduledTasksController.GetTasks (Jellyfin.Api)' with route pattern 'ScheduledTasks' is valid for the request path '/ScheduledTasks'
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware: Request matched endpoint 'Jellyfin.Api.Controllers.ScheduledTasksController.GetTasks (Jellyfin.Api)'
[20:49:43] [DBG] [28] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was successfully authenticated.
[20:49:43] [DBG] [23] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was successfully authenticated.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Authorization.DefaultAuthorizationService: Authorization was successful.
[20:49:43] [DBG] [23] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[20:49:43] [DBG] [23] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: 1 candidate(s) found for the request path '/System/ActivityLog/Entries'
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: Endpoint 'Jellyfin.Api.Controllers.ActivityLogController.GetLogEntries (Jellyfin.Api)' with route pattern 'System/ActivityLog/Entries' is valid for the request path '/System/ActivityLog/Entries'
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware: Request matched endpoint 'Jellyfin.Api.Controllers.ActivityLogController.GetLogEntries (Jellyfin.Api)'
[20:49:43] [DBG] [23] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was successfully authenticated.
[20:49:43] [DBG] [11] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was successfully authenticated.
[20:49:43] [DBG] [11] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[20:49:43] [DBG] [11] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[20:49:43] [DBG] [11] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: 1 candidate(s) found for the request path '/System/ActivityLog/Entries'
[20:49:43] [DBG] [11] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: Endpoint 'Jellyfin.Api.Controllers.ActivityLogController.GetLogEntries (Jellyfin.Api)' with route pattern 'System/ActivityLog/Entries' is valid for the request path '/System/ActivityLog/Entries'
[20:49:43] [DBG] [11] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware: Request matched endpoint 'Jellyfin.Api.Controllers.ActivityLogController.GetLogEntries (Jellyfin.Api)'
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Authorization.DefaultAuthorizationService: Authorization was successful.
[20:49:43] [DBG] [11] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was successfully authenticated.
[20:49:43] [INF] [23] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executing endpoint 'Jellyfin.Api.Controllers.ActivityLogController.GetLogEntries (Jellyfin.Api)'
[20:49:43] [DBG] [11] Microsoft.AspNetCore.Authorization.DefaultAuthorizationService: Authorization was successful.
[20:49:43] [INF] [11] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executing endpoint 'Jellyfin.Api.Controllers.ActivityLogController.GetLogEntries (Jellyfin.Api)'
[20:49:43] [INF] [23] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Route matched with {action = "GetLogEntries", controller = "ActivityLog"}. Executing controller action with signature System.Threading.Tasks.Task`1[Microsoft.AspNetCore.Mvc.ActionResult`1[MediaBrowser.Model.Querying.QueryResult`1[MediaBrowser.Model.Activity.ActivityLogEntry]]] GetLogEntries(System.Nullable`1[System.Int32], System.Nullable`1[System.Int32], System.Nullable`1[System.DateTime], System.Nullable`1[System.Boolean]) on controller Jellyfin.Api.Controllers.ActivityLogController (Jellyfin.Api).
[20:49:43] [INF] [8] Microsoft.AspNetCore.Hosting.Diagnostics: Request starting HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/LiveTv/Recordings?UserId=b68358d0aa4e4943a4e294fc4ec8adcc&IsInProgress=true&Fields=CanDelete%2CPrimaryImageAspectRatio&EnableTotalRecordCount=false&EnableImageTypes=Primary%2CThumb%2CBackdrop - -
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of authorization filters (in the following order): ["None"]
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of resource filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter"]
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of action filters (in the following order): ["Microsoft.AspNetCore.Mvc.ModelBinding.UnsupportedContentTypeFilter (Order: -3000)", "Microsoft.AspNetCore.Mvc.Infrastructure.ModelStateInvalidFilter (Order: -2000)"]
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of exception filters (in the following order): ["None"]
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of result filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter", "Microsoft.AspNetCore.Mvc.Infrastructure.ClientErrorResultFilter (Order: -2000)", "Microsoft.AspNetCore.Mvc.ProducesAttribute (Order: 0)"]
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executing controller factory for controller Jellyfin.Api.Controllers.ActivityLogController (Jellyfin.Api)
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed controller factory for controller Jellyfin.Api.Controllers.ActivityLogController (Jellyfin.Api)
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to bind parameter 'startIndex' of type 'System.Nullable`1[System.Int32]' ...
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Attempting to bind parameter 'startIndex' of type 'System.Nullable`1[System.Int32]' using the name 'startIndex' in request data ...
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Done attempting to bind parameter 'startIndex' of type 'System.Nullable`1[System.Int32]'.
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to bind parameter 'startIndex' of type 'System.Nullable`1[System.Int32]'.
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to validate the bound parameter 'startIndex' of type 'System.Nullable`1[System.Int32]' ...
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to validate the bound parameter 'startIndex' of type 'System.Nullable`1[System.Int32]'.
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to bind parameter 'limit' of type 'System.Nullable`1[System.Int32]' ...
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Attempting to bind parameter 'limit' of type 'System.Nullable`1[System.Int32]' using the name 'limit' in request data ...
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Done attempting to bind parameter 'limit' of type 'System.Nullable`1[System.Int32]'.
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to bind parameter 'limit' of type 'System.Nullable`1[System.Int32]'.
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to validate the bound parameter 'limit' of type 'System.Nullable`1[System.Int32]' ...
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to validate the bound parameter 'limit' of type 'System.Nullable`1[System.Int32]'.
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to bind parameter 'minDate' of type 'System.Nullable`1[System.DateTime]' ...
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.DateTimeModelBinder: Attempting to bind parameter 'minDate' of type 'System.Nullable`1[System.DateTime]' using the name 'minDate' in request data ...
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.DateTimeModelBinder: Done attempting to bind parameter 'minDate' of type 'System.Nullable`1[System.DateTime]'.
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to bind parameter 'minDate' of type 'System.Nullable`1[System.DateTime]'.
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to validate the bound parameter 'minDate' of type 'System.Nullable`1[System.DateTime]' ...
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to validate the bound parameter 'minDate' of type 'System.Nullable`1[System.DateTime]'.
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to bind parameter 'hasUserId' of type 'System.Nullable`1[System.Boolean]' ...
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Attempting to bind parameter 'hasUserId' of type 'System.Nullable`1[System.Boolean]' using the name 'hasUserId' in request data ...
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Done attempting to bind parameter 'hasUserId' of type 'System.Nullable`1[System.Boolean]'.
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to bind parameter 'hasUserId' of type 'System.Nullable`1[System.Boolean]'.
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to validate the bound parameter 'hasUserId' of type 'System.Nullable`1[System.Boolean]' ...
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to validate the bound parameter 'hasUserId' of type 'System.Nullable`1[System.Boolean]'.
[20:49:43] [DBG] [21] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[20:49:43] [DBG] [21] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[20:49:43] [DBG] [21] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: 1 candidate(s) found for the request path '/System/Info'
[20:49:43] [DBG] [21] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: Endpoint 'Jellyfin.Api.Controllers.SystemController.GetSystemInfo (Jellyfin.Api)' with route pattern 'System/Info' is valid for the request path '/System/Info'
[20:49:43] [DBG] [21] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware: Request matched endpoint 'Jellyfin.Api.Controllers.SystemController.GetSystemInfo (Jellyfin.Api)'
[20:49:43] [DBG] [21] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was successfully authenticated.
[20:49:43] [DBG] [21] Microsoft.AspNetCore.Authorization.DefaultAuthorizationService: Authorization was successful.
[20:49:43] [INF] [21] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executing endpoint 'Jellyfin.Api.Controllers.SystemController.GetSystemInfo (Jellyfin.Api)'
[20:49:43] [INF] [21] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Route matched with {action = "GetSystemInfo", controller = "System"}. Executing controller action with signature Microsoft.AspNetCore.Mvc.ActionResult`1[MediaBrowser.Model.System.SystemInfo] GetSystemInfo() on controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api).
[20:49:43] [DBG] [21] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of authorization filters (in the following order): ["None"]
[20:49:43] [DBG] [21] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of resource filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter"]
[20:49:43] [DBG] [21] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of action filters (in the following order): ["Microsoft.AspNetCore.Mvc.ModelBinding.UnsupportedContentTypeFilter (Order: -3000)", "Microsoft.AspNetCore.Mvc.Infrastructure.ModelStateInvalidFilter (Order: -2000)"]
[20:49:43] [DBG] [21] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of exception filters (in the following order): ["None"]
[20:49:43] [DBG] [21] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of result filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter", "Microsoft.AspNetCore.Mvc.Infrastructure.ClientErrorResultFilter (Order: -2000)", "Microsoft.AspNetCore.Mvc.ProducesAttribute (Order: 0)"]
[20:49:43] [DBG] [21] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executing controller factory for controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api)
[20:49:43] [DBG] [21] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed controller factory for controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api)
[20:49:43] [INF] [28] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executing endpoint 'Jellyfin.Api.Controllers.ScheduledTasksController.GetTasks (Jellyfin.Api)'
[20:49:43] [INF] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Route matched with {action = "GetTasks", controller = "ScheduledTasks"}. Executing controller action with signature System.Collections.Generic.IEnumerable`1[MediaBrowser.Model.Tasks.TaskInfo] GetTasks(System.Nullable`1[System.Boolean], System.Nullable`1[System.Boolean]) on controller Jellyfin.Api.Controllers.ScheduledTasksController (Jellyfin.Api).
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of authorization filters (in the following order): ["None"]
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of resource filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter"]
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of action filters (in the following order): ["Microsoft.AspNetCore.Mvc.ModelBinding.UnsupportedContentTypeFilter (Order: -3000)", "Microsoft.AspNetCore.Mvc.Infrastructure.ModelStateInvalidFilter (Order: -2000)"]
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of exception filters (in the following order): ["None"]
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of result filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter", "Microsoft.AspNetCore.Mvc.Infrastructure.ClientErrorResultFilter (Order: -2000)", "Microsoft.AspNetCore.Mvc.ProducesAttribute (Order: 0)"]
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executing controller factory for controller Jellyfin.Api.Controllers.ScheduledTasksController (Jellyfin.Api)
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed controller factory for controller Jellyfin.Api.Controllers.ScheduledTasksController (Jellyfin.Api)
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to bind parameter 'isHidden' of type 'System.Nullable`1[System.Boolean]' ...
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Attempting to bind parameter 'isHidden' of type 'System.Nullable`1[System.Boolean]' using the name '' in request data ...
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Could not find a value in the request with name '' for binding parameter 'isHidden' of type 'System.Nullable`1[System.Boolean]'.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Done attempting to bind parameter 'isHidden' of type 'System.Nullable`1[System.Boolean]'.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to bind parameter 'isHidden' of type 'System.Nullable`1[System.Boolean]'.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to validate the bound parameter 'isHidden' of type 'System.Nullable`1[System.Boolean]' ...
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to validate the bound parameter 'isHidden' of type 'System.Nullable`1[System.Boolean]'.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to bind parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]' ...
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Attempting to bind parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]' using the name '' in request data ...
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Could not find a value in the request with name '' for binding parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]'.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Done attempting to bind parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]'.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to bind parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]'.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to validate the bound parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]' ...
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to validate the bound parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]'.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: List of registered output formatters, in the following order: ["Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter", "Jellyfin.Server.Formatters.CamelCaseJsonProfileFormatter", "Microsoft.AspNetCore.Mvc.Formatters.HttpNoContentOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StringOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StreamOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.SystemTextJsonOutputFormatter", "Jellyfin.Server.Formatters.CssOutputFormatter", "Jellyfin.Server.Formatters.XmlOutputFormatter"]
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Attempting to select an output formatter based on Accept header '["application/json"]' and explicitly specified content types '["application/json", "application/json; profile=\"CamelCase\"", "application/json; profile=\"PascalCase\""]'. The content types in the accept header must be a subset of the explicitly set content types.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Selected output formatter 'Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter' and content type 'application/json' to write the response.
[20:49:43] [INF] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ObjectResultExecutor: Executing ObjectResult, writing value of type 'Jellyfin.Api.Controllers.ScheduledTasksController+<GetTasks>d__2'.
[20:49:43] [INF] [11] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Route matched with {action = "GetLogEntries", controller = "ActivityLog"}. Executing controller action with signature System.Threading.Tasks.Task`1[Microsoft.AspNetCore.Mvc.ActionResult`1[MediaBrowser.Model.Querying.QueryResult`1[MediaBrowser.Model.Activity.ActivityLogEntry]]] GetLogEntries(System.Nullable`1[System.Int32], System.Nullable`1[System.Int32], System.Nullable`1[System.DateTime], System.Nullable`1[System.Boolean]) on controller Jellyfin.Api.Controllers.ActivityLogController (Jellyfin.Api).
[20:49:43] [DBG] [28] Microsoft.AspNetCore.ResponseCompression.ResponseCompressionProvider: The response will be compressed with 'gzip'.
[20:49:43] [INF] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed action Jellyfin.Api.Controllers.ScheduledTasksController.GetTasks (Jellyfin.Api) in 25.4801ms
[20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of authorization filters (in the following order): ["None"]
[20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of resource filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter"]
[20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of action filters (in the following order): ["Microsoft.AspNetCore.Mvc.ModelBinding.UnsupportedContentTypeFilter (Order: -3000)", "Microsoft.AspNetCore.Mvc.Infrastructure.ModelStateInvalidFilter (Order: -2000)"]
[20:49:43] [INF] [28] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executed endpoint 'Jellyfin.Api.Controllers.ScheduledTasksController.GetTasks (Jellyfin.Api)'
[20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of exception filters (in the following order): ["None"]
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMJF7VJU78TU" completed keep alive response.
[20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of result filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter", "Microsoft.AspNetCore.Mvc.Infrastructure.ClientErrorResultFilter (Order: -2000)", "Microsoft.AspNetCore.Mvc.ProducesAttribute (Order: 0)"]
[20:49:43] [DBG] [8] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path /LiveTv/Recordings does not match a supported file type
[20:49:43] [DBG] [8] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executing controller factory for controller Jellyfin.Api.Controllers.ActivityLogController (Jellyfin.Api)
[20:49:43] [DBG] [23] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMJF7VJU78U0" completed keep alive response.
[20:49:43] [INF] [23] Microsoft.AspNetCore.Hosting.Diagnostics: Request finished HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2022-07-25T19%3A49%3A43.019Z&hasUserId=true - - - 200 - application/json;+charset=utf-8 65.2086ms
[20:49:43] [DBG] [11] Microsoft.AspNetCore.ResponseCompression.ResponseCompressionProvider: The response will be compressed with 'gzip'.
[20:49:43] [INF] [11] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed action Jellyfin.Api.Controllers.ActivityLogController.GetLogEntries (Jellyfin.Api) in 25.6505ms
[20:49:43] [INF] [11] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executed endpoint 'Jellyfin.Api.Controllers.ActivityLogController.GetLogEntries (Jellyfin.Api)'
[20:49:43] [DBG] [11] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMJF7VJU78TV" completed keep alive response.
[20:49:43] [INF] [11] Microsoft.AspNetCore.Hosting.Diagnostics: Request finished HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2022-07-19T19%3A49%3A43.020Z&hasUserId=false - - - 200 - application/json;+charset=utf-8 64.6243ms
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to bind parameter 'controllableByUserId' of type 'System.Nullable`1[System.Guid]' ...
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Attempting to bind parameter 'controllableByUserId' of type 'System.Nullable`1[System.Guid]' using the name '' in request data ...
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Could not find a value in the request with name '' for binding parameter 'controllableByUserId' of type 'System.Nullable`1[System.Guid]'.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Done attempting to bind parameter 'controllableByUserId' of type 'System.Nullable`1[System.Guid]'.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to bind parameter 'controllableByUserId' of type 'System.Nullable`1[System.Guid]'.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to validate the bound parameter 'controllableByUserId' of type 'System.Nullable`1[System.Guid]' ...
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to validate the bound parameter 'controllableByUserId' of type 'System.Nullable`1[System.Guid]'.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to bind parameter 'deviceId' of type 'System.String' ...
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Attempting to bind parameter 'deviceId' of type 'System.String' using the name '' in request data ...
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Could not find a value in the request with name '' for binding parameter 'deviceId' of type 'System.String'.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Done attempting to bind parameter 'deviceId' of type 'System.String'.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to bind parameter 'deviceId' of type 'System.String'.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to validate the bound parameter 'deviceId' of type 'System.String' ...
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to validate the bound parameter 'deviceId' of type 'System.String'.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to bind parameter 'activeWithinSeconds' of type 'System.Nullable`1[System.Int32]' ...
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Attempting to bind parameter 'activeWithinSeconds' of type 'System.Nullable`1[System.Int32]' using the name 'activeWithinSeconds' in request data ...
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Done attempting to bind parameter 'activeWithinSeconds' of type 'System.Nullable`1[System.Int32]'.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to bind parameter 'activeWithinSeconds' of type 'System.Nullable`1[System.Int32]'.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to validate the bound parameter 'activeWithinSeconds' of type 'System.Nullable`1[System.Int32]' ...
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to validate the bound parameter 'activeWithinSeconds' of type 'System.Nullable`1[System.Int32]'.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: List of registered output formatters, in the following order: ["Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter", "Jellyfin.Server.Formatters.CamelCaseJsonProfileFormatter", "Microsoft.AspNetCore.Mvc.Formatters.HttpNoContentOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StringOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StreamOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.SystemTextJsonOutputFormatter", "Jellyfin.Server.Formatters.CssOutputFormatter", "Jellyfin.Server.Formatters.XmlOutputFormatter"]
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Attempting to select an output formatter based on Accept header '["application/json"]' and explicitly specified content types '["application/json", "application/json; profile=\"CamelCase\"", "application/json; profile=\"PascalCase\""]'. The content types in the accept header must be a subset of the explicitly set content types.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Selected output formatter 'Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter' and content type 'application/json' to write the response.
[20:49:43] [INF] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ObjectResultExecutor: Executing OkObjectResult, writing value of type 'System.Linq.OrderedEnumerable`2[[MediaBrowser.Controller.Session.SessionInfo, MediaBrowser.Controller, Version=10.8.1.0, Culture=neutral, PublicKeyToken=null],[System.DateTime, System.Private.CoreLib, Version=6.0.0.0, Culture=neutral, PublicKeyToken=7cec85d7bea7798e]]'.
[20:49:43] [DBG] [28] Microsoft.AspNetCore.ResponseCompression.ResponseCompressionProvider: The response will be compressed with 'gzip'.
[20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Attempting to bind parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]' using the name 'isEnabled' in request data ...
[20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Done attempting to bind parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]'.
[20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to bind parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]'.
[20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to validate the bound parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]' ...
[20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to validate the bound parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]'.
[20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: List of registered output formatters, in the following order: ["Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter", "Jellyfin.Server.Formatters.CamelCaseJsonProfileFormatter", "Microsoft.AspNetCore.Mvc.Formatters.HttpNoContentOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StringOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StreamOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.SystemTextJsonOutputFormatter", "Jellyfin.Server.Formatters.CssOutputFormatter", "Jellyfin.Server.Formatters.XmlOutputFormatter"]
[20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Attempting to select an output formatter based on Accept header '["application/json"]' and explicitly specified content types '["application/json", "application/json; profile=\"CamelCase\"", "application/json; profile=\"PascalCase\""]'. The content types in the accept header must be a subset of the explicitly set content types.
[20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Selected output formatter 'Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter' and content type 'application/json' to write the response.
[20:49:43] [INF] [11] Microsoft.AspNetCore.Mvc.Infrastructure.ObjectResultExecutor: Executing ObjectResult, writing value of type 'Jellyfin.Api.Controllers.ScheduledTasksController+<GetTasks>d__2'.
[20:49:43] [DBG] [11] Microsoft.AspNetCore.ResponseCompression.ResponseCompressionProvider: The response will be compressed with 'gzip'.
[20:49:43] [INF] [8] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed action Jellyfin.Api.Controllers.LiveTvController.GetRecordings (Jellyfin.Api) in 25.1931ms
[20:49:43] [INF] [8] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executed endpoint 'Jellyfin.Api.Controllers.LiveTvController.GetRecordings (Jellyfin.Api)'
[20:49:43] [DBG] [8] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMJF7VJU78U1" completed keep alive response.
[20:49:43] [INF] [8] Microsoft.AspNetCore.Hosting.Diagnostics: Request finished HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/LiveTv/Recordings?UserId=b68358d0aa4e4943a4e294fc4ec8adcc&IsInProgress=true&Fields=CanDelete%2CPrimaryImageAspectRatio&EnableTotalRecordCount=false&EnableImageTypes=Primary%2CThumb%2CBackdrop - - - 200 - application/json;+charset=utf-8 82.0889ms
[20:49:43] [INF] [11] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed action Jellyfin.Api.Controllers.ScheduledTasksController.GetTasks (Jellyfin.Api) in 9.5458ms
[20:49:43] [INF] [11] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executed endpoint 'Jellyfin.Api.Controllers.ScheduledTasksController.GetTasks (Jellyfin.Api)'
[20:49:43] [DBG] [11] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMJF7VJU78TU" completed keep alive response.
[20:49:43] [INF] [11] Microsoft.AspNetCore.Hosting.Diagnostics: Request finished HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/ScheduledTasks?IsEnabled=true - - - 200 - application/json;+charset=utf-8 16.7945ms
[20:49:44] [DBG] [11] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets.
[20:49:47] [DBG] [21] Jellyfin.Networking.Manager.NetworkManager: GetBindInterface: Source: False, External: False:
[20:49:47] [DBG] [21] Jellyfin.Networking.Manager.NetworkManager: media-laptop.lan: GetBindInterface: Matched first internal interface. 127.0.0.1
[20:49:47] [DBG] [21] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: List of registered output formatters, in the following order: ["Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter", "Jellyfin.Server.Formatters.CamelCaseJsonProfileFormatter", "Microsoft.AspNetCore.Mvc.Formatters.HttpNoContentOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StringOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StreamOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.SystemTextJsonOutputFormatter", "Jellyfin.Server.Formatters.CssOutputFormatter", "Jellyfin.Server.Formatters.XmlOutputFormatter"]
[20:49:47] [DBG] [21] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Attempting to select an output formatter based on Accept header '["application/json"]' and explicitly specified content types '["application/json", "application/json; profile=\"CamelCase\"", "application/json; profile=\"PascalCase\""]'. The content types in the accept header must be a subset of the explicitly set content types.
[20:49:47] [DBG] [21] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Selected output formatter 'Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter' and content type 'application/json' to write the response.
[20:49:47] [INF] [21] Microsoft.AspNetCore.Mvc.Infrastructure.ObjectResultExecutor: Executing ObjectResult, writing value of type 'MediaBrowser.Model.System.SystemInfo'.
[20:49:47] [DBG] [21] Microsoft.AspNetCore.ResponseCompression.ResponseCompressionProvider: The response will be compressed with 'gzip'.
[20:49:47] [WRN] [21] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from http://media-laptop.lan:9004/jellyfin-laptop/System/Info to 192.168.0.5 in 0:00:04.0407792 with Status Code 200
[20:49:47] [INF] [21] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed action Jellyfin.Api.Controllers.SystemController.GetSystemInfo (Jellyfin.Api) in 4029.4762ms
[20:49:47] [INF] [21] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executed endpoint 'Jellyfin.Api.Controllers.SystemController.GetSystemInfo (Jellyfin.Api)'
[20:49:47] [DBG] [21] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMJF7VJU78TS" completed keep alive response.
[20:49:47] [INF] [21] Microsoft.AspNetCore.Hosting.Diagnostics: Request finished HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/System/Info - - - 200 - application/json;+charset=utf-8 4043.0172ms
[20:49:50] [DBG] [28] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS 192.168.0.5 received message: {"MessageType": "KeepAlive", "MessageId": "00000000-0000-0000-0000-000000000000", "ServerId":

FFmpeg logs

No response

Please attach any browser or client logs here

Requesting http://media-laptop.lan:9004/jellyfin-laptop/Sessions?ActiveWithinSeconds=960
main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Requesting http://media-laptop.lan:9004/jellyfin-laptop/ScheduledTasks
main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Sending web socket message: SessionsStart
main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Sending web socket message: ScheduledTasksInfoStart
main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Requesting http://media-laptop.lan:9004/jellyfin-laptop/System/Info
main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Requesting http://media-laptop.lan:9004/jellyfin-laptop/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2022-07-25T20%3A28%3A45.311Z&hasUserId=true
main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Sending web socket message: ActivityLogEntryStart
main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Requesting http://media-laptop.lan:9004/jellyfin-laptop/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2022-07-19T20%3A28%3A45.312Z&hasUserId=false
main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Sending web socket message: ActivityLogEntryStart
main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Requesting http://media-laptop.lan:9004/jellyfin-laptop/LiveTv/Recordings?UserId=b68358d0aa4e4943a4e294fc4ec8adcc&IsInProgress=true&Fields=CanDelete%2CPrimaryImageAspectRatio&EnableTotalRecordCount=false&EnableImageTypes=Primary%2CThumb%2CBackdrop
main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Requesting http://media-laptop.lan:9004/jellyfin-laptop/ScheduledTasks?IsEnabled=true
main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Sending web socket message: ScheduledTasksInfoStart

Please attach any screenshots here

No response

Code of Conduct

  • I agree to follow this project's Code of Conduct
Originally created by @ragebflame on GitHub (Jul 26, 2022). ### Please describe your bug When initially accessing Jellyfin via the Browser or Android app, it is very slow to load any content. It is also very slow for some of the Dashboard components to be populated. The Jellyfin logs below are from navigating to the Dashboard page. The server Info is very slow to load. _Server: Jellyfin-laptop Version: 10.8.1 Operating System: Linux Architecture: X64_ From the Debug level logs I can see that the request is taking 4 seconds to complete: `[20:49:47] [INF] [21] Microsoft.AspNetCore.Hosting.Diagnostics: Request finished HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/System/Info - - - 200 - application/json;+charset=utf-8 4043.0172ms` Browsing media and playback is all working without issue. This has only started occurring since upgrading to 10.8.0. Steps to reproduce: 1. Visit the Dashboard via a browser / Android app 2. Wait for the information to be displayed ### Jellyfin Version Other ### if other: 10.8.1 ### Environment ```markdown - OS: Ubuntu 20.04.4 LTS x86_64 (Host) - Virtualization: Docker - Clients: Browser - Browser: Chrome 103.0.5060.134 - Hardware Acceleration: - Plugins: none - Reverse Proxy: none - Base URL: /jellyfin-laptop - Networking: Bridge - Storage: Local ``` ### Jellyfin logs ```shell [20:49:43] [INF] [21] Microsoft.AspNetCore.Hosting.Diagnostics: Request starting HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/System/Info - - [20:49:43] [INF] [28] Microsoft.AspNetCore.Hosting.Diagnostics: Request starting HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/ScheduledTasks - - [20:49:43] [DBG] [21] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path /System/Info does not match a supported file type [20:49:43] [DBG] [28] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path /ScheduledTasks does not match a supported file type [20:49:43] [DBG] [21] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter [20:49:43] [DBG] [28] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter [20:49:43] [INF] [23] Microsoft.AspNetCore.Hosting.Diagnostics: Request starting HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2022-07-25T19%3A49%3A43.019Z&hasUserId=true - - [20:49:43] [DBG] [23] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path /System/ActivityLog/Entries does not match a supported file type [20:49:43] [DBG] [23] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter [20:49:43] [DBG] [28] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was successfully authenticated. [20:49:43] [INF] [11] Microsoft.AspNetCore.Hosting.Diagnostics: Request starting HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2022-07-19T19%3A49%3A43.020Z&hasUserId=false - - [20:49:43] [DBG] [28] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter [20:49:43] [DBG] [11] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path /System/ActivityLog/Entries does not match a supported file type [20:49:43] [DBG] [28] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter [20:49:43] [DBG] [11] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter [20:49:43] [DBG] [21] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was successfully authenticated. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: 1 candidate(s) found for the request path '/ScheduledTasks' [20:49:43] [DBG] [28] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: Endpoint 'Jellyfin.Api.Controllers.ScheduledTasksController.GetTasks (Jellyfin.Api)' with route pattern 'ScheduledTasks' is valid for the request path '/ScheduledTasks' [20:49:43] [DBG] [28] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware: Request matched endpoint 'Jellyfin.Api.Controllers.ScheduledTasksController.GetTasks (Jellyfin.Api)' [20:49:43] [DBG] [28] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was successfully authenticated. [20:49:43] [DBG] [23] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was successfully authenticated. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Authorization.DefaultAuthorizationService: Authorization was successful. [20:49:43] [DBG] [23] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter [20:49:43] [DBG] [23] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter [20:49:43] [DBG] [23] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: 1 candidate(s) found for the request path '/System/ActivityLog/Entries' [20:49:43] [DBG] [23] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: Endpoint 'Jellyfin.Api.Controllers.ActivityLogController.GetLogEntries (Jellyfin.Api)' with route pattern 'System/ActivityLog/Entries' is valid for the request path '/System/ActivityLog/Entries' [20:49:43] [DBG] [23] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware: Request matched endpoint 'Jellyfin.Api.Controllers.ActivityLogController.GetLogEntries (Jellyfin.Api)' [20:49:43] [DBG] [23] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was successfully authenticated. [20:49:43] [DBG] [11] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was successfully authenticated. [20:49:43] [DBG] [11] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter [20:49:43] [DBG] [11] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter [20:49:43] [DBG] [11] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: 1 candidate(s) found for the request path '/System/ActivityLog/Entries' [20:49:43] [DBG] [11] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: Endpoint 'Jellyfin.Api.Controllers.ActivityLogController.GetLogEntries (Jellyfin.Api)' with route pattern 'System/ActivityLog/Entries' is valid for the request path '/System/ActivityLog/Entries' [20:49:43] [DBG] [11] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware: Request matched endpoint 'Jellyfin.Api.Controllers.ActivityLogController.GetLogEntries (Jellyfin.Api)' [20:49:43] [DBG] [23] Microsoft.AspNetCore.Authorization.DefaultAuthorizationService: Authorization was successful. [20:49:43] [DBG] [11] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was successfully authenticated. [20:49:43] [INF] [23] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executing endpoint 'Jellyfin.Api.Controllers.ActivityLogController.GetLogEntries (Jellyfin.Api)' [20:49:43] [DBG] [11] Microsoft.AspNetCore.Authorization.DefaultAuthorizationService: Authorization was successful. [20:49:43] [INF] [11] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executing endpoint 'Jellyfin.Api.Controllers.ActivityLogController.GetLogEntries (Jellyfin.Api)' [20:49:43] [INF] [23] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Route matched with {action = "GetLogEntries", controller = "ActivityLog"}. Executing controller action with signature System.Threading.Tasks.Task`1[Microsoft.AspNetCore.Mvc.ActionResult`1[MediaBrowser.Model.Querying.QueryResult`1[MediaBrowser.Model.Activity.ActivityLogEntry]]] GetLogEntries(System.Nullable`1[System.Int32], System.Nullable`1[System.Int32], System.Nullable`1[System.DateTime], System.Nullable`1[System.Boolean]) on controller Jellyfin.Api.Controllers.ActivityLogController (Jellyfin.Api). [20:49:43] [INF] [8] Microsoft.AspNetCore.Hosting.Diagnostics: Request starting HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/LiveTv/Recordings?UserId=b68358d0aa4e4943a4e294fc4ec8adcc&IsInProgress=true&Fields=CanDelete%2CPrimaryImageAspectRatio&EnableTotalRecordCount=false&EnableImageTypes=Primary%2CThumb%2CBackdrop - - [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of authorization filters (in the following order): ["None"] [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of resource filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter"] [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of action filters (in the following order): ["Microsoft.AspNetCore.Mvc.ModelBinding.UnsupportedContentTypeFilter (Order: -3000)", "Microsoft.AspNetCore.Mvc.Infrastructure.ModelStateInvalidFilter (Order: -2000)"] [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of exception filters (in the following order): ["None"] [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of result filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter", "Microsoft.AspNetCore.Mvc.Infrastructure.ClientErrorResultFilter (Order: -2000)", "Microsoft.AspNetCore.Mvc.ProducesAttribute (Order: 0)"] [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executing controller factory for controller Jellyfin.Api.Controllers.ActivityLogController (Jellyfin.Api) [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed controller factory for controller Jellyfin.Api.Controllers.ActivityLogController (Jellyfin.Api) [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to bind parameter 'startIndex' of type 'System.Nullable`1[System.Int32]' ... [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Attempting to bind parameter 'startIndex' of type 'System.Nullable`1[System.Int32]' using the name 'startIndex' in request data ... [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Done attempting to bind parameter 'startIndex' of type 'System.Nullable`1[System.Int32]'. [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to bind parameter 'startIndex' of type 'System.Nullable`1[System.Int32]'. [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to validate the bound parameter 'startIndex' of type 'System.Nullable`1[System.Int32]' ... [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to validate the bound parameter 'startIndex' of type 'System.Nullable`1[System.Int32]'. [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to bind parameter 'limit' of type 'System.Nullable`1[System.Int32]' ... [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Attempting to bind parameter 'limit' of type 'System.Nullable`1[System.Int32]' using the name 'limit' in request data ... [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Done attempting to bind parameter 'limit' of type 'System.Nullable`1[System.Int32]'. [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to bind parameter 'limit' of type 'System.Nullable`1[System.Int32]'. [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to validate the bound parameter 'limit' of type 'System.Nullable`1[System.Int32]' ... [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to validate the bound parameter 'limit' of type 'System.Nullable`1[System.Int32]'. [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to bind parameter 'minDate' of type 'System.Nullable`1[System.DateTime]' ... [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.DateTimeModelBinder: Attempting to bind parameter 'minDate' of type 'System.Nullable`1[System.DateTime]' using the name 'minDate' in request data ... [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.DateTimeModelBinder: Done attempting to bind parameter 'minDate' of type 'System.Nullable`1[System.DateTime]'. [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to bind parameter 'minDate' of type 'System.Nullable`1[System.DateTime]'. [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to validate the bound parameter 'minDate' of type 'System.Nullable`1[System.DateTime]' ... [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to validate the bound parameter 'minDate' of type 'System.Nullable`1[System.DateTime]'. [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to bind parameter 'hasUserId' of type 'System.Nullable`1[System.Boolean]' ... [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Attempting to bind parameter 'hasUserId' of type 'System.Nullable`1[System.Boolean]' using the name 'hasUserId' in request data ... [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Done attempting to bind parameter 'hasUserId' of type 'System.Nullable`1[System.Boolean]'. [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to bind parameter 'hasUserId' of type 'System.Nullable`1[System.Boolean]'. [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to validate the bound parameter 'hasUserId' of type 'System.Nullable`1[System.Boolean]' ... [20:49:43] [DBG] [23] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to validate the bound parameter 'hasUserId' of type 'System.Nullable`1[System.Boolean]'. [20:49:43] [DBG] [21] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter [20:49:43] [DBG] [21] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter [20:49:43] [DBG] [21] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: 1 candidate(s) found for the request path '/System/Info' [20:49:43] [DBG] [21] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: Endpoint 'Jellyfin.Api.Controllers.SystemController.GetSystemInfo (Jellyfin.Api)' with route pattern 'System/Info' is valid for the request path '/System/Info' [20:49:43] [DBG] [21] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware: Request matched endpoint 'Jellyfin.Api.Controllers.SystemController.GetSystemInfo (Jellyfin.Api)' [20:49:43] [DBG] [21] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was successfully authenticated. [20:49:43] [DBG] [21] Microsoft.AspNetCore.Authorization.DefaultAuthorizationService: Authorization was successful. [20:49:43] [INF] [21] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executing endpoint 'Jellyfin.Api.Controllers.SystemController.GetSystemInfo (Jellyfin.Api)' [20:49:43] [INF] [21] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Route matched with {action = "GetSystemInfo", controller = "System"}. Executing controller action with signature Microsoft.AspNetCore.Mvc.ActionResult`1[MediaBrowser.Model.System.SystemInfo] GetSystemInfo() on controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api). [20:49:43] [DBG] [21] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of authorization filters (in the following order): ["None"] [20:49:43] [DBG] [21] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of resource filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter"] [20:49:43] [DBG] [21] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of action filters (in the following order): ["Microsoft.AspNetCore.Mvc.ModelBinding.UnsupportedContentTypeFilter (Order: -3000)", "Microsoft.AspNetCore.Mvc.Infrastructure.ModelStateInvalidFilter (Order: -2000)"] [20:49:43] [DBG] [21] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of exception filters (in the following order): ["None"] [20:49:43] [DBG] [21] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of result filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter", "Microsoft.AspNetCore.Mvc.Infrastructure.ClientErrorResultFilter (Order: -2000)", "Microsoft.AspNetCore.Mvc.ProducesAttribute (Order: 0)"] [20:49:43] [DBG] [21] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executing controller factory for controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api) [20:49:43] [DBG] [21] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed controller factory for controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api) [20:49:43] [INF] [28] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executing endpoint 'Jellyfin.Api.Controllers.ScheduledTasksController.GetTasks (Jellyfin.Api)' [20:49:43] [INF] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Route matched with {action = "GetTasks", controller = "ScheduledTasks"}. Executing controller action with signature System.Collections.Generic.IEnumerable`1[MediaBrowser.Model.Tasks.TaskInfo] GetTasks(System.Nullable`1[System.Boolean], System.Nullable`1[System.Boolean]) on controller Jellyfin.Api.Controllers.ScheduledTasksController (Jellyfin.Api). [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of authorization filters (in the following order): ["None"] [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of resource filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter"] [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of action filters (in the following order): ["Microsoft.AspNetCore.Mvc.ModelBinding.UnsupportedContentTypeFilter (Order: -3000)", "Microsoft.AspNetCore.Mvc.Infrastructure.ModelStateInvalidFilter (Order: -2000)"] [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of exception filters (in the following order): ["None"] [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of result filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter", "Microsoft.AspNetCore.Mvc.Infrastructure.ClientErrorResultFilter (Order: -2000)", "Microsoft.AspNetCore.Mvc.ProducesAttribute (Order: 0)"] [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executing controller factory for controller Jellyfin.Api.Controllers.ScheduledTasksController (Jellyfin.Api) [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed controller factory for controller Jellyfin.Api.Controllers.ScheduledTasksController (Jellyfin.Api) [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to bind parameter 'isHidden' of type 'System.Nullable`1[System.Boolean]' ... [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Attempting to bind parameter 'isHidden' of type 'System.Nullable`1[System.Boolean]' using the name '' in request data ... [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Could not find a value in the request with name '' for binding parameter 'isHidden' of type 'System.Nullable`1[System.Boolean]'. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Done attempting to bind parameter 'isHidden' of type 'System.Nullable`1[System.Boolean]'. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to bind parameter 'isHidden' of type 'System.Nullable`1[System.Boolean]'. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to validate the bound parameter 'isHidden' of type 'System.Nullable`1[System.Boolean]' ... [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to validate the bound parameter 'isHidden' of type 'System.Nullable`1[System.Boolean]'. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to bind parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]' ... [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Attempting to bind parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]' using the name '' in request data ... [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Could not find a value in the request with name '' for binding parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]'. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Done attempting to bind parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]'. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to bind parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]'. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to validate the bound parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]' ... [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to validate the bound parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]'. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: List of registered output formatters, in the following order: ["Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter", "Jellyfin.Server.Formatters.CamelCaseJsonProfileFormatter", "Microsoft.AspNetCore.Mvc.Formatters.HttpNoContentOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StringOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StreamOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.SystemTextJsonOutputFormatter", "Jellyfin.Server.Formatters.CssOutputFormatter", "Jellyfin.Server.Formatters.XmlOutputFormatter"] [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Attempting to select an output formatter based on Accept header '["application/json"]' and explicitly specified content types '["application/json", "application/json; profile=\"CamelCase\"", "application/json; profile=\"PascalCase\""]'. The content types in the accept header must be a subset of the explicitly set content types. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Selected output formatter 'Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter' and content type 'application/json' to write the response. [20:49:43] [INF] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ObjectResultExecutor: Executing ObjectResult, writing value of type 'Jellyfin.Api.Controllers.ScheduledTasksController+<GetTasks>d__2'. [20:49:43] [INF] [11] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Route matched with {action = "GetLogEntries", controller = "ActivityLog"}. Executing controller action with signature System.Threading.Tasks.Task`1[Microsoft.AspNetCore.Mvc.ActionResult`1[MediaBrowser.Model.Querying.QueryResult`1[MediaBrowser.Model.Activity.ActivityLogEntry]]] GetLogEntries(System.Nullable`1[System.Int32], System.Nullable`1[System.Int32], System.Nullable`1[System.DateTime], System.Nullable`1[System.Boolean]) on controller Jellyfin.Api.Controllers.ActivityLogController (Jellyfin.Api). [20:49:43] [DBG] [28] Microsoft.AspNetCore.ResponseCompression.ResponseCompressionProvider: The response will be compressed with 'gzip'. [20:49:43] [INF] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed action Jellyfin.Api.Controllers.ScheduledTasksController.GetTasks (Jellyfin.Api) in 25.4801ms [20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of authorization filters (in the following order): ["None"] [20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of resource filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter"] [20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of action filters (in the following order): ["Microsoft.AspNetCore.Mvc.ModelBinding.UnsupportedContentTypeFilter (Order: -3000)", "Microsoft.AspNetCore.Mvc.Infrastructure.ModelStateInvalidFilter (Order: -2000)"] [20:49:43] [INF] [28] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executed endpoint 'Jellyfin.Api.Controllers.ScheduledTasksController.GetTasks (Jellyfin.Api)' [20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of exception filters (in the following order): ["None"] [20:49:43] [DBG] [28] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMJF7VJU78TU" completed keep alive response. [20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of result filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter", "Microsoft.AspNetCore.Mvc.Infrastructure.ClientErrorResultFilter (Order: -2000)", "Microsoft.AspNetCore.Mvc.ProducesAttribute (Order: 0)"] [20:49:43] [DBG] [8] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path /LiveTv/Recordings does not match a supported file type [20:49:43] [DBG] [8] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter [20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executing controller factory for controller Jellyfin.Api.Controllers.ActivityLogController (Jellyfin.Api) [20:49:43] [DBG] [23] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMJF7VJU78U0" completed keep alive response. [20:49:43] [INF] [23] Microsoft.AspNetCore.Hosting.Diagnostics: Request finished HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2022-07-25T19%3A49%3A43.019Z&hasUserId=true - - - 200 - application/json;+charset=utf-8 65.2086ms [20:49:43] [DBG] [11] Microsoft.AspNetCore.ResponseCompression.ResponseCompressionProvider: The response will be compressed with 'gzip'. [20:49:43] [INF] [11] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed action Jellyfin.Api.Controllers.ActivityLogController.GetLogEntries (Jellyfin.Api) in 25.6505ms [20:49:43] [INF] [11] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executed endpoint 'Jellyfin.Api.Controllers.ActivityLogController.GetLogEntries (Jellyfin.Api)' [20:49:43] [DBG] [11] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMJF7VJU78TV" completed keep alive response. [20:49:43] [INF] [11] Microsoft.AspNetCore.Hosting.Diagnostics: Request finished HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2022-07-19T19%3A49%3A43.020Z&hasUserId=false - - - 200 - application/json;+charset=utf-8 64.6243ms [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to bind parameter 'controllableByUserId' of type 'System.Nullable`1[System.Guid]' ... [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Attempting to bind parameter 'controllableByUserId' of type 'System.Nullable`1[System.Guid]' using the name '' in request data ... [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Could not find a value in the request with name '' for binding parameter 'controllableByUserId' of type 'System.Nullable`1[System.Guid]'. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Done attempting to bind parameter 'controllableByUserId' of type 'System.Nullable`1[System.Guid]'. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to bind parameter 'controllableByUserId' of type 'System.Nullable`1[System.Guid]'. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to validate the bound parameter 'controllableByUserId' of type 'System.Nullable`1[System.Guid]' ... [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to validate the bound parameter 'controllableByUserId' of type 'System.Nullable`1[System.Guid]'. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to bind parameter 'deviceId' of type 'System.String' ... [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Attempting to bind parameter 'deviceId' of type 'System.String' using the name '' in request data ... [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Could not find a value in the request with name '' for binding parameter 'deviceId' of type 'System.String'. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Done attempting to bind parameter 'deviceId' of type 'System.String'. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to bind parameter 'deviceId' of type 'System.String'. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to validate the bound parameter 'deviceId' of type 'System.String' ... [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to validate the bound parameter 'deviceId' of type 'System.String'. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to bind parameter 'activeWithinSeconds' of type 'System.Nullable`1[System.Int32]' ... [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Attempting to bind parameter 'activeWithinSeconds' of type 'System.Nullable`1[System.Int32]' using the name 'activeWithinSeconds' in request data ... [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Done attempting to bind parameter 'activeWithinSeconds' of type 'System.Nullable`1[System.Int32]'. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to bind parameter 'activeWithinSeconds' of type 'System.Nullable`1[System.Int32]'. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to validate the bound parameter 'activeWithinSeconds' of type 'System.Nullable`1[System.Int32]' ... [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to validate the bound parameter 'activeWithinSeconds' of type 'System.Nullable`1[System.Int32]'. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: List of registered output formatters, in the following order: ["Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter", "Jellyfin.Server.Formatters.CamelCaseJsonProfileFormatter", "Microsoft.AspNetCore.Mvc.Formatters.HttpNoContentOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StringOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StreamOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.SystemTextJsonOutputFormatter", "Jellyfin.Server.Formatters.CssOutputFormatter", "Jellyfin.Server.Formatters.XmlOutputFormatter"] [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Attempting to select an output formatter based on Accept header '["application/json"]' and explicitly specified content types '["application/json", "application/json; profile=\"CamelCase\"", "application/json; profile=\"PascalCase\""]'. The content types in the accept header must be a subset of the explicitly set content types. [20:49:43] [DBG] [28] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Selected output formatter 'Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter' and content type 'application/json' to write the response. [20:49:43] [INF] [28] Microsoft.AspNetCore.Mvc.Infrastructure.ObjectResultExecutor: Executing OkObjectResult, writing value of type 'System.Linq.OrderedEnumerable`2[[MediaBrowser.Controller.Session.SessionInfo, MediaBrowser.Controller, Version=10.8.1.0, Culture=neutral, PublicKeyToken=null],[System.DateTime, System.Private.CoreLib, Version=6.0.0.0, Culture=neutral, PublicKeyToken=7cec85d7bea7798e]]'. [20:49:43] [DBG] [28] Microsoft.AspNetCore.ResponseCompression.ResponseCompressionProvider: The response will be compressed with 'gzip'. [20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Attempting to bind parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]' using the name 'isEnabled' in request data ... [20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.ModelBinding.Binders.SimpleTypeModelBinder: Done attempting to bind parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]'. [20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to bind parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]'. [20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Attempting to validate the bound parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]' ... [20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.ModelBinding.ParameterBinder: Done attempting to validate the bound parameter 'isEnabled' of type 'System.Nullable`1[System.Boolean]'. [20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: List of registered output formatters, in the following order: ["Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter", "Jellyfin.Server.Formatters.CamelCaseJsonProfileFormatter", "Microsoft.AspNetCore.Mvc.Formatters.HttpNoContentOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StringOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StreamOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.SystemTextJsonOutputFormatter", "Jellyfin.Server.Formatters.CssOutputFormatter", "Jellyfin.Server.Formatters.XmlOutputFormatter"] [20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Attempting to select an output formatter based on Accept header '["application/json"]' and explicitly specified content types '["application/json", "application/json; profile=\"CamelCase\"", "application/json; profile=\"PascalCase\""]'. The content types in the accept header must be a subset of the explicitly set content types. [20:49:43] [DBG] [11] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Selected output formatter 'Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter' and content type 'application/json' to write the response. [20:49:43] [INF] [11] Microsoft.AspNetCore.Mvc.Infrastructure.ObjectResultExecutor: Executing ObjectResult, writing value of type 'Jellyfin.Api.Controllers.ScheduledTasksController+<GetTasks>d__2'. [20:49:43] [DBG] [11] Microsoft.AspNetCore.ResponseCompression.ResponseCompressionProvider: The response will be compressed with 'gzip'. [20:49:43] [INF] [8] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed action Jellyfin.Api.Controllers.LiveTvController.GetRecordings (Jellyfin.Api) in 25.1931ms [20:49:43] [INF] [8] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executed endpoint 'Jellyfin.Api.Controllers.LiveTvController.GetRecordings (Jellyfin.Api)' [20:49:43] [DBG] [8] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMJF7VJU78U1" completed keep alive response. [20:49:43] [INF] [8] Microsoft.AspNetCore.Hosting.Diagnostics: Request finished HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/LiveTv/Recordings?UserId=b68358d0aa4e4943a4e294fc4ec8adcc&IsInProgress=true&Fields=CanDelete%2CPrimaryImageAspectRatio&EnableTotalRecordCount=false&EnableImageTypes=Primary%2CThumb%2CBackdrop - - - 200 - application/json;+charset=utf-8 82.0889ms [20:49:43] [INF] [11] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed action Jellyfin.Api.Controllers.ScheduledTasksController.GetTasks (Jellyfin.Api) in 9.5458ms [20:49:43] [INF] [11] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executed endpoint 'Jellyfin.Api.Controllers.ScheduledTasksController.GetTasks (Jellyfin.Api)' [20:49:43] [DBG] [11] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMJF7VJU78TU" completed keep alive response. [20:49:43] [INF] [11] Microsoft.AspNetCore.Hosting.Diagnostics: Request finished HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/ScheduledTasks?IsEnabled=true - - - 200 - application/json;+charset=utf-8 16.7945ms [20:49:44] [DBG] [11] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [20:49:47] [DBG] [21] Jellyfin.Networking.Manager.NetworkManager: GetBindInterface: Source: False, External: False: [20:49:47] [DBG] [21] Jellyfin.Networking.Manager.NetworkManager: media-laptop.lan: GetBindInterface: Matched first internal interface. 127.0.0.1 [20:49:47] [DBG] [21] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: List of registered output formatters, in the following order: ["Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter", "Jellyfin.Server.Formatters.CamelCaseJsonProfileFormatter", "Microsoft.AspNetCore.Mvc.Formatters.HttpNoContentOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StringOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StreamOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.SystemTextJsonOutputFormatter", "Jellyfin.Server.Formatters.CssOutputFormatter", "Jellyfin.Server.Formatters.XmlOutputFormatter"] [20:49:47] [DBG] [21] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Attempting to select an output formatter based on Accept header '["application/json"]' and explicitly specified content types '["application/json", "application/json; profile=\"CamelCase\"", "application/json; profile=\"PascalCase\""]'. The content types in the accept header must be a subset of the explicitly set content types. [20:49:47] [DBG] [21] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Selected output formatter 'Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter' and content type 'application/json' to write the response. [20:49:47] [INF] [21] Microsoft.AspNetCore.Mvc.Infrastructure.ObjectResultExecutor: Executing ObjectResult, writing value of type 'MediaBrowser.Model.System.SystemInfo'. [20:49:47] [DBG] [21] Microsoft.AspNetCore.ResponseCompression.ResponseCompressionProvider: The response will be compressed with 'gzip'. [20:49:47] [WRN] [21] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from http://media-laptop.lan:9004/jellyfin-laptop/System/Info to 192.168.0.5 in 0:00:04.0407792 with Status Code 200 [20:49:47] [INF] [21] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed action Jellyfin.Api.Controllers.SystemController.GetSystemInfo (Jellyfin.Api) in 4029.4762ms [20:49:47] [INF] [21] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executed endpoint 'Jellyfin.Api.Controllers.SystemController.GetSystemInfo (Jellyfin.Api)' [20:49:47] [DBG] [21] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMJF7VJU78TS" completed keep alive response. [20:49:47] [INF] [21] Microsoft.AspNetCore.Hosting.Diagnostics: Request finished HTTP/1.1 GET http://media-laptop.lan:9004/jellyfin-laptop/System/Info - - - 200 - application/json;+charset=utf-8 4043.0172ms [20:49:50] [DBG] [28] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS 192.168.0.5 received message: {"MessageType": "KeepAlive", "MessageId": "00000000-0000-0000-0000-000000000000", "ServerId": ``` ### FFmpeg logs _No response_ ### Please attach any browser or client logs here Requesting http://media-laptop.lan:9004/jellyfin-laptop/Sessions?ActiveWithinSeconds=960 main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Requesting http://media-laptop.lan:9004/jellyfin-laptop/ScheduledTasks main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Sending web socket message: SessionsStart main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Sending web socket message: ScheduledTasksInfoStart main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Requesting http://media-laptop.lan:9004/jellyfin-laptop/System/Info main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Requesting http://media-laptop.lan:9004/jellyfin-laptop/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2022-07-25T20%3A28%3A45.311Z&hasUserId=true main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Sending web socket message: ActivityLogEntryStart main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Requesting http://media-laptop.lan:9004/jellyfin-laptop/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2022-07-19T20%3A28%3A45.312Z&hasUserId=false main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Sending web socket message: ActivityLogEntryStart main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Requesting http://media-laptop.lan:9004/jellyfin-laptop/LiveTv/Recordings?UserId=b68358d0aa4e4943a4e294fc4ec8adcc&IsInProgress=true&Fields=CanDelete%2CPrimaryImageAspectRatio&EnableTotalRecordCount=false&EnableImageTypes=Primary%2CThumb%2CBackdrop main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Requesting http://media-laptop.lan:9004/jellyfin-laptop/ScheduledTasks?IsEnabled=true main.jellyfin.bundle.js?709b29ddaf40620d691b:2 Sending web socket message: ScheduledTasksInfoStart ### Please attach any screenshots here _No response_ ### Code of Conduct - [X] I agree to follow this project's Code of Conduct
OVERLORD added the bug label 2026-02-07 00:21:05 +03:00
Author
Owner

@ServeurpersoCom commented on GitHub (Jul 29, 2022):

Same problem here. it run smooth for about few hours and fastly slow down especialy on the admin pannel.
Tested native install on Debian 11 without VM or Docker. i9 12900K 64GB DDR5 !
Admin dashboard kill about 25% CPU and very slow to populate. Restart the service solve the problem for few hours.

@ServeurpersoCom commented on GitHub (Jul 29, 2022): Same problem here. it run smooth for about few hours and fastly slow down especialy on the admin pannel. Tested native install on Debian 11 without VM or Docker. i9 12900K 64GB DDR5 ! Admin dashboard kill about 25% CPU and very slow to populate. Restart the service solve the problem for few hours.
Author
Owner

@ragebflame commented on GitHub (Sep 26, 2022):

Issue still occurring in 10.8.5

@ragebflame commented on GitHub (Sep 26, 2022): Issue still occurring in 10.8.5
Author
Owner

@FleyX commented on GitHub (Sep 27, 2022):

It could be avoided by useing IP and PORT ,not domain.

@FleyX commented on GitHub (Sep 27, 2022): It could be avoided by useing IP and PORT ,not domain.
Author
Owner

@Cueball666uk commented on GitHub (Sep 29, 2022):

Having the same issue. Slow very to load, but then fine whilst using. But slow again the next time.

@Cueball666uk commented on GitHub (Sep 29, 2022): Having the same issue. Slow very to load, but then fine whilst using. But slow again the next time.
Author
Owner

@r0ckinit commented on GitHub (Oct 2, 2022):

Same issue here as well

@r0ckinit commented on GitHub (Oct 2, 2022): Same issue here as well
Author
Owner

@bloominstrong commented on GitHub (Oct 3, 2022):

I have been experiencing this issue as well, to the point that the Android App will timeout trying to connect when selecting the server by entering the URL.
I have been doing some testing and it is definitely to do with the the disk i/o and name resolution when accessing http://<JELLYFIN_DOMAIN>/System/Info/Public and is only affecting versions from 10.8.0 (rolling back to 10.7.7 resolves the issue). Other pages do not appear affected (http://<JELLYFIN_DOMAIN>/health returns straight away) but I have not found a good example that would need to read from disk.

As FleyX mentioned the work around is to access the server via http://<IP_ADDRESS>/ rather than http://<YOUR_DOMAIN>/ http vs https and port mapping does not matter.

An easy way to test is to curl -v http://<JELLYFIN_DOMAIN>/System/Info/Public and look for X-Response-Time-ms:
For myself when using my domain the response is ~5 seconds and when using IP address the response is sub 5ms (10.7.7 is a similar response time for both domain name and IP address).

My disk IO is constrained due to ZFS with a high number of snapshots so if I clone the ZFS volume the issue is resolved as that resolves the disk IO, if I spin up a brand new Jellyfin instance (new default config) on the same ZFS volume the issue remains so the issue is not related to the Jellyfin config but the underlying disk IO.

So that explains the symptoms and why spinning up a new Jellyfin instance does not show this issue. As to the cause looking at the 10.8.0 commit log there appears to be a few changes about domain resolution. I have not worked in dotnet before but will work through these and test reverting them to see if I can identify the cause over the next couple of weeks as I have time.

I will also see if I can find an easy way to replicate the issue so others can assist.

@bloominstrong commented on GitHub (Oct 3, 2022): I have been experiencing this issue as well, to the point that the Android App will timeout trying to connect when selecting the server by entering the URL. I have been doing some testing and it is definitely to do with the the disk i/o and name resolution when accessing http://<JELLYFIN_DOMAIN>/System/Info/Public and is only affecting versions from 10.8.0 (rolling back to 10.7.7 resolves the issue). Other pages do not appear affected (http://<JELLYFIN_DOMAIN>/health returns straight away) but I have not found a good example that would need to read from disk. As FleyX mentioned the **work around is to access the server via http://<IP_ADDRESS>/ rather than http://<YOUR_DOMAIN>/ http vs https and port mapping does not matter**. An easy way to test is to `curl -v http://<JELLYFIN_DOMAIN>/System/Info/Public` and look for `X-Response-Time-ms: ` For myself when using my domain the response is ~5 seconds and when using IP address the response is sub 5ms (10.7.7 is a similar response time for both domain name and IP address). My disk IO is constrained due to ZFS with a high number of snapshots so if I clone the ZFS volume the issue is resolved as that resolves the disk IO, if I spin up a brand new Jellyfin instance (new default config) on the same ZFS volume the issue remains so the issue is not related to the Jellyfin config but the underlying disk IO. So that explains the symptoms and why spinning up a new Jellyfin instance does not show this issue. As to the cause looking at the 10.8.0 commit log there appears to be a few changes about domain resolution. I have not worked in dotnet before but will work through these and test reverting them to see if I can identify the cause over the next couple of weeks as I have time. I will also see if I can find an easy way to replicate the issue so others can assist.
Author
Owner

@ServeurpersoCom commented on GitHub (Oct 4, 2022):

For me the problem (since 10.8.0 and to 10.8.5 last stable) appear when there is about 10 users and when I go to admin panel. User information don't appear and server page and streams freeze for everyone for about 10 seconds and way more if I keep on the admin panel page. This is an important bug make Jellyfin unusable and make me rollback to 10.7.7

@ServeurpersoCom commented on GitHub (Oct 4, 2022): For me the problem (since 10.8.0 and to 10.8.5 last stable) appear when there is about 10 users and when I go to admin panel. User information don't appear and server page and streams freeze for everyone for about 10 seconds and way more if I keep on the admin panel page. This is an important bug make Jellyfin unusable and make me rollback to 10.7.7
Author
Owner

@bloominstrong commented on GitHub (Oct 7, 2022):

For me the problem (since 10.8.0 and to 10.8.5 last stable) appear when there is about 10 users and when I go to admin panel. User information don't appear and server page and streams freeze for everyone for about 10 seconds and way more if I keep on the admin panel page. This is an important bug make Jellyfin unusable and make me rollback to 10.7.7

Looking at your issue it does sound slightly different, as this issue seems to center around the <JELLYFIN_SERVER>/System/Info path taking a long time to return when using the domain as opposed to just the IP address.
I know you already rolled yours back but does using IP vs domain name give you a difference?

@bloominstrong commented on GitHub (Oct 7, 2022): > For me the problem (since 10.8.0 and to 10.8.5 last stable) appear when there is about 10 users and when I go to admin panel. User information don't appear and server page and streams freeze for everyone for about 10 seconds and way more if I keep on the admin panel page. This is an important bug make Jellyfin unusable and make me rollback to 10.7.7 Looking at your issue it does sound slightly different, as this issue seems to center around the <JELLYFIN_SERVER>/System/Info path taking a long time to return when using the domain as opposed to just the IP address. I know you already rolled yours back but does using IP vs domain name give you a difference?
Author
Owner

@Cueball666uk commented on GitHub (Oct 7, 2022):

Yes, it's much faster without the domain. But I would appreciate using the domain instead of my IP address. I had no such issue on 10.7.... but I also didn't notice this issue until i started using the domain, my downgrade back too 10.8.4 has not saved me from the issue either.

@Cueball666uk commented on GitHub (Oct 7, 2022): Yes, it's much faster without the domain. But I would appreciate using the domain instead of my IP address. I had no such issue on 10.7.... but I also didn't notice this issue until i started using the domain, my downgrade back too 10.8.4 has not saved me from the issue either.
Author
Owner

@ServeurpersoCom commented on GitHub (Oct 7, 2022):

For me the problem (since 10.8.0 and to 10.8.5 last stable) appear when there is about 10 users and when I go to admin panel. User information don't appear and server page and streams freeze for everyone for about 10 seconds and way more if I keep on the admin panel page. This is an important bug make Jellyfin unusable and make me rollback to 10.7.7

Looking at your issue it does sound slightly different, as this issue seems to center around the <JELLYFIN_SERVER>/System/Info path taking a long time to return when using the domain as opposed to just the IP address. I know you already rolled yours back but does using IP vs domain name give you a difference?

No difference for me. Admin dashboard (only the "who / user list") consume about 33% of an i9 12900K for few minutes. If I keep on this dashboard this freeze entire Jellyfin server, if I keep only few seconds, it consume also 33% for about 10 seconds and no freeze.

@ServeurpersoCom commented on GitHub (Oct 7, 2022): > > For me the problem (since 10.8.0 and to 10.8.5 last stable) appear when there is about 10 users and when I go to admin panel. User information don't appear and server page and streams freeze for everyone for about 10 seconds and way more if I keep on the admin panel page. This is an important bug make Jellyfin unusable and make me rollback to 10.7.7 > > Looking at your issue it does sound slightly different, as this issue seems to center around the <JELLYFIN_SERVER>/System/Info path taking a long time to return when using the domain as opposed to just the IP address. I know you already rolled yours back but does using IP vs domain name give you a difference? No difference for me. Admin dashboard (only the "who / user list") consume about 33% of an i9 12900K for few minutes. If I keep on this dashboard this freeze entire Jellyfin server, if I keep only few seconds, it consume also 33% for about 10 seconds and no freeze.
Author
Owner

@ServeurpersoCom commented on GitHub (Oct 7, 2022):

I use Apache SSL Websocket reverse proxy, jellyfin server get the same request in all case (domain or public IP)

@ServeurpersoCom commented on GitHub (Oct 7, 2022): I use Apache SSL Websocket reverse proxy, jellyfin server get the same request in all case (domain or public IP)
Author
Owner

@cvium commented on GitHub (Oct 8, 2022):

For me the problem (since 10.8.0 and to 10.8.5 last stable) appear when there is about 10 users and when I go to admin panel. User information don't appear and server page and streams freeze for everyone for about 10 seconds and way more if I keep on the admin panel page. This is an important bug make Jellyfin unusable and make me rollback to 10.7.7

Looking at your issue it does sound slightly different, as this issue seems to center around the <JELLYFIN_SERVER>/System/Info path taking a long time to return when using the domain as opposed to just the IP address. I know you already rolled yours back but does using IP vs domain name give you a difference?

No difference for me. Admin dashboard (only the "who / user list") consume about 33% of an i9 12900K for few minutes. If I keep on this dashboard this freeze entire Jellyfin server, if I keep only few seconds, it consume also 33% for about 10 seconds and no freeze.

It's not something I can reproduce. Maybe some debug logging can shed some more light on this. Alternatively, you can also send me your database files

@cvium commented on GitHub (Oct 8, 2022): > > > For me the problem (since 10.8.0 and to 10.8.5 last stable) appear when there is about 10 users and when I go to admin panel. User information don't appear and server page and streams freeze for everyone for about 10 seconds and way more if I keep on the admin panel page. This is an important bug make Jellyfin unusable and make me rollback to 10.7.7 > > > > > > Looking at your issue it does sound slightly different, as this issue seems to center around the <JELLYFIN_SERVER>/System/Info path taking a long time to return when using the domain as opposed to just the IP address. I know you already rolled yours back but does using IP vs domain name give you a difference? > > > > No difference for me. Admin dashboard (only the "who / user list") consume about 33% of an i9 12900K for few minutes. If I keep on this dashboard this freeze entire Jellyfin server, if I keep only few seconds, it consume also 33% for about 10 seconds and no freeze. > > It's not something I can reproduce. Maybe some debug logging can shed some more light on this. Alternatively, you can also send me your database files
Author
Owner

@ragebflame commented on GitHub (Oct 8, 2022):

It could be avoided by useing IP and PORT ,not domain.

This is correct. If I access my Jellyfin instance via IP & PORT rather than via the reverse proxy (linuxserver/swag) I get no unusual slowness. Sounds like the issue may not be with Jellyfin, but rather my reverse proxy setup.

@ragebflame commented on GitHub (Oct 8, 2022): > It could be avoided by useing IP and PORT ,not domain. This is correct. If I access my Jellyfin instance via IP & PORT rather than via the reverse proxy ([linuxserver/swag](https://docs.linuxserver.io/images/docker-swag)) I get no unusual slowness. Sounds like the issue may not be with Jellyfin, but rather my reverse proxy setup.
Author
Owner

@Cueball666uk commented on GitHub (Oct 8, 2022):

I'm using Jellyfin with a certificate directly added to it (for my domain) and a port forwarded on my router. No reverse proxy at all... But I'm still experiencing the slowdown via domain/port vs IP/port

@Cueball666uk commented on GitHub (Oct 8, 2022): I'm using Jellyfin with a certificate directly added to it (for my domain) and a port forwarded on my router. No reverse proxy at all... But I'm still experiencing the slowdown via domain/port vs IP/port
Author
Owner

@ragebflame commented on GitHub (Oct 8, 2022):

I'm using Jellyfin with a certificate directly added to it (for my domain) and a port forwarded on my router. No reverse proxy at all... But I'm still experiencing the slowdown via domain/port vs IP/port

Okay, so possibly still a Jellyfin issue. I've not had time to investigate things further on my end.

@ragebflame commented on GitHub (Oct 8, 2022): > I'm using Jellyfin with a certificate directly added to it (for my domain) and a port forwarded on my router. No reverse proxy at all... But I'm still experiencing the slowdown via domain/port vs IP/port Okay, so possibly still a Jellyfin issue. I've not had time to investigate things further on my end.
Author
Owner

@mrautlan commented on GitHub (Oct 10, 2022):

I'm using Jellyfin with a certificate directly added to it (for my domain) and a port forwarded on my router. No reverse proxy at all... But I'm still experiencing the slowdown via domain/port vs IP/port

I have exactly the same issue. Access via IP and port is fast, via domain and port is very slow. I'm also not using a reverse proxy. A similar issue occurs when I use the Jellyfin app (iOS) to connect to the server via domain/port - "unable to connect to the server". IP/port works fine.

@mrautlan commented on GitHub (Oct 10, 2022): > I'm using Jellyfin with a certificate directly added to it (for my domain) and a port forwarded on my router. No reverse proxy at all... But I'm still experiencing the slowdown via domain/port vs IP/port I have exactly the same issue. Access via IP and port is fast, via domain and port is very slow. I'm also not using a reverse proxy. A similar issue occurs when I use the Jellyfin app (iOS) to connect to the server via domain/port - "unable to connect to the server". IP/port works fine.
Author
Owner

@Cueball666uk commented on GitHub (Oct 11, 2022):

Yes, it's much faster without the domain. But I would appreciate using the
domain instead of my IP address. I had no such issue on 10.7....

On Fri, 7 Oct 2022, 12:16 pm bloominstrong, @.***>
wrote:

For me the problem (since 10.8.0 and to 10.8.5 last stable) appear when
there is about 10 users and when I go to admin panel. User information
don't appear and server page and streams freeze for everyone for about 10
seconds and way more if I keep on the admin panel page. This is an
important bug make Jellyfin unusable and make me rollback to 10.7.7

Looking at your issue it does sound slightly different, as this issue
seems to center around the <JELLYFIN_SERVER>/System/Info path taking a long
time to return when using the domain as opposed to just the IP address.
I know you already rolled yours back but does using IP vs domain name give
you a difference?


Reply to this email directly, view it on GitHub
https://github.com/jellyfin/jellyfin/issues/8179#issuecomment-1271459158,
or unsubscribe
https://github.com/notifications/unsubscribe-auth/AMMKZNZRRS64F6XKCDZRG4LWCABBZANCNFSM54XIAPUQ
.
You are receiving this because you are subscribed to this thread.Message
ID: @.***>

@Cueball666uk commented on GitHub (Oct 11, 2022): Yes, it's much faster without the domain. But I would appreciate using the domain instead of my IP address. I had no such issue on 10.7.... On Fri, 7 Oct 2022, 12:16 pm bloominstrong, ***@***.***> wrote: > For me the problem (since 10.8.0 and to 10.8.5 last stable) appear when > there is about 10 users and when I go to admin panel. User information > don't appear and server page and streams freeze for everyone for about 10 > seconds and way more if I keep on the admin panel page. This is an > important bug make Jellyfin unusable and make me rollback to 10.7.7 > > Looking at your issue it does sound slightly different, as this issue > seems to center around the <JELLYFIN_SERVER>/System/Info path taking a long > time to return when using the domain as opposed to just the IP address. > I know you already rolled yours back but does using IP vs domain name give > you a difference? > > — > Reply to this email directly, view it on GitHub > <https://github.com/jellyfin/jellyfin/issues/8179#issuecomment-1271459158>, > or unsubscribe > <https://github.com/notifications/unsubscribe-auth/AMMKZNZRRS64F6XKCDZRG4LWCABBZANCNFSM54XIAPUQ> > . > You are receiving this because you are subscribed to this thread.Message > ID: ***@***.***> >
Author
Owner

@cvium commented on GitHub (Oct 11, 2022):

May I suggest actually doing what I'm asking? https://github.com/jellyfin/jellyfin/issues/8179#issuecomment-1272243846

@cvium commented on GitHub (Oct 11, 2022): May I suggest actually doing what I'm asking? https://github.com/jellyfin/jellyfin/issues/8179#issuecomment-1272243846
Author
Owner

@bloominstrong commented on GitHub (Oct 12, 2022):

I have not been able to reproduce the issue by limiting IO on a fresh instance but cloning the volume that my instance runs on removes the issue so it does not seem tied to something specific in the database.

Cranking my logging up to

{
    "Serilog": {
        "MinimumLevel": {
            "Default": "Debug",
            "Override": {
                "Microsoft": "Debug",
                "System": "Debug"
            }
        },
        "WriteTo": [
            {
                "Name": "Console",
                "Args": {
                    "outputTemplate": "[{Timestamp:HH:mm:ss}] [{Level:u3}] [{ThreadId}] {SourceContext}: {Message:lj}{NewLine}{Exception}"
                }
            },
            {
                "Name": "Async",
                "Args": {
                    "configure": [
                        {
                            "Name": "File",
                            "Args": {
                                "path": "%JELLYFIN_LOG_DIR%//log_.log",
                                "rollingInterval": "Day",
                                "retainedFileCountLimit": 3,
                                "rollOnFileSizeLimit": true,
                                "fileSizeLimitBytes": 100000000,
                                "outputTemplate": "[{Timestamp:yyyy-MM-dd HH:mm:ss.fff zzz}] [{Level:u3}] [{ThreadId}] {SourceContext}: {Message}{NewLine}{Exception}"
                            }
                        }
                    ]
                }
            }
        ],
        "Enrich": [ "FromLogContext", "WithThreadId" ]
    }
}

I get the following covering the JELLYFIN-DOMIAN/System/Info/Public request

[11:10:44] [DBG] [25] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets.
[11:10:45] [DBG] [25] Microsoft.Extensions.Http.DefaultHttpClientFactory: Starting HttpMessageHandler cleanup cycle with 1 items
[11:10:45] [DBG] [25] Microsoft.Extensions.Http.DefaultHttpClientFactory: Ending HttpMessageHandler cleanup cycle after 0.004ms - processed: 0 items - remaining: 1 items
[11:10:51] [DBG] [25] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1I" accepted.
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1I" started.
[11:10:51] [INF] [22] Microsoft.AspNetCore.Hosting.Diagnostics: Request starting HTTP/1.1 GET http://JELLYFIN.DOMAIN/System/Info/Public - -
[11:10:51] [DBG] [22] Microsoft.AspNetCore.ResponseCompression.ResponseCompressionProvider: No response compression available, the Accept-Encoding header is missing or invalid.
[11:10:51] [DBG] [22] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path /System/Info/Public does not match a supported file type
[11:10:51] [DBG] [22] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[11:10:51] [DBG] [22] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was not authenticated.
[11:10:51] [DBG] [22] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[11:10:51] [DBG] [22] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: 1 candidate(s) found for the request path '/System/Info/Public'
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: Endpoint 'Jellyfin.Api.Controllers.SystemController.GetPublicSystemInfo (Jellyfin.Api)' with route pattern 'System/Info/Public' is valid for the request path '/System/Info/Public'
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware: Request matched endpoint 'Jellyfin.Api.Controllers.SystemController.GetPublicSystemInfo (Jellyfin.Api)'
[11:10:51] [INF] [22] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executing endpoint 'Jellyfin.Api.Controllers.SystemController.GetPublicSystemInfo (Jellyfin.Api)'
[11:10:51] [INF] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Route matched with {action = "GetPublicSystemInfo", controller = "System"}. Executing controller action with signature Microsoft.AspNetCore.Mvc.ActionResult`1[MediaBrowser.Model.System.PublicSystemInfo] GetPublicSystemInfo() on controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api).
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of authorization filters (in the following order): ["None"]
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of resource filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter"]
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of action filters (in the following order): ["Microsoft.AspNetCore.Mvc.ModelBinding.UnsupportedContentTypeFilter (Order: -3000)", "Microsoft.AspNetCore.Mvc.Infrastructure.ModelStateInvalidFilter (Order: -2000)"]
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of exception filters (in the following order): ["None"]
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of result filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter", "Microsoft.AspNetCore.Mvc.Infrastructure.ClientErrorResultFilter (Order: -2000)", "Microsoft.AspNetCore.Mvc.ProducesAttribute (Order: 0)"]
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executing controller factory for controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api)
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed controller factory for controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api)
[11:10:54] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1J" accepted.
[11:10:54] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1J" started.
[11:10:54] [INF] [31] Microsoft.AspNetCore.Hosting.Diagnostics: Request starting HTTP/1.1 GET http://localhost:8096/health - -
[11:10:55] [DBG] [31] Microsoft.AspNetCore.ResponseCompression.ResponseCompressionProvider: No response compression available, the Accept-Encoding header is missing or invalid.
[11:10:55] [DBG] [31] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path /health does not match a supported file type
[11:10:55] [DBG] [31] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[11:10:55] [DBG] [31] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was not authenticated.
[11:10:55] [DBG] [31] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[11:10:55] [DBG] [31] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[11:10:55] [DBG] [31] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: 1 candidate(s) found for the request path '/health'
[11:10:55] [DBG] [31] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware: Request matched endpoint 'Health checks'
[11:10:55] [INF] [31] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executing endpoint 'Health checks'
[11:10:55] [DBG] [31] Microsoft.Extensions.Diagnostics.HealthChecks.DefaultHealthCheckService: Running health checks
[11:10:55] [INF] [31] Microsoft.EntityFrameworkCore.Infrastructure: Entity Framework Core 6.0.9 initialized 'JellyfinDb' using provider 'Microsoft.EntityFrameworkCore.Sqlite:6.0.9' with options: MaxPoolSize=1024 
[11:10:55] [DBG] [31] Microsoft.Extensions.Diagnostics.HealthChecks.DefaultHealthCheckService: Running health check JellyfinDb
[11:10:55] [DBG] [31] Microsoft.EntityFrameworkCore.Database.Connection: Opening connection to database 'main' on server '/config/data/jellyfin.db'.
[11:10:55] [DBG] [31] Microsoft.EntityFrameworkCore.Database.Connection: Opened connection to database 'main' on server '/config/data/jellyfin.db'.
[11:10:55] [DBG] [31] Microsoft.Extensions.Diagnostics.HealthChecks.DefaultHealthCheckService: Health check JellyfinDb with status Healthy completed after 5.3577ms with message 'null'
[11:10:55] [DBG] [31] Microsoft.Extensions.Diagnostics.HealthChecks.DefaultHealthCheckService: Health check processing with combined status Healthy completed after 12.1448ms
[11:10:55] [INF] [31] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executed endpoint 'Health checks'
[11:10:55] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1J" completed keep alive response.
[11:10:55] [INF] [31] Microsoft.AspNetCore.Hosting.Diagnostics: Request finished HTTP/1.1 GET http://localhost:8096/health - - - 200 - text/plain 15.5152ms
[11:10:55] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets: Connection id "0HMLBF2C17T1J" received FIN.
[11:10:55] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1J" disconnecting.
[11:10:55] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1J" stopped.
[11:10:55] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets: Connection id "0HMLBF2C17T1J" sending FIN because: "The Socket transport's send loop completed gracefully."
[11:10:55] [DBG] [31] Microsoft.Extensions.Http.DefaultHttpClientFactory: Starting HttpMessageHandler cleanup cycle with 1 items
[11:10:55] [DBG] [31] Microsoft.Extensions.Http.DefaultHttpClientFactory: Ending HttpMessageHandler cleanup cycle after 0.0032ms - processed: 0 items - remaining: 1 items
[11:10:56] [DBG] [22] Jellyfin.Networking.Manager.NetworkManager: GetBindInterface: Source: True, External: False:
[11:10:56] [DBG] [22] Jellyfin.Networking.Manager.NetworkManager: 192.168.50.60/32: GetBindInterface: Matched first internal interface. 172.17.0.7
[11:10:56] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: List of registered output formatters, in the following order: ["Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter", "Jellyfin.Server.Formatters.CamelCaseJsonProfileFormatter", "Microsoft.AspNetCore.Mvc.Formatters.HttpNoContentOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StringOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StreamOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.SystemTextJsonOutputFormatter", "Jellyfin.Server.Formatters.CssOutputFormatter", "Jellyfin.Server.Formatters.XmlOutputFormatter"]
[11:10:56] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Attempting to select an output formatter based on Accept header '["*/*"]' and explicitly specified content types '["application/json", "application/json; profile=\"CamelCase\"", "application/json; profile=\"PascalCase\""]'. The content types in the accept header must be a subset of the explicitly set content types.
[11:10:56] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Selected output formatter 'Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter' and content type 'application/json' to write the response.
[11:10:56] [INF] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ObjectResultExecutor: Executing ObjectResult, writing value of type 'MediaBrowser.Model.System.PublicSystemInfo'.
[11:10:56] [WRN] [22] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from http://JELLYFIN.DOMAIN/System/Info/Public to 192.168.50.102 in 0:00:05.0128171 with Status Code 200
[11:10:56] [INF] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed action Jellyfin.Api.Controllers.SystemController.GetPublicSystemInfo (Jellyfin.Api) in 5011.366ms
[11:10:56] [INF] [22] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executed endpoint 'Jellyfin.Api.Controllers.SystemController.GetPublicSystemInfo (Jellyfin.Api)'
[11:10:56] [INF] [22] Microsoft.AspNetCore.Hosting.Diagnostics: Request finished HTTP/1.1 GET http://JELLYFIN.DOMAIN/System/Info/Public - - - 200 - application/json;+charset=utf-8 5013.5341ms
[11:10:56] [DBG] [22] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1I" disconnecting.
[11:10:56] [DBG] [22] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1I" stopped.
[11:10:56] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets: Connection id "0HMLBF2C17T1I" sending FIN because: "The Socket transport's send loop completed gracefully."
[11:10:56] [DBG] [22] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets.

Specifically this line seems to be the one to blame.
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed controller factory for controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api)

Following that leads to Emby.Server.Implementations/Emby.Server.Implementations)/ApplicationHost.cs and the git diff to v10.7.7 the two commits that affect that function are Dynamically populate LocalAddress based on HTTP request and Improve platform checks with the former seeming the likely culprit.

@bloominstrong commented on GitHub (Oct 12, 2022): I have not been able to reproduce the issue by limiting IO on a fresh instance but cloning the volume that my instance runs on removes the issue so it does not seem tied to something specific in the database. Cranking my logging up to ``` { "Serilog": { "MinimumLevel": { "Default": "Debug", "Override": { "Microsoft": "Debug", "System": "Debug" } }, "WriteTo": [ { "Name": "Console", "Args": { "outputTemplate": "[{Timestamp:HH:mm:ss}] [{Level:u3}] [{ThreadId}] {SourceContext}: {Message:lj}{NewLine}{Exception}" } }, { "Name": "Async", "Args": { "configure": [ { "Name": "File", "Args": { "path": "%JELLYFIN_LOG_DIR%//log_.log", "rollingInterval": "Day", "retainedFileCountLimit": 3, "rollOnFileSizeLimit": true, "fileSizeLimitBytes": 100000000, "outputTemplate": "[{Timestamp:yyyy-MM-dd HH:mm:ss.fff zzz}] [{Level:u3}] [{ThreadId}] {SourceContext}: {Message}{NewLine}{Exception}" } } ] } } ], "Enrich": [ "FromLogContext", "WithThreadId" ] } } ``` I get the following covering the JELLYFIN-DOMIAN/System/Info/Public request ``` [11:10:44] [DBG] [25] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [11:10:45] [DBG] [25] Microsoft.Extensions.Http.DefaultHttpClientFactory: Starting HttpMessageHandler cleanup cycle with 1 items [11:10:45] [DBG] [25] Microsoft.Extensions.Http.DefaultHttpClientFactory: Ending HttpMessageHandler cleanup cycle after 0.004ms - processed: 0 items - remaining: 1 items [11:10:51] [DBG] [25] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1I" accepted. [11:10:51] [DBG] [22] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1I" started. [11:10:51] [INF] [22] Microsoft.AspNetCore.Hosting.Diagnostics: Request starting HTTP/1.1 GET http://JELLYFIN.DOMAIN/System/Info/Public - - [11:10:51] [DBG] [22] Microsoft.AspNetCore.ResponseCompression.ResponseCompressionProvider: No response compression available, the Accept-Encoding header is missing or invalid. [11:10:51] [DBG] [22] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path /System/Info/Public does not match a supported file type [11:10:51] [DBG] [22] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter [11:10:51] [DBG] [22] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was not authenticated. [11:10:51] [DBG] [22] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter [11:10:51] [DBG] [22] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter [11:10:51] [DBG] [22] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: 1 candidate(s) found for the request path '/System/Info/Public' [11:10:51] [DBG] [22] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: Endpoint 'Jellyfin.Api.Controllers.SystemController.GetPublicSystemInfo (Jellyfin.Api)' with route pattern 'System/Info/Public' is valid for the request path '/System/Info/Public' [11:10:51] [DBG] [22] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware: Request matched endpoint 'Jellyfin.Api.Controllers.SystemController.GetPublicSystemInfo (Jellyfin.Api)' [11:10:51] [INF] [22] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executing endpoint 'Jellyfin.Api.Controllers.SystemController.GetPublicSystemInfo (Jellyfin.Api)' [11:10:51] [INF] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Route matched with {action = "GetPublicSystemInfo", controller = "System"}. Executing controller action with signature Microsoft.AspNetCore.Mvc.ActionResult`1[MediaBrowser.Model.System.PublicSystemInfo] GetPublicSystemInfo() on controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api). [11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of authorization filters (in the following order): ["None"] [11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of resource filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter"] [11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of action filters (in the following order): ["Microsoft.AspNetCore.Mvc.ModelBinding.UnsupportedContentTypeFilter (Order: -3000)", "Microsoft.AspNetCore.Mvc.Infrastructure.ModelStateInvalidFilter (Order: -2000)"] [11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of exception filters (in the following order): ["None"] [11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of result filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter", "Microsoft.AspNetCore.Mvc.Infrastructure.ClientErrorResultFilter (Order: -2000)", "Microsoft.AspNetCore.Mvc.ProducesAttribute (Order: 0)"] [11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executing controller factory for controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api) [11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed controller factory for controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api) [11:10:54] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1J" accepted. [11:10:54] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1J" started. [11:10:54] [INF] [31] Microsoft.AspNetCore.Hosting.Diagnostics: Request starting HTTP/1.1 GET http://localhost:8096/health - - [11:10:55] [DBG] [31] Microsoft.AspNetCore.ResponseCompression.ResponseCompressionProvider: No response compression available, the Accept-Encoding header is missing or invalid. [11:10:55] [DBG] [31] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path /health does not match a supported file type [11:10:55] [DBG] [31] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter [11:10:55] [DBG] [31] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was not authenticated. [11:10:55] [DBG] [31] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter [11:10:55] [DBG] [31] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter [11:10:55] [DBG] [31] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: 1 candidate(s) found for the request path '/health' [11:10:55] [DBG] [31] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware: Request matched endpoint 'Health checks' [11:10:55] [INF] [31] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executing endpoint 'Health checks' [11:10:55] [DBG] [31] Microsoft.Extensions.Diagnostics.HealthChecks.DefaultHealthCheckService: Running health checks [11:10:55] [INF] [31] Microsoft.EntityFrameworkCore.Infrastructure: Entity Framework Core 6.0.9 initialized 'JellyfinDb' using provider 'Microsoft.EntityFrameworkCore.Sqlite:6.0.9' with options: MaxPoolSize=1024 [11:10:55] [DBG] [31] Microsoft.Extensions.Diagnostics.HealthChecks.DefaultHealthCheckService: Running health check JellyfinDb [11:10:55] [DBG] [31] Microsoft.EntityFrameworkCore.Database.Connection: Opening connection to database 'main' on server '/config/data/jellyfin.db'. [11:10:55] [DBG] [31] Microsoft.EntityFrameworkCore.Database.Connection: Opened connection to database 'main' on server '/config/data/jellyfin.db'. [11:10:55] [DBG] [31] Microsoft.Extensions.Diagnostics.HealthChecks.DefaultHealthCheckService: Health check JellyfinDb with status Healthy completed after 5.3577ms with message 'null' [11:10:55] [DBG] [31] Microsoft.Extensions.Diagnostics.HealthChecks.DefaultHealthCheckService: Health check processing with combined status Healthy completed after 12.1448ms [11:10:55] [INF] [31] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executed endpoint 'Health checks' [11:10:55] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1J" completed keep alive response. [11:10:55] [INF] [31] Microsoft.AspNetCore.Hosting.Diagnostics: Request finished HTTP/1.1 GET http://localhost:8096/health - - - 200 - text/plain 15.5152ms [11:10:55] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets: Connection id "0HMLBF2C17T1J" received FIN. [11:10:55] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1J" disconnecting. [11:10:55] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1J" stopped. [11:10:55] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets: Connection id "0HMLBF2C17T1J" sending FIN because: "The Socket transport's send loop completed gracefully." [11:10:55] [DBG] [31] Microsoft.Extensions.Http.DefaultHttpClientFactory: Starting HttpMessageHandler cleanup cycle with 1 items [11:10:55] [DBG] [31] Microsoft.Extensions.Http.DefaultHttpClientFactory: Ending HttpMessageHandler cleanup cycle after 0.0032ms - processed: 0 items - remaining: 1 items [11:10:56] [DBG] [22] Jellyfin.Networking.Manager.NetworkManager: GetBindInterface: Source: True, External: False: [11:10:56] [DBG] [22] Jellyfin.Networking.Manager.NetworkManager: 192.168.50.60/32: GetBindInterface: Matched first internal interface. 172.17.0.7 [11:10:56] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: List of registered output formatters, in the following order: ["Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter", "Jellyfin.Server.Formatters.CamelCaseJsonProfileFormatter", "Microsoft.AspNetCore.Mvc.Formatters.HttpNoContentOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StringOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StreamOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.SystemTextJsonOutputFormatter", "Jellyfin.Server.Formatters.CssOutputFormatter", "Jellyfin.Server.Formatters.XmlOutputFormatter"] [11:10:56] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Attempting to select an output formatter based on Accept header '["*/*"]' and explicitly specified content types '["application/json", "application/json; profile=\"CamelCase\"", "application/json; profile=\"PascalCase\""]'. The content types in the accept header must be a subset of the explicitly set content types. [11:10:56] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Selected output formatter 'Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter' and content type 'application/json' to write the response. [11:10:56] [INF] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ObjectResultExecutor: Executing ObjectResult, writing value of type 'MediaBrowser.Model.System.PublicSystemInfo'. [11:10:56] [WRN] [22] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from http://JELLYFIN.DOMAIN/System/Info/Public to 192.168.50.102 in 0:00:05.0128171 with Status Code 200 [11:10:56] [INF] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed action Jellyfin.Api.Controllers.SystemController.GetPublicSystemInfo (Jellyfin.Api) in 5011.366ms [11:10:56] [INF] [22] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executed endpoint 'Jellyfin.Api.Controllers.SystemController.GetPublicSystemInfo (Jellyfin.Api)' [11:10:56] [INF] [22] Microsoft.AspNetCore.Hosting.Diagnostics: Request finished HTTP/1.1 GET http://JELLYFIN.DOMAIN/System/Info/Public - - - 200 - application/json;+charset=utf-8 5013.5341ms [11:10:56] [DBG] [22] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1I" disconnecting. [11:10:56] [DBG] [22] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1I" stopped. [11:10:56] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets: Connection id "0HMLBF2C17T1I" sending FIN because: "The Socket transport's send loop completed gracefully." [11:10:56] [DBG] [22] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. ``` Specifically this line seems to be the one to blame. `[11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed controller factory for controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api)` Following that leads to [Emby.Server.Implementations/Emby.Server.Implementations)/ApplicationHost.cs](https://github.com/jellyfin/jellyfin/blob/d50c1b2d4bc0c85428671b288adef1b336da1156/Emby.Server.Implementations/ApplicationHost.cs#L1051) and the git diff to v10.7.7 the two commits that affect that function are [Dynamically populate LocalAddress based on HTTP request](https://github.com/jellyfin/jellyfin/commit/90174f68e2b477e613d756d78cbfd89c2a33f30a) and [Improve platform checks](https://github.com/jellyfin/jellyfin/commit/8528e9bddb4c2dd9e1e3294649e39c7ec609bdf5) with the former seeming the likely culprit.
Author
Owner

@cvium commented on GitHub (Oct 12, 2022):

I don't really see how either of those PRs could affect it in such a way

@cvium commented on GitHub (Oct 12, 2022): I don't really see how either of those PRs could affect it in such a way
Author
Owner

@SuperCatss commented on GitHub (Oct 19, 2022):

Same problem, when I access via LAN it is fast and when I access via internet (via reverse proxy as well as port forwarding) it is very slow.

@SuperCatss commented on GitHub (Oct 19, 2022): Same problem, when I access via LAN it is fast and when I access via internet (via reverse proxy as well as port forwarding) it is very slow.
Author
Owner

@cvium commented on GitHub (Oct 19, 2022):

Works for me

@cvium commented on GitHub (Oct 19, 2022): Works for me
Author
Owner

@SuperCatss commented on GitHub (Oct 20, 2022):

I have not been able to reproduce the issue by limiting IO on a fresh instance but cloning the volume that my instance runs on removes the issue so it does not seem tied to something specific in the database.

Cranking my logging up to

{
    "Serilog": {
        "MinimumLevel": {
            "Default": "Debug",
            "Override": {
                "Microsoft": "Debug",
                "System": "Debug"
            }
        },
        "WriteTo": [
            {
                "Name": "Console",
                "Args": {
                    "outputTemplate": "[{Timestamp:HH:mm:ss}] [{Level:u3}] [{ThreadId}] {SourceContext}: {Message:lj}{NewLine}{Exception}"
                }
            },
            {
                "Name": "Async",
                "Args": {
                    "configure": [
                        {
                            "Name": "File",
                            "Args": {
                                "path": "%JELLYFIN_LOG_DIR%//log_.log",
                                "rollingInterval": "Day",
                                "retainedFileCountLimit": 3,
                                "rollOnFileSizeLimit": true,
                                "fileSizeLimitBytes": 100000000,
                                "outputTemplate": "[{Timestamp:yyyy-MM-dd HH:mm:ss.fff zzz}] [{Level:u3}] [{ThreadId}] {SourceContext}: {Message}{NewLine}{Exception}"
                            }
                        }
                    ]
                }
            }
        ],
        "Enrich": [ "FromLogContext", "WithThreadId" ]
    }
}

I get the following covering the JELLYFIN-DOMIAN/System/Info/Public request

[11:10:44] [DBG] [25] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets.
[11:10:45] [DBG] [25] Microsoft.Extensions.Http.DefaultHttpClientFactory: Starting HttpMessageHandler cleanup cycle with 1 items
[11:10:45] [DBG] [25] Microsoft.Extensions.Http.DefaultHttpClientFactory: Ending HttpMessageHandler cleanup cycle after 0.004ms - processed: 0 items - remaining: 1 items
[11:10:51] [DBG] [25] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1I" accepted.
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1I" started.
[11:10:51] [INF] [22] Microsoft.AspNetCore.Hosting.Diagnostics: Request starting HTTP/1.1 GET http://JELLYFIN.DOMAIN/System/Info/Public - -
[11:10:51] [DBG] [22] Microsoft.AspNetCore.ResponseCompression.ResponseCompressionProvider: No response compression available, the Accept-Encoding header is missing or invalid.
[11:10:51] [DBG] [22] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path /System/Info/Public does not match a supported file type
[11:10:51] [DBG] [22] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[11:10:51] [DBG] [22] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was not authenticated.
[11:10:51] [DBG] [22] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[11:10:51] [DBG] [22] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: 1 candidate(s) found for the request path '/System/Info/Public'
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: Endpoint 'Jellyfin.Api.Controllers.SystemController.GetPublicSystemInfo (Jellyfin.Api)' with route pattern 'System/Info/Public' is valid for the request path '/System/Info/Public'
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware: Request matched endpoint 'Jellyfin.Api.Controllers.SystemController.GetPublicSystemInfo (Jellyfin.Api)'
[11:10:51] [INF] [22] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executing endpoint 'Jellyfin.Api.Controllers.SystemController.GetPublicSystemInfo (Jellyfin.Api)'
[11:10:51] [INF] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Route matched with {action = "GetPublicSystemInfo", controller = "System"}. Executing controller action with signature Microsoft.AspNetCore.Mvc.ActionResult`1[MediaBrowser.Model.System.PublicSystemInfo] GetPublicSystemInfo() on controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api).
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of authorization filters (in the following order): ["None"]
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of resource filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter"]
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of action filters (in the following order): ["Microsoft.AspNetCore.Mvc.ModelBinding.UnsupportedContentTypeFilter (Order: -3000)", "Microsoft.AspNetCore.Mvc.Infrastructure.ModelStateInvalidFilter (Order: -2000)"]
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of exception filters (in the following order): ["None"]
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of result filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter", "Microsoft.AspNetCore.Mvc.Infrastructure.ClientErrorResultFilter (Order: -2000)", "Microsoft.AspNetCore.Mvc.ProducesAttribute (Order: 0)"]
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executing controller factory for controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api)
[11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed controller factory for controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api)
[11:10:54] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1J" accepted.
[11:10:54] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1J" started.
[11:10:54] [INF] [31] Microsoft.AspNetCore.Hosting.Diagnostics: Request starting HTTP/1.1 GET http://localhost:8096/health - -
[11:10:55] [DBG] [31] Microsoft.AspNetCore.ResponseCompression.ResponseCompressionProvider: No response compression available, the Accept-Encoding header is missing or invalid.
[11:10:55] [DBG] [31] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path /health does not match a supported file type
[11:10:55] [DBG] [31] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[11:10:55] [DBG] [31] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was not authenticated.
[11:10:55] [DBG] [31] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[11:10:55] [DBG] [31] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path  does not match the path filter
[11:10:55] [DBG] [31] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: 1 candidate(s) found for the request path '/health'
[11:10:55] [DBG] [31] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware: Request matched endpoint 'Health checks'
[11:10:55] [INF] [31] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executing endpoint 'Health checks'
[11:10:55] [DBG] [31] Microsoft.Extensions.Diagnostics.HealthChecks.DefaultHealthCheckService: Running health checks
[11:10:55] [INF] [31] Microsoft.EntityFrameworkCore.Infrastructure: Entity Framework Core 6.0.9 initialized 'JellyfinDb' using provider 'Microsoft.EntityFrameworkCore.Sqlite:6.0.9' with options: MaxPoolSize=1024 
[11:10:55] [DBG] [31] Microsoft.Extensions.Diagnostics.HealthChecks.DefaultHealthCheckService: Running health check JellyfinDb
[11:10:55] [DBG] [31] Microsoft.EntityFrameworkCore.Database.Connection: Opening connection to database 'main' on server '/config/data/jellyfin.db'.
[11:10:55] [DBG] [31] Microsoft.EntityFrameworkCore.Database.Connection: Opened connection to database 'main' on server '/config/data/jellyfin.db'.
[11:10:55] [DBG] [31] Microsoft.Extensions.Diagnostics.HealthChecks.DefaultHealthCheckService: Health check JellyfinDb with status Healthy completed after 5.3577ms with message 'null'
[11:10:55] [DBG] [31] Microsoft.Extensions.Diagnostics.HealthChecks.DefaultHealthCheckService: Health check processing with combined status Healthy completed after 12.1448ms
[11:10:55] [INF] [31] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executed endpoint 'Health checks'
[11:10:55] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1J" completed keep alive response.
[11:10:55] [INF] [31] Microsoft.AspNetCore.Hosting.Diagnostics: Request finished HTTP/1.1 GET http://localhost:8096/health - - - 200 - text/plain 15.5152ms
[11:10:55] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets: Connection id "0HMLBF2C17T1J" received FIN.
[11:10:55] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1J" disconnecting.
[11:10:55] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1J" stopped.
[11:10:55] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets: Connection id "0HMLBF2C17T1J" sending FIN because: "The Socket transport's send loop completed gracefully."
[11:10:55] [DBG] [31] Microsoft.Extensions.Http.DefaultHttpClientFactory: Starting HttpMessageHandler cleanup cycle with 1 items
[11:10:55] [DBG] [31] Microsoft.Extensions.Http.DefaultHttpClientFactory: Ending HttpMessageHandler cleanup cycle after 0.0032ms - processed: 0 items - remaining: 1 items
[11:10:56] [DBG] [22] Jellyfin.Networking.Manager.NetworkManager: GetBindInterface: Source: True, External: False:
[11:10:56] [DBG] [22] Jellyfin.Networking.Manager.NetworkManager: 192.168.50.60/32: GetBindInterface: Matched first internal interface. 172.17.0.7
[11:10:56] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: List of registered output formatters, in the following order: ["Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter", "Jellyfin.Server.Formatters.CamelCaseJsonProfileFormatter", "Microsoft.AspNetCore.Mvc.Formatters.HttpNoContentOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StringOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StreamOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.SystemTextJsonOutputFormatter", "Jellyfin.Server.Formatters.CssOutputFormatter", "Jellyfin.Server.Formatters.XmlOutputFormatter"]
[11:10:56] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Attempting to select an output formatter based on Accept header '["*/*"]' and explicitly specified content types '["application/json", "application/json; profile=\"CamelCase\"", "application/json; profile=\"PascalCase\""]'. The content types in the accept header must be a subset of the explicitly set content types.
[11:10:56] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Selected output formatter 'Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter' and content type 'application/json' to write the response.
[11:10:56] [INF] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ObjectResultExecutor: Executing ObjectResult, writing value of type 'MediaBrowser.Model.System.PublicSystemInfo'.
[11:10:56] [WRN] [22] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from http://JELLYFIN.DOMAIN/System/Info/Public to 192.168.50.102 in 0:00:05.0128171 with Status Code 200
[11:10:56] [INF] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed action Jellyfin.Api.Controllers.SystemController.GetPublicSystemInfo (Jellyfin.Api) in 5011.366ms
[11:10:56] [INF] [22] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executed endpoint 'Jellyfin.Api.Controllers.SystemController.GetPublicSystemInfo (Jellyfin.Api)'
[11:10:56] [INF] [22] Microsoft.AspNetCore.Hosting.Diagnostics: Request finished HTTP/1.1 GET http://JELLYFIN.DOMAIN/System/Info/Public - - - 200 - application/json;+charset=utf-8 5013.5341ms
[11:10:56] [DBG] [22] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1I" disconnecting.
[11:10:56] [DBG] [22] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1I" stopped.
[11:10:56] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets: Connection id "0HMLBF2C17T1I" sending FIN because: "The Socket transport's send loop completed gracefully."
[11:10:56] [DBG] [22] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets.

Specifically this line seems to be the one to blame. [11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed controller factory for controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api)

Following that leads to Emby.Server.Implementations/Emby.Server.Implementations)/ApplicationHost.cs and the git diff to v10.7.7 the two commits that affect that function are Dynamically populate LocalAddress based on HTTP request and Improve platform checks with the former seeming the likely culprit.

When I go to the domain name, the request "/System/Info/Public" usually takes 40 seconds to respond. When I access via LAN, it only takes 1 second.

@SuperCatss commented on GitHub (Oct 20, 2022): > I have not been able to reproduce the issue by limiting IO on a fresh instance but cloning the volume that my instance runs on removes the issue so it does not seem tied to something specific in the database. > > Cranking my logging up to > > ``` > { > "Serilog": { > "MinimumLevel": { > "Default": "Debug", > "Override": { > "Microsoft": "Debug", > "System": "Debug" > } > }, > "WriteTo": [ > { > "Name": "Console", > "Args": { > "outputTemplate": "[{Timestamp:HH:mm:ss}] [{Level:u3}] [{ThreadId}] {SourceContext}: {Message:lj}{NewLine}{Exception}" > } > }, > { > "Name": "Async", > "Args": { > "configure": [ > { > "Name": "File", > "Args": { > "path": "%JELLYFIN_LOG_DIR%//log_.log", > "rollingInterval": "Day", > "retainedFileCountLimit": 3, > "rollOnFileSizeLimit": true, > "fileSizeLimitBytes": 100000000, > "outputTemplate": "[{Timestamp:yyyy-MM-dd HH:mm:ss.fff zzz}] [{Level:u3}] [{ThreadId}] {SourceContext}: {Message}{NewLine}{Exception}" > } > } > ] > } > } > ], > "Enrich": [ "FromLogContext", "WithThreadId" ] > } > } > ``` > > I get the following covering the JELLYFIN-DOMIAN/System/Info/Public request > > ``` > [11:10:44] [DBG] [25] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. > [11:10:45] [DBG] [25] Microsoft.Extensions.Http.DefaultHttpClientFactory: Starting HttpMessageHandler cleanup cycle with 1 items > [11:10:45] [DBG] [25] Microsoft.Extensions.Http.DefaultHttpClientFactory: Ending HttpMessageHandler cleanup cycle after 0.004ms - processed: 0 items - remaining: 1 items > [11:10:51] [DBG] [25] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1I" accepted. > [11:10:51] [DBG] [22] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1I" started. > [11:10:51] [INF] [22] Microsoft.AspNetCore.Hosting.Diagnostics: Request starting HTTP/1.1 GET http://JELLYFIN.DOMAIN/System/Info/Public - - > [11:10:51] [DBG] [22] Microsoft.AspNetCore.ResponseCompression.ResponseCompressionProvider: No response compression available, the Accept-Encoding header is missing or invalid. > [11:10:51] [DBG] [22] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path /System/Info/Public does not match a supported file type > [11:10:51] [DBG] [22] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter > [11:10:51] [DBG] [22] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was not authenticated. > [11:10:51] [DBG] [22] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter > [11:10:51] [DBG] [22] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter > [11:10:51] [DBG] [22] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: 1 candidate(s) found for the request path '/System/Info/Public' > [11:10:51] [DBG] [22] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: Endpoint 'Jellyfin.Api.Controllers.SystemController.GetPublicSystemInfo (Jellyfin.Api)' with route pattern 'System/Info/Public' is valid for the request path '/System/Info/Public' > [11:10:51] [DBG] [22] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware: Request matched endpoint 'Jellyfin.Api.Controllers.SystemController.GetPublicSystemInfo (Jellyfin.Api)' > [11:10:51] [INF] [22] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executing endpoint 'Jellyfin.Api.Controllers.SystemController.GetPublicSystemInfo (Jellyfin.Api)' > [11:10:51] [INF] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Route matched with {action = "GetPublicSystemInfo", controller = "System"}. Executing controller action with signature Microsoft.AspNetCore.Mvc.ActionResult`1[MediaBrowser.Model.System.PublicSystemInfo] GetPublicSystemInfo() on controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api). > [11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of authorization filters (in the following order): ["None"] > [11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of resource filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter"] > [11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of action filters (in the following order): ["Microsoft.AspNetCore.Mvc.ModelBinding.UnsupportedContentTypeFilter (Order: -3000)", "Microsoft.AspNetCore.Mvc.Infrastructure.ModelStateInvalidFilter (Order: -2000)"] > [11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of exception filters (in the following order): ["None"] > [11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Execution plan of result filters (in the following order): ["Microsoft.AspNetCore.Mvc.ViewFeatures.Filters.SaveTempDataFilter", "Microsoft.AspNetCore.Mvc.Infrastructure.ClientErrorResultFilter (Order: -2000)", "Microsoft.AspNetCore.Mvc.ProducesAttribute (Order: 0)"] > [11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executing controller factory for controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api) > [11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed controller factory for controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api) > [11:10:54] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1J" accepted. > [11:10:54] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1J" started. > [11:10:54] [INF] [31] Microsoft.AspNetCore.Hosting.Diagnostics: Request starting HTTP/1.1 GET http://localhost:8096/health - - > [11:10:55] [DBG] [31] Microsoft.AspNetCore.ResponseCompression.ResponseCompressionProvider: No response compression available, the Accept-Encoding header is missing or invalid. > [11:10:55] [DBG] [31] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path /health does not match a supported file type > [11:10:55] [DBG] [31] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter > [11:10:55] [DBG] [31] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: CustomAuthentication was not authenticated. > [11:10:55] [DBG] [31] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter > [11:10:55] [DBG] [31] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The request path does not match the path filter > [11:10:55] [DBG] [31] Microsoft.AspNetCore.Routing.Matching.DfaMatcher: 1 candidate(s) found for the request path '/health' > [11:10:55] [DBG] [31] Microsoft.AspNetCore.Routing.EndpointRoutingMiddleware: Request matched endpoint 'Health checks' > [11:10:55] [INF] [31] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executing endpoint 'Health checks' > [11:10:55] [DBG] [31] Microsoft.Extensions.Diagnostics.HealthChecks.DefaultHealthCheckService: Running health checks > [11:10:55] [INF] [31] Microsoft.EntityFrameworkCore.Infrastructure: Entity Framework Core 6.0.9 initialized 'JellyfinDb' using provider 'Microsoft.EntityFrameworkCore.Sqlite:6.0.9' with options: MaxPoolSize=1024 > [11:10:55] [DBG] [31] Microsoft.Extensions.Diagnostics.HealthChecks.DefaultHealthCheckService: Running health check JellyfinDb > [11:10:55] [DBG] [31] Microsoft.EntityFrameworkCore.Database.Connection: Opening connection to database 'main' on server '/config/data/jellyfin.db'. > [11:10:55] [DBG] [31] Microsoft.EntityFrameworkCore.Database.Connection: Opened connection to database 'main' on server '/config/data/jellyfin.db'. > [11:10:55] [DBG] [31] Microsoft.Extensions.Diagnostics.HealthChecks.DefaultHealthCheckService: Health check JellyfinDb with status Healthy completed after 5.3577ms with message 'null' > [11:10:55] [DBG] [31] Microsoft.Extensions.Diagnostics.HealthChecks.DefaultHealthCheckService: Health check processing with combined status Healthy completed after 12.1448ms > [11:10:55] [INF] [31] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executed endpoint 'Health checks' > [11:10:55] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1J" completed keep alive response. > [11:10:55] [INF] [31] Microsoft.AspNetCore.Hosting.Diagnostics: Request finished HTTP/1.1 GET http://localhost:8096/health - - - 200 - text/plain 15.5152ms > [11:10:55] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets: Connection id "0HMLBF2C17T1J" received FIN. > [11:10:55] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1J" disconnecting. > [11:10:55] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1J" stopped. > [11:10:55] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets: Connection id "0HMLBF2C17T1J" sending FIN because: "The Socket transport's send loop completed gracefully." > [11:10:55] [DBG] [31] Microsoft.Extensions.Http.DefaultHttpClientFactory: Starting HttpMessageHandler cleanup cycle with 1 items > [11:10:55] [DBG] [31] Microsoft.Extensions.Http.DefaultHttpClientFactory: Ending HttpMessageHandler cleanup cycle after 0.0032ms - processed: 0 items - remaining: 1 items > [11:10:56] [DBG] [22] Jellyfin.Networking.Manager.NetworkManager: GetBindInterface: Source: True, External: False: > [11:10:56] [DBG] [22] Jellyfin.Networking.Manager.NetworkManager: 192.168.50.60/32: GetBindInterface: Matched first internal interface. 172.17.0.7 > [11:10:56] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: List of registered output formatters, in the following order: ["Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter", "Jellyfin.Server.Formatters.CamelCaseJsonProfileFormatter", "Microsoft.AspNetCore.Mvc.Formatters.HttpNoContentOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StringOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.StreamOutputFormatter", "Microsoft.AspNetCore.Mvc.Formatters.SystemTextJsonOutputFormatter", "Jellyfin.Server.Formatters.CssOutputFormatter", "Jellyfin.Server.Formatters.XmlOutputFormatter"] > [11:10:56] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Attempting to select an output formatter based on Accept header '["*/*"]' and explicitly specified content types '["application/json", "application/json; profile=\"CamelCase\"", "application/json; profile=\"PascalCase\""]'. The content types in the accept header must be a subset of the explicitly set content types. > [11:10:56] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.DefaultOutputFormatterSelector: Selected output formatter 'Jellyfin.Server.Formatters.PascalCaseJsonProfileFormatter' and content type 'application/json' to write the response. > [11:10:56] [INF] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ObjectResultExecutor: Executing ObjectResult, writing value of type 'MediaBrowser.Model.System.PublicSystemInfo'. > [11:10:56] [WRN] [22] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from http://JELLYFIN.DOMAIN/System/Info/Public to 192.168.50.102 in 0:00:05.0128171 with Status Code 200 > [11:10:56] [INF] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed action Jellyfin.Api.Controllers.SystemController.GetPublicSystemInfo (Jellyfin.Api) in 5011.366ms > [11:10:56] [INF] [22] Microsoft.AspNetCore.Routing.EndpointMiddleware: Executed endpoint 'Jellyfin.Api.Controllers.SystemController.GetPublicSystemInfo (Jellyfin.Api)' > [11:10:56] [INF] [22] Microsoft.AspNetCore.Hosting.Diagnostics: Request finished HTTP/1.1 GET http://JELLYFIN.DOMAIN/System/Info/Public - - - 200 - application/json;+charset=utf-8 5013.5341ms > [11:10:56] [DBG] [22] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1I" disconnecting. > [11:10:56] [DBG] [22] Microsoft.AspNetCore.Server.Kestrel.Connections: Connection id "0HMLBF2C17T1I" stopped. > [11:10:56] [DBG] [31] Microsoft.AspNetCore.Server.Kestrel.Transport.Sockets: Connection id "0HMLBF2C17T1I" sending FIN because: "The Socket transport's send loop completed gracefully." > [11:10:56] [DBG] [22] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. > ``` > > Specifically this line seems to be the one to blame. `[11:10:51] [DBG] [22] Microsoft.AspNetCore.Mvc.Infrastructure.ControllerActionInvoker: Executed controller factory for controller Jellyfin.Api.Controllers.SystemController (Jellyfin.Api)` > > Following that leads to [Emby.Server.Implementations/Emby.Server.Implementations)/ApplicationHost.cs](https://github.com/jellyfin/jellyfin/blob/d50c1b2d4bc0c85428671b288adef1b336da1156/Emby.Server.Implementations/ApplicationHost.cs#L1051) and the git diff to v10.7.7 the two commits that affect that function are [Dynamically populate LocalAddress based on HTTP request](https://github.com/jellyfin/jellyfin/commit/90174f68e2b477e613d756d78cbfd89c2a33f30a) and [Improve platform checks](https://github.com/jellyfin/jellyfin/commit/8528e9bddb4c2dd9e1e3294649e39c7ec609bdf5) with the former seeming the likely culprit. When I go to the domain name, the request "/System/Info/Public" usually takes 40 seconds to respond. When I access via LAN, it only takes 1 second.
Author
Owner

@cvium commented on GitHub (Oct 20, 2022):

There's shockingly few logs being posted. I need a complete debug log. Enable debug logging, restart Jellyfin and upload it.

@cvium commented on GitHub (Oct 20, 2022): There's shockingly few logs being posted. I need a _complete_ debug log. Enable debug logging, restart Jellyfin and upload it.
Author
Owner

@matvey00z commented on GitHub (Oct 20, 2022):

I have similar issue here when run jellyfin in docker with access to internal docker network only, running behind nginx reverse proxy on a public domain. The corresponding logline is:

jellyfin      | [14:13:21] [WRN] [31] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from http://my.domain.here/System/Info/Public to 10.111.112.1 in 0:00:10.011909 with Status Code 200

If I allow access to the internet, basically adding

services:
  jellyfin:
    ...
    networks:
      - ...
      - default

to my compose.yml, the issue disappears and it loads much faster.
Also, I do observe that switching from the domain name to ip address solves eliminates this long load too.
So, it seems that it tries to do some external requests and then times out.
And imo it looks similar to #6918

@matvey00z commented on GitHub (Oct 20, 2022): I have similar issue here when run jellyfin in docker with access to internal docker network only, running behind nginx reverse proxy on a public domain. The corresponding logline is: ``` jellyfin | [14:13:21] [WRN] [31] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from http://my.domain.here/System/Info/Public to 10.111.112.1 in 0:00:10.011909 with Status Code 200 ``` If I allow access to the internet, basically adding ``` services: jellyfin: ... networks: - ... - default ``` to my compose.yml, the issue disappears and it loads much faster. Also, I do observe that switching from the domain name to ip address solves eliminates this long load too. So, it seems that it tries to do some external requests and then times out. And imo it looks similar to #6918
Author
Owner

@matvey00z commented on GitHub (Oct 20, 2022):

One more observation.
This request takes 10 seconds:

curl --connect-to my.domain.here:80:10.111.112.1:8096 http://my.domain.here/System/Info/Public

This responds immediately:

curl http://10.111.112.1:8096/System/Info/Public

Meaning it's a hostname in the request what matters, not the actual path the request goes through.

@matvey00z commented on GitHub (Oct 20, 2022): One more observation. This request takes 10 seconds: ``` curl --connect-to my.domain.here:80:10.111.112.1:8096 http://my.domain.here/System/Info/Public ``` This responds immediately: ``` curl http://10.111.112.1:8096/System/Info/Public ``` Meaning it's a hostname in the request what matters, not the actual path the request goes through.
Author
Owner

@cvium commented on GitHub (Oct 20, 2022):

I cannot reproduce it, but if I were to guess, I'd say it's this line 4c1286fd24/MediaBrowser.Common/Net/IPHost.cs (L431), which is triggered by this call to source.Address e299adc819/Jellyfin.Networking/Manager/NetworkManager.cs (L362), which is called by 3ff78b687d/Emby.Server.Implementations/ApplicationHost.cs (L1077). It was introduced by this PR https://github.com/jellyfin/jellyfin/pull/6486.

A PR is probably coming soon.

@cvium commented on GitHub (Oct 20, 2022): I cannot reproduce it, but if I were to guess, I'd say it's this line https://github.com/jellyfin/jellyfin/blob/4c1286fd24139527f1fbd3db23e8d74e4d8da283/MediaBrowser.Common/Net/IPHost.cs#L431, which is triggered by this call to `source.Address` https://github.com/jellyfin/jellyfin/blob/e299adc819c01b48c23ad406ab4bf3a8783c868d/Jellyfin.Networking/Manager/NetworkManager.cs#L362, which is called by https://github.com/jellyfin/jellyfin/blob/3ff78b687dfa0f598ce70adb8603899f31b8a201/Emby.Server.Implementations/ApplicationHost.cs#L1077. It was introduced by this PR https://github.com/jellyfin/jellyfin/pull/6486. A PR is probably coming soon.
Author
Owner

@ServeurpersoCom commented on GitHub (Oct 30, 2022):

Look fixed for me on 10.8.6, problem hard to reproduce but I'm about 90% sure with a few days of heavy use.

@ServeurpersoCom commented on GitHub (Oct 30, 2022): Look fixed for me on 10.8.6, problem hard to reproduce but I'm about 90% sure with a few days of heavy use.
Author
Owner

@matvey00z commented on GitHub (Nov 7, 2022):

Confirm. Missed the 10.8.6 update but 10.8.7 works fine for me.

@matvey00z commented on GitHub (Nov 7, 2022): Confirm. Missed the 10.8.6 update but 10.8.7 works fine for me.
Author
Owner

@ServeurpersoCom commented on GitHub (Nov 3, 2023):

I learned a lot tonight. On last stable, with 42 testing users the server saturate with 100% I/O on jbd2/nvme0n1p2- This process is the EXT4 kernel thread for FS Journal.

echo 1 > /sys/kernel/debug/tracing/events/ext4/ext4_sync_file_enter/enable
cat /sys/kernel/debug/tracing/trace_pipe
.NET ThreadPool-2460896 [009] .... 50322.096955: ext4_sync_file_enter: dev 259,2 ino 60555275 parent 60590080 datasync 0
.NET ThreadPool-2460519 [003] .... 50322.247079: ext4_sync_file_enter: dev 259,2 ino 60555275 parent 60590080 datasync 0

With a very high frequency SPAM from .NET ThreadPool, from (lsof) library.db SQLite writing (WAL disabled),

I moved this database only (db file and journal file) to a tmpfs Ramdisk, and now I get 0% I/O problem and a very low server load with the same amount of users, and I pushed up to 200 fake random testing users without any performance problem (only network is the limit because I don't use any video transcoding).

BUT even with this huge performance bump, the Daskboard problem is not solved : Even a very short access to it freeze the entire server for all users (freeze at 0kbps) and stream upload resume few seconds later.

The dashboard issue is not related to the SQLite performance contrary to what I believed. There is a serious bug that I can't dig deeper into:(

@ServeurpersoCom commented on GitHub (Nov 3, 2023): I learned a lot tonight. On last stable, with 42 testing users the server saturate with 100% I/O on jbd2/nvme0n1p2- This process is the EXT4 kernel thread for FS Journal. echo 1 > /sys/kernel/debug/tracing/events/ext4/ext4_sync_file_enter/enable cat /sys/kernel/debug/tracing/trace_pipe .NET ThreadPool-2460896 [009] .... 50322.096955: ext4_sync_file_enter: dev 259,2 ino 60555275 parent 60590080 datasync 0 .NET ThreadPool-2460519 [003] .... 50322.247079: ext4_sync_file_enter: dev 259,2 ino 60555275 parent 60590080 datasync 0 With a very high frequency SPAM from .NET ThreadPool, from (lsof) library.db SQLite writing (WAL disabled), I moved this database only (db file and journal file) to a tmpfs Ramdisk, and now I get 0% I/O problem and a very low server load with the same amount of users, and I pushed up to 200 fake random testing users without any performance problem (only network is the limit because I don't use any video transcoding). BUT even with this huge performance bump, the Daskboard problem is not solved : Even a very short access to it freeze the entire server for all users (freeze at 0kbps) and stream upload resume few seconds later. The dashboard issue is not related to the SQLite performance contrary to what I believed. There is a serious bug that I can't dig deeper into:(
Sign in to join this conversation.
1 Participants
Notifications
Due Date
No due date set.
Dependencies

No dependencies set.

Reference: starred/jellyfin#4062