[2023-11-08 16:53:56.874 -06:00] [INF] [1] Main: Jellyfin version: "10.8.12" [2023-11-08 16:53:56.893 -06:00] [INF] [1] Main: Environment Variables: ["[JELLYFIN_WEB_DIR, /jellyfin/jellyfin-web]", "[JELLYFIN_CACHE_DIR, /cache]", "[JELLYFIN_DATA_DIR, /config]", "[JELLYFIN_LOG_DIR, /config/log]", "[JELLYFIN_CONFIG_DIR, /config/config]", "[DOTNET_SYSTEM_GLOBALIZATION_INVARIANT, 1]", "[JELLYFIN_FFMPEG, /usr/lib/jellyfin-ffmpeg/ffmpeg]"] [2023-11-08 16:53:56.896 -06:00] [INF] [1] Main: Arguments: ["/jellyfin/jellyfin.dll"] [2023-11-08 16:53:56.897 -06:00] [INF] [1] Main: Operating system: "Linux" [2023-11-08 16:53:56.897 -06:00] [INF] [1] Main: Architecture: X64 [2023-11-08 16:53:56.897 -06:00] [INF] [1] Main: 64-Bit Process: True [2023-11-08 16:53:56.897 -06:00] [INF] [1] Main: User Interactive: True [2023-11-08 16:53:56.897 -06:00] [INF] [1] Main: Processor count: 4 [2023-11-08 16:53:56.897 -06:00] [INF] [1] Main: Program data path: "/config" [2023-11-08 16:53:56.897 -06:00] [INF] [1] Main: Web resources path: "/jellyfin/jellyfin-web" [2023-11-08 16:53:56.898 -06:00] [INF] [1] Main: Application directory: "/jellyfin/" [2023-11-08 16:53:57.161 -06:00] [DBG] [1] Jellyfin.Server.Migrations.MigrationRunner: Skipping migration '"CreateNetworkConfiguration"' since it is already applied [2023-11-08 16:53:57.219 -06:00] [INF] [1] Emby.Server.Implementations.AppBase.BaseConfigurationManager: Setting cache path: "/cache" [2023-11-08 16:53:57.341 -06:00] [INF] [1] Emby.Server.Implementations.ApplicationHost: Loading assemblies [2023-11-08 16:53:57.399 -06:00] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded assembly "Jellyfin.Plugin.Bookshelf, Version=9.0.0.0, Culture=neutral, PublicKeyToken=null" from "/config/plugins/Bookshelf_9.0.0.0/Jellyfin.Plugin.Bookshelf.dll" [2023-11-08 16:53:57.434 -06:00] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded assembly "Jellyfin.Plugin.Opds, Version=4.0.0.0, Culture=neutral, PublicKeyToken=null" from "/config/plugins/OPDS_4.0.0.0/Jellyfin.Plugin.Opds.dll" [2023-11-08 16:53:57.487 -06:00] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded assembly "Jellyfin.Plugin.PlaybackReporting, Version=13.0.0.0, Culture=neutral, PublicKeyToken=null" from "/config/plugins/Playback Reporting_13.0.0.0/Jellyfin.Plugin.PlaybackReporting.dll" [2023-11-08 16:53:57.504 -06:00] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded assembly "Jellyfin.Plugin.Reports, Version=15.0.0.0, Culture=neutral, PublicKeyToken=null" from "/config/plugins/Reports_15.0.0.0/Jellyfin.Plugin.Reports.dll" [2023-11-08 16:53:57.605 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Jellyfin.Plugin.PlaybackReporting.ReportPlaybackOptionsFactory" [2023-11-08 16:53:57.605 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Common.Providers.SubtitleConfigurationFactory" [2023-11-08 16:53:57.605 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Controller.Library.MetadataConfigurationStore" [2023-11-08 16:53:57.605 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.LiveTv.LiveTvConfigurationFactory" [2023-11-08 16:53:57.606 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Branding.BrandingConfigurationFactory" [2023-11-08 16:53:57.606 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.MediaEncoding.Configuration.EncodingConfigurationFactory" [2023-11-08 16:53:57.606 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Dlna.DlnaConfigurationFactory" [2023-11-08 16:53:57.606 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Notifications.NotificationConfigurationFactory" [2023-11-08 16:53:57.606 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.XbmcMetadata.Configuration.NfoConfigurationFactory" [2023-11-08 16:53:57.606 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Jellyfin.Networking.Configuration.NetworkConfigurationFactory" [2023-11-08 16:53:57.606 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Jellyfin.Server.Migrations.MigrationsFactory" [2023-11-08 16:53:57.666 -06:00] [DBG] [1] Jellyfin.Networking.Manager.NetworkManager: Refreshing interfaces. [2023-11-08 16:53:57.672 -06:00] [DBG] [1] Jellyfin.Networking.Manager.NetworkManager: Discovered 2 interfaces. [2023-11-08 16:53:57.673 -06:00] [DBG] [1] Jellyfin.Networking.Manager.NetworkManager: Interfaces addresses : "[172.18.0.6/16,127.0.0.1/8]" [2023-11-08 16:53:57.673 -06:00] [DBG] [1] Jellyfin.Networking.Manager.NetworkManager: Refreshing LAN information. [2023-11-08 16:53:57.679 -06:00] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN addresses : "[10.6.0.0/24]" [2023-11-08 16:53:57.679 -06:00] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN exclusions : "[]" [2023-11-08 16:53:57.680 -06:00] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Using LAN addresses: "[10.6.0.0/24]" [2023-11-08 16:53:57.686 -06:00] [DBG] [1] Jellyfin.Networking.Manager.NetworkManager: Invalid or unknown object "vEthernet*". [2023-11-08 16:53:57.686 -06:00] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Using bind addresses: "[]" [2023-11-08 16:53:57.686 -06:00] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Using bind exclusions: "[]" [2023-11-08 16:53:57.688 -06:00] [ERR] [1] Jellyfin.Networking.Manager.NetworkManager: Unable to parse bind override: "SERVERURL" [2023-11-08 16:53:58.012 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Found API endpoints in plugin "Jellyfin.Plugin.Opds, Version=4.0.0.0, Culture=neutral, PublicKeyToken=null" [2023-11-08 16:53:58.013 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Found API endpoints in plugin "Jellyfin.Plugin.PlaybackReporting, Version=13.0.0.0, Culture=neutral, PublicKeyToken=null" [2023-11-08 16:53:58.013 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Found API endpoints in plugin "Jellyfin.Plugin.Reports, Version=15.0.0.0, Culture=neutral, PublicKeyToken=null" [2023-11-08 16:53:58.013 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Found API endpoints in plugin "MediaBrowser.Providers, Version=10.8.12.0, Culture=neutral, PublicKeyToken=null" [2023-11-08 16:53:59.166 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Jellyfin.Server.Implementations.Users.DefaultPasswordResetProvider" [2023-11-08 16:53:59.167 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Jellyfin.Server.Implementations.Users.DefaultAuthenticationProvider" [2023-11-08 16:53:59.167 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Jellyfin.Server.Implementations.Users.InvalidAuthProvider" [2023-11-08 16:54:29.994 -06:00] [DBG] [1] Emby.Server.Implementations.Plugins.PluginManager: Creating instance of "Jellyfin.Plugin.Bookshelf.Plugin" [2023-11-08 16:54:30.007 -06:00] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: "Bookshelf" "9.0.0.0" [2023-11-08 16:54:30.007 -06:00] [DBG] [1] Emby.Server.Implementations.Plugins.PluginManager: Creating instance of "Jellyfin.Plugin.Opds.OpdsPlugin" [2023-11-08 16:54:30.010 -06:00] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: "OPDS Feed" "4.0.0.0" [2023-11-08 16:54:30.010 -06:00] [DBG] [1] Emby.Server.Implementations.Plugins.PluginManager: Creating instance of "Jellyfin.Plugin.PlaybackReporting.Plugin" [2023-11-08 16:54:30.011 -06:00] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: "Playback Reporting" "13.0.0.0" [2023-11-08 16:54:30.012 -06:00] [DBG] [1] Emby.Server.Implementations.Plugins.PluginManager: Creating instance of "Jellyfin.Plugin.Reports.Plugin" [2023-11-08 16:54:30.012 -06:00] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: "Reports" "15.0.0.0" [2023-11-08 16:54:30.013 -06:00] [DBG] [1] Emby.Server.Implementations.Plugins.PluginManager: Creating instance of "MediaBrowser.Providers.Plugins.Tmdb.Plugin" [2023-11-08 16:54:30.013 -06:00] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: "TMDb" "10.8.12.0" [2023-11-08 16:54:30.013 -06:00] [DBG] [1] Emby.Server.Implementations.Plugins.PluginManager: Creating instance of "MediaBrowser.Providers.Plugins.StudioImages.Plugin" [2023-11-08 16:54:30.013 -06:00] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: "Studio Images" "10.8.12.0" [2023-11-08 16:54:30.013 -06:00] [DBG] [1] Emby.Server.Implementations.Plugins.PluginManager: Creating instance of "MediaBrowser.Providers.Plugins.Omdb.Plugin" [2023-11-08 16:54:30.013 -06:00] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: "OMDb" "10.8.12.0" [2023-11-08 16:54:30.014 -06:00] [DBG] [1] Emby.Server.Implementations.Plugins.PluginManager: Creating instance of "MediaBrowser.Providers.Plugins.MusicBrainz.Plugin" [2023-11-08 16:54:30.014 -06:00] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: "MusicBrainz" "10.8.12.0" [2023-11-08 16:54:30.014 -06:00] [DBG] [1] Emby.Server.Implementations.Plugins.PluginManager: Creating instance of "MediaBrowser.Providers.Plugins.AudioDb.Plugin" [2023-11-08 16:54:30.014 -06:00] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: "AudioDB" "10.8.12.0" [2023-11-08 16:54:30.015 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.CoreResolutionIgnoreRule" [2023-11-08 16:54:30.015 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.Resolvers.FolderResolver" [2023-11-08 16:54:30.015 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.Resolvers.PhotoAlbumResolver" [2023-11-08 16:54:30.015 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.Resolvers.PhotoResolver" [2023-11-08 16:54:30.015 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.Resolvers.PlaylistResolver" [2023-11-08 16:54:30.015 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.Resolvers.SpecialFolderResolver" [2023-11-08 16:54:30.016 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.Resolvers.TV.EpisodeResolver" [2023-11-08 16:54:30.016 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.Resolvers.TV.SeasonResolver" [2023-11-08 16:54:30.016 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.Resolvers.TV.SeriesResolver" [2023-11-08 16:54:30.016 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.Resolvers.Movies.BoxSetResolver" [2023-11-08 16:54:30.016 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.Resolvers.Movies.MovieResolver" [2023-11-08 16:54:30.016 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.Resolvers.Books.BookResolver" [2023-11-08 16:54:30.017 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.Resolvers.Audio.AudioResolver" [2023-11-08 16:54:30.017 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.Resolvers.Audio.MusicAlbumResolver" [2023-11-08 16:54:30.017 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.Resolvers.Audio.MusicArtistResolver" [2023-11-08 16:54:30.018 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.AiredEpisodeOrderComparer" [2023-11-08 16:54:30.018 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.AlbumArtistComparer" [2023-11-08 16:54:30.018 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.AlbumComparer" [2023-11-08 16:54:30.018 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.ArtistComparer" [2023-11-08 16:54:30.018 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.CommunityRatingComparer" [2023-11-08 16:54:30.018 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.CriticRatingComparer" [2023-11-08 16:54:30.018 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.DateCreatedComparer" [2023-11-08 16:54:30.018 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.DateLastMediaAddedComparer" [2023-11-08 16:54:30.018 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.DatePlayedComparer" [2023-11-08 16:54:30.018 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.IndexNumberComparer" [2023-11-08 16:54:30.018 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.IsFavoriteOrLikeComparer" [2023-11-08 16:54:30.019 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.IsFolderComparer" [2023-11-08 16:54:30.019 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.IsPlayedComparer" [2023-11-08 16:54:30.019 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.IsUnplayedComparer" [2023-11-08 16:54:30.019 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.NameComparer" [2023-11-08 16:54:30.019 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.OfficialRatingComparer" [2023-11-08 16:54:30.019 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.ParentIndexNumberComparer" [2023-11-08 16:54:30.019 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.PlayCountComparer" [2023-11-08 16:54:30.019 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.PremiereDateComparer" [2023-11-08 16:54:30.019 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.ProductionYearComparer" [2023-11-08 16:54:30.019 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.RandomComparer" [2023-11-08 16:54:30.019 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.RuntimeComparer" [2023-11-08 16:54:30.019 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.SeriesSortNameComparer" [2023-11-08 16:54:30.019 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.SortNameComparer" [2023-11-08 16:54:30.020 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.StartDateComparer" [2023-11-08 16:54:30.020 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Sorting.StudioComparer" [2023-11-08 16:54:30.020 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.SplashscreenPostScanTask" [2023-11-08 16:54:30.020 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.Validators.ArtistsPostScanTask" [2023-11-08 16:54:30.020 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.Validators.CollectionPostScanTask" [2023-11-08 16:54:30.020 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.Validators.GenresPostScanTask" [2023-11-08 16:54:30.021 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.Validators.MusicGenresPostScanTask" [2023-11-08 16:54:30.021 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Library.Validators.StudiosPostScanTask" [2023-11-08 16:54:30.021 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Data.CleanDatabaseScheduledTask" [2023-11-08 16:54:30.024 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Jellyfin.Plugin.Bookshelf.Providers.ComicBookImageProvider" [2023-11-08 16:54:30.025 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Jellyfin.Plugin.Bookshelf.Providers.GoogleBooks.GoogleBooksImageProvider" [2023-11-08 16:54:30.025 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Jellyfin.Plugin.Bookshelf.Providers.Epub.EpubMetadataImageProvider" [2023-11-08 16:54:30.025 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.Tmdb.TV.TmdbEpisodeImageProvider" [2023-11-08 16:54:30.063 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.Tmdb.TV.TmdbSeasonImageProvider" [2023-11-08 16:54:30.063 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.Tmdb.TV.TmdbSeriesImageProvider" [2023-11-08 16:54:30.063 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.Tmdb.People.TmdbPersonImageProvider" [2023-11-08 16:54:30.063 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.Tmdb.Movies.TmdbMovieImageProvider" [2023-11-08 16:54:30.064 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.Tmdb.BoxSets.TmdbBoxSetImageProvider" [2023-11-08 16:54:30.064 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.StudioImages.StudiosImageProvider" [2023-11-08 16:54:30.064 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.Omdb.OmdbImageProvider" [2023-11-08 16:54:30.065 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.AudioDb.AudioDbAlbumImageProvider" [2023-11-08 16:54:30.065 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.AudioDb.AudioDbArtistImageProvider" [2023-11-08 16:54:30.065 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.MediaInfo.AudioImageProvider" [2023-11-08 16:54:30.065 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.MediaInfo.EmbeddedImageProvider" [2023-11-08 16:54:30.065 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.MediaInfo.VideoImageProvider" [2023-11-08 16:54:30.066 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Channels.ChannelImageProvider" [2023-11-08 16:54:30.066 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.LocalMetadata.Images.CollectionFolderLocalImageProvider" [2023-11-08 16:54:30.066 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.LocalMetadata.Images.EpisodeLocalImageProvider" [2023-11-08 16:54:30.066 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.LocalMetadata.Images.InternalMetadataFolderImageProvider" [2023-11-08 16:54:30.066 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.LocalMetadata.Images.LocalImageProvider" [2023-11-08 16:54:30.066 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Years.YearMetadataService" [2023-11-08 16:54:30.067 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Videos.VideoMetadataService" [2023-11-08 16:54:30.067 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.TV.EpisodeMetadataService" [2023-11-08 16:54:30.067 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.TV.SeasonMetadataService" [2023-11-08 16:54:30.067 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.TV.SeriesMetadataService" [2023-11-08 16:54:30.068 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Studios.StudioMetadataService" [2023-11-08 16:54:30.068 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Playlists.PlaylistMetadataService" [2023-11-08 16:54:30.068 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Photos.PhotoAlbumMetadataService" [2023-11-08 16:54:30.068 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Photos.PhotoMetadataService" [2023-11-08 16:54:30.068 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.People.PersonMetadataService" [2023-11-08 16:54:30.068 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.MusicGenres.MusicGenreMetadataService" [2023-11-08 16:54:30.069 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Music.AlbumMetadataService" [2023-11-08 16:54:30.069 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Music.ArtistMetadataService" [2023-11-08 16:54:30.069 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Music.AudioMetadataService" [2023-11-08 16:54:30.069 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Music.MusicVideoMetadataService" [2023-11-08 16:54:30.069 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Movies.MovieMetadataService" [2023-11-08 16:54:30.069 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Movies.TrailerMetadataService" [2023-11-08 16:54:30.069 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.LiveTv.LiveTvMetadataService" [2023-11-08 16:54:30.070 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Genres.GenreMetadataService" [2023-11-08 16:54:30.070 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Folders.CollectionFolderMetadataService" [2023-11-08 16:54:30.070 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Folders.FolderMetadataService" [2023-11-08 16:54:30.070 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Folders.UserViewMetadataService" [2023-11-08 16:54:30.070 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Channels.ChannelMetadataService" [2023-11-08 16:54:30.070 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.BoxSets.BoxSetMetadataService" [2023-11-08 16:54:30.071 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Books.AudioBookMetadataService" [2023-11-08 16:54:30.071 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Books.BookMetadataService" [2023-11-08 16:54:30.071 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Jellyfin.Plugin.Bookshelf.Providers.BookProviderFromOpf" [2023-11-08 16:54:30.071 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Jellyfin.Plugin.Bookshelf.Providers.ComicFileProvider" [2023-11-08 16:54:30.072 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Jellyfin.Plugin.Bookshelf.Providers.GoogleBooks.GoogleBooksProvider" [2023-11-08 16:54:30.072 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Jellyfin.Plugin.Bookshelf.Providers.Epub.EpubMetadataProvider" [2023-11-08 16:54:30.072 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.Tmdb.TV.TmdbEpisodeProvider" [2023-11-08 16:54:30.072 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.Tmdb.TV.TmdbSeasonProvider" [2023-11-08 16:54:30.072 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.Tmdb.TV.TmdbSeriesProvider" [2023-11-08 16:54:30.073 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.Tmdb.People.TmdbPersonProvider" [2023-11-08 16:54:30.073 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.Tmdb.Movies.TmdbMovieProvider" [2023-11-08 16:54:30.073 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.Tmdb.BoxSets.TmdbBoxSetProvider" [2023-11-08 16:54:30.073 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.Omdb.OmdbEpisodeProvider" [2023-11-08 16:54:30.073 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.Omdb.OmdbItemProvider" [2023-11-08 16:54:30.073 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.AudioDb.AudioDbAlbumProvider" [2023-11-08 16:54:30.073 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.AudioDb.AudioDbArtistProvider" [2023-11-08 16:54:30.073 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Playlists.PlaylistItemsProvider" [2023-11-08 16:54:30.074 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Music.MusicBrainzAlbumProvider" [2023-11-08 16:54:30.102 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Music.MusicBrainzArtistProvider" [2023-11-08 16:54:30.102 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.MediaInfo.FFProbeProvider" [2023-11-08 16:54:30.104 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Photos.PhotoProvider" [2023-11-08 16:54:30.104 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Images.ArtistImageProvider" [2023-11-08 16:54:30.104 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Images.CollectionFolderImageProvider" [2023-11-08 16:54:30.104 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Images.DynamicImageProvider" [2023-11-08 16:54:30.105 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Images.FolderImageProvider" [2023-11-08 16:54:30.105 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Images.GenreImageProvider" [2023-11-08 16:54:30.105 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Images.MusicAlbumImageProvider" [2023-11-08 16:54:30.105 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Images.MusicGenreImageProvider" [2023-11-08 16:54:30.105 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Images.PhotoAlbumImageProvider" [2023-11-08 16:54:30.105 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Images.PlaylistImageProvider" [2023-11-08 16:54:30.105 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Collections.CollectionImageProvider" [2023-11-08 16:54:30.105 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.LocalMetadata.Providers.BoxSetXmlProvider" [2023-11-08 16:54:30.106 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.LocalMetadata.Providers.PlaylistXmlProvider" [2023-11-08 16:54:30.106 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.XbmcMetadata.Providers.AlbumNfoProvider" [2023-11-08 16:54:30.106 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.XbmcMetadata.Providers.ArtistNfoProvider" [2023-11-08 16:54:30.106 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.XbmcMetadata.Providers.EpisodeNfoProvider" [2023-11-08 16:54:30.107 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.XbmcMetadata.Providers.MovieNfoProvider" [2023-11-08 16:54:30.107 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.XbmcMetadata.Providers.MusicVideoNfoProvider" [2023-11-08 16:54:30.107 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.XbmcMetadata.Providers.SeasonNfoProvider" [2023-11-08 16:54:30.107 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.XbmcMetadata.Providers.SeriesNfoProvider" [2023-11-08 16:54:30.107 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.XbmcMetadata.Providers.VideoNfoProvider" [2023-11-08 16:54:30.110 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.LocalMetadata.Savers.BoxSetXmlSaver" [2023-11-08 16:54:30.110 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.LocalMetadata.Savers.PlaylistXmlSaver" [2023-11-08 16:54:30.110 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.XbmcMetadata.Savers.AlbumNfoSaver" [2023-11-08 16:54:30.111 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.XbmcMetadata.Savers.ArtistNfoSaver" [2023-11-08 16:54:30.111 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.XbmcMetadata.Savers.EpisodeNfoSaver" [2023-11-08 16:54:30.111 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.XbmcMetadata.Savers.MovieNfoSaver" [2023-11-08 16:54:30.111 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.XbmcMetadata.Savers.SeasonNfoSaver" [2023-11-08 16:54:30.111 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.XbmcMetadata.Savers.SeriesNfoSaver" [2023-11-08 16:54:30.112 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Jellyfin.Plugin.Bookshelf.Providers.ComicVine.ComicVineVolumeExternalId" [2023-11-08 16:54:30.112 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.TV.Zap2ItExternalId" [2023-11-08 16:54:30.112 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.Tmdb.TV.TmdbSeriesExternalId" [2023-11-08 16:54:30.112 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.Tmdb.People.TmdbPersonExternalId" [2023-11-08 16:54:30.112 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.Tmdb.Movies.TmdbMovieExternalId" [2023-11-08 16:54:30.112 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.Tmdb.BoxSets.TmdbBoxSetExternalId" [2023-11-08 16:54:30.112 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.AudioDb.AudioDbAlbumExternalId" [2023-11-08 16:54:30.112 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.AudioDb.AudioDbArtistExternalId" [2023-11-08 16:54:30.112 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.AudioDb.AudioDbOtherAlbumExternalId" [2023-11-08 16:54:30.113 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Plugins.AudioDb.AudioDbOtherArtistExternalId" [2023-11-08 16:54:30.113 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Music.ImvdbId" [2023-11-08 16:54:30.113 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Music.MusicBrainzAlbumArtistExternalId" [2023-11-08 16:54:30.113 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Music.MusicBrainzAlbumExternalId" [2023-11-08 16:54:30.113 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Music.MusicBrainzArtistExternalId" [2023-11-08 16:54:30.113 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Music.MusicBrainzOtherArtistExternalId" [2023-11-08 16:54:30.113 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Music.MusicBrainzReleaseGroupExternalId" [2023-11-08 16:54:30.113 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Music.MusicBrainzTrackId" [2023-11-08 16:54:30.113 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Movies.ImdbExternalId" [2023-11-08 16:54:30.113 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.Movies.ImdbPersonExternalId" [2023-11-08 16:54:30.115 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.LiveTv.EmbyTV.EmbyTV" [2023-11-08 16:54:30.117 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.LiveTv.TunerHosts.M3UTunerHost" [2023-11-08 16:54:30.118 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.LiveTv.TunerHosts.HdHomerun.HdHomerunHost" [2023-11-08 16:54:30.118 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.LiveTv.Listings.SchedulesDirect" [2023-11-08 16:54:30.119 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.LiveTv.Listings.XmlTvListingsProvider" [2023-11-08 16:54:30.122 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.LiveTv.LiveTvMediaSourceProvider" [2023-11-08 16:54:30.122 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Channels.ChannelDynamicMediaSourceProvider" [2023-11-08 16:54:30.123 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Notifications.CoreNotificationTypes" [2023-11-08 16:54:30.127 -06:00] [DBG] [1] Jellyfin.Server.Migrations.MigrationRunner: Skipping migration '"DisableTranscodingThrottling"' since it is already applied [2023-11-08 16:54:30.127 -06:00] [DBG] [1] Jellyfin.Server.Migrations.MigrationRunner: Skipping migration '"CreateLoggingConfigHeirarchy"' since it is already applied [2023-11-08 16:54:30.127 -06:00] [DBG] [1] Jellyfin.Server.Migrations.MigrationRunner: Skipping migration '"MigrateActivityLogDatabase"' since it is already applied [2023-11-08 16:54:30.127 -06:00] [DBG] [1] Jellyfin.Server.Migrations.MigrationRunner: Skipping migration '"RemoveDuplicateExtras"' since it is already applied [2023-11-08 16:54:30.127 -06:00] [DBG] [1] Jellyfin.Server.Migrations.MigrationRunner: Skipping migration '"AddDefaultPluginRepository"' since it is already applied [2023-11-08 16:54:30.127 -06:00] [DBG] [1] Jellyfin.Server.Migrations.MigrationRunner: Skipping migration '"MigrateUserDatabase"' since it is already applied [2023-11-08 16:54:30.127 -06:00] [DBG] [1] Jellyfin.Server.Migrations.MigrationRunner: Skipping migration '"ReaddDefaultPluginRepository"' since it is already applied [2023-11-08 16:54:30.127 -06:00] [DBG] [1] Jellyfin.Server.Migrations.MigrationRunner: Skipping migration '"MigrateDisplayPreferencesDatabase"' since it is already applied [2023-11-08 16:54:30.127 -06:00] [DBG] [1] Jellyfin.Server.Migrations.MigrationRunner: Skipping migration '"RemoveDownloadImagesInAdvance"' since it is already applied [2023-11-08 16:54:30.128 -06:00] [DBG] [1] Jellyfin.Server.Migrations.MigrationRunner: Skipping migration '"AddPeopleQueryIndex"' since it is already applied [2023-11-08 16:54:30.128 -06:00] [DBG] [1] Jellyfin.Server.Migrations.MigrationRunner: Skipping migration '"MigrateAuthenticationDatabase"' since it is already applied [2023-11-08 16:54:30.133 -06:00] [INF] [1] Main: Kestrel listening on "Any IP4 Address" [2023-11-08 16:54:30.673 -06:00] [INF] [1] Emby.Server.Implementations.ApplicationHost: Running startup tasks [2023-11-08 16:54:30.673 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Jellyfin.Plugin.PlaybackReporting.TaskCleanDb" [2023-11-08 16:54:30.673 -06:00] [INF] [1] Jellyfin.Plugin.PlaybackReporting.TaskCleanDb: TaskCleanDb Loaded [2023-11-08 16:54:30.675 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Jellyfin.Plugin.PlaybackReporting.TaskRunBackup" [2023-11-08 16:54:30.676 -06:00] [INF] [1] Jellyfin.Plugin.PlaybackReporting.TaskRunBackup: TaskRunBackup Loaded [2023-11-08 16:54:30.676 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.Providers.MediaInfo.SubtitleScheduledTask" [2023-11-08 16:54:30.676 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.ScheduledTasks.Tasks.ChapterImagesTask" [2023-11-08 16:54:30.676 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.ScheduledTasks.Tasks.CleanActivityLogTask" [2023-11-08 16:54:30.676 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.ScheduledTasks.Tasks.DeleteCacheFileTask" [2023-11-08 16:54:30.677 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.ScheduledTasks.Tasks.DeleteLogFileTask" [2023-11-08 16:54:30.677 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.ScheduledTasks.Tasks.DeleteTranscodeFileTask" [2023-11-08 16:54:30.677 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.ScheduledTasks.Tasks.OptimizeDatabaseTask" [2023-11-08 16:54:30.677 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.ScheduledTasks.Tasks.PeopleValidationTask" [2023-11-08 16:54:30.677 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.ScheduledTasks.Tasks.PluginUpdateTask" [2023-11-08 16:54:30.678 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.ScheduledTasks.Tasks.RefreshMediaLibraryTask" [2023-11-08 16:54:30.678 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.LiveTv.RefreshGuideScheduledTask" [2023-11-08 16:54:30.679 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.Channels.RefreshChannelsScheduledTask" [2023-11-08 16:54:30.679 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Jellyfin.MediaEncoding.Hls.ScheduledTasks.KeyframeExtractionScheduledTask" [2023-11-08 16:54:30.725 -06:00] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for "Playback Reporting Trim Db" set to fire at 2023-11-09 00:00:00.000 -06:00, which is 07:05:29.2748066 from now. [2023-11-08 16:54:30.767 -06:00] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for "Extract Chapter Images" set to fire at 2023-11-09 02:00:00.000 -06:00, which is 09:05:29.2326567 from now. [2023-11-08 16:54:30.859 -06:00] [DBG] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Running "/usr/lib/jellyfin-ffmpeg/ffmpeg" "-version" [2023-11-08 16:54:30.907 -06:00] [DBG] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: ffmpeg output: "ffmpeg version 5.1.3-Jellyfin Copyright (c) 2000-2022 the FFmpeg developers built with gcc 10 (Debian 10.2.1-6) configuration: --prefix=/usr/lib/jellyfin-ffmpeg --target-os=linux --extra-libs=-lfftw3f --extra-version=Jellyfin --disable-doc --disable-ffplay --disable-ptx-compression --disable-static --disable-libxcb --disable-sdl2 --disable-xlib --enable-lto --enable-gpl --enable-version3 --enable-shared --enable-gmp --enable-gnutls --enable-chromaprint --enable-libdrm --enable-libass --enable-libfreetype --enable-libfribidi --enable-libfontconfig --enable-libbluray --enable-libmp3lame --enable-libopus --enable-libtheora --enable-libvorbis --enable-libopenmpt --enable-libdav1d --enable-libwebp --enable-libvpx --enable-libx264 --enable-libx265 --enable-libzvbi --enable-libzimg --enable-libfdk-aac --arch=amd64 --enable-libsvtav1 --enable-libshaderc --enable-libplacebo --enable-vulkan --enable-opencl --enable-vaapi --enable-amf --enable-libmfx --enable-ffnvcodec --enable-cuda --enable-cuda-llvm --enable-cuvid --enable-nvdec --enable-nvenc libavutil 57. 28.100 / 57. 28.100 libavcodec 59. 37.100 / 59. 37.100 libavformat 59. 27.100 / 59. 27.100 libavdevice 59. 7.100 / 59. 7.100 libavfilter 8. 44.100 / 8. 44.100 libswscale 6. 7.100 / 6. 7.100 libswresample 4. 7.100 / 4. 7.100 libpostproc 56. 6.100 / 56. 6.100 " [2023-11-08 16:54:30.909 -06:00] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Found ffmpeg version "5.1.3" [2023-11-08 16:54:30.923 -06:00] [DBG] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Running "/usr/lib/jellyfin-ffmpeg/ffmpeg" "-decoders" [2023-11-08 16:54:30.961 -06:00] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available "decoders": ["libdav1d", "av1", "av1_cuvid", "av1_qsv", "h264", "h264_qsv", "h264_cuvid", "hevc", "hevc_qsv", "hevc_cuvid", "mpeg2video", "mpeg2_qsv", "mpeg2_cuvid", "mpeg4", "mpeg4_cuvid", "msmpeg4", "vc1_qsv", "vc1_cuvid", "vp8", "libvpx", "vp8_cuvid", "vp8_qsv", "vp9", "libvpx-vp9", "vp9_cuvid", "vp9_qsv", "aac", "ac3", "dca", "flac", "mp3", "truehd"] [2023-11-08 16:54:30.962 -06:00] [DBG] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Running "/usr/lib/jellyfin-ffmpeg/ffmpeg" "-encoders" [2023-11-08 16:54:30.998 -06:00] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available "encoders": ["libx264", "h264_amf", "h264_nvenc", "h264_qsv", "h264_v4l2m2m", "h264_vaapi", "libx265", "hevc_amf", "hevc_nvenc", "hevc_qsv", "hevc_vaapi", "mpeg4", "msmpeg4", "libvpx", "libvpx-vp9", "aac", "libfdk_aac", "ac3", "dca", "flac", "libmp3lame", "libopus", "truehd", "libvorbis", "srt"] [2023-11-08 16:54:30.999 -06:00] [DBG] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Running "/usr/lib/jellyfin-ffmpeg/ffmpeg" "-filters" [2023-11-08 16:54:31.036 -06:00] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available filters: ["deinterlace_qsv", "deinterlace_vaapi", "hwupload_cuda", "hwupload_vaapi", "overlay_opencl", "overlay_qsv", "overlay_vaapi", "overlay_cuda", "procamp_vaapi", "scale_cuda", "scale_opencl", "scale_qsv", "scale_vaapi", "tonemap_cuda", "tonemap_opencl", "tonemap_vaapi", "vpp_qsv", "yadif_cuda", "zscale", "alphasrc"] [2023-11-08 16:54:31.037 -06:00] [DBG] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Running "/usr/lib/jellyfin-ffmpeg/ffmpeg" "-h filter=scale_cuda" [2023-11-08 16:54:31.073 -06:00] [DBG] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Running "/usr/lib/jellyfin-ffmpeg/ffmpeg" "-h filter=tonemap_cuda" [2023-11-08 16:54:31.108 -06:00] [DBG] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Running "/usr/lib/jellyfin-ffmpeg/ffmpeg" "-h filter=tonemap_opencl" [2023-11-08 16:54:31.143 -06:00] [DBG] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Running "/usr/lib/jellyfin-ffmpeg/ffmpeg" "-h filter=overlay_opencl" [2023-11-08 16:54:31.180 -06:00] [DBG] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Running "/usr/lib/jellyfin-ffmpeg/ffmpeg" "-h filter=overlay_vaapi" [2023-11-08 16:54:31.216 -06:00] [DBG] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Running "/usr/lib/jellyfin-ffmpeg/ffmpeg" "-hwaccels" [2023-11-08 16:54:31.249 -06:00] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available hwaccel types: ["cuda", "vaapi", "qsv", "drm", "opencl", "vulkan"] [2023-11-08 16:54:31.250 -06:00] [DBG] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Running "/usr/lib/jellyfin-ffmpeg/ffmpeg" "-version" [2023-11-08 16:54:31.284 -06:00] [DBG] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: ffmpeg output: "ffmpeg version 5.1.3-Jellyfin Copyright (c) 2000-2022 the FFmpeg developers built with gcc 10 (Debian 10.2.1-6) configuration: --prefix=/usr/lib/jellyfin-ffmpeg --target-os=linux --extra-libs=-lfftw3f --extra-version=Jellyfin --disable-doc --disable-ffplay --disable-ptx-compression --disable-static --disable-libxcb --disable-sdl2 --disable-xlib --enable-lto --enable-gpl --enable-version3 --enable-shared --enable-gmp --enable-gnutls --enable-chromaprint --enable-libdrm --enable-libass --enable-libfreetype --enable-libfribidi --enable-libfontconfig --enable-libbluray --enable-libmp3lame --enable-libopus --enable-libtheora --enable-libvorbis --enable-libopenmpt --enable-libdav1d --enable-libwebp --enable-libvpx --enable-libx264 --enable-libx265 --enable-libzvbi --enable-libzimg --enable-libfdk-aac --arch=amd64 --enable-libsvtav1 --enable-libshaderc --enable-libplacebo --enable-vulkan --enable-opencl --enable-vaapi --enable-amf --enable-libmfx --enable-ffnvcodec --enable-cuda --enable-cuda-llvm --enable-cuvid --enable-nvdec --enable-nvenc libavutil 57. 28.100 / 57. 28.100 libavcodec 59. 37.100 / 59. 37.100 libavformat 59. 27.100 / 59. 27.100 libavdevice 59. 7.100 / 59. 7.100 libavfilter 8. 44.100 / 8. 44.100 libswscale 6. 7.100 / 6. 7.100 libswresample 4. 7.100 / 4. 7.100 libpostproc 56. 6.100 / 56. 6.100 " [2023-11-08 16:54:31.285 -06:00] [DBG] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Running "/usr/lib/jellyfin-ffmpeg/ffmpeg" "-hide_banner -f lavfi -i nullsrc=s=1x1:d=500 -f null -" [2023-11-08 16:54:31.469 -06:00] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: FFmpeg: "/usr/lib/jellyfin-ffmpeg/ffmpeg" [2023-11-08 16:54:31.483 -06:00] [INF] [1] Emby.Server.Implementations.ApplicationHost: ServerId: "fd0c74f7cf764961878656059fda19fd" [2023-11-08 16:54:31.483 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint" [2023-11-08 16:54:31.483 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.LiveTv.EmbyTV.EntryPoint" [2023-11-08 16:54:31.484 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.IO.LibraryMonitorStartup" [2023-11-08 16:54:31.484 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.EntryPoints.ExternalPortForwarding" [2023-11-08 16:54:31.484 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.EntryPoints.LibraryChangedNotifier" [2023-11-08 16:54:31.485 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.EntryPoints.RecordingNotifier" [2023-11-08 16:54:31.486 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.EntryPoints.UdpServerEntryPoint" [2023-11-08 16:54:31.486 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Server.Implementations.EntryPoints.UserDataChangeNotifier" [2023-11-08 16:54:31.486 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Dlna.Main.DlnaEntryPoint" [2023-11-08 16:54:31.487 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Emby.Notifications.NotificationEntryPoint" [2023-11-08 16:54:31.490 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "MediaBrowser.XbmcMetadata.EntryPoint" [2023-11-08 16:54:31.490 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Creating instance of "Jellyfin.Server.Implementations.Users.DeviceAccessEntryPoint" [2023-11-08 16:54:31.492 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Starting entry point "Emby.Dlna.Main.DlnaEntryPoint" [2023-11-08 16:54:31.625 -06:00] [INF] [1] Emby.Server.Implementations.ApplicationHost: Executed all pre-startup entry points in 0:00:00.1337714 [2023-11-08 16:54:31.625 -06:00] [INF] [1] Emby.Server.Implementations.ApplicationHost: Core startup complete [2023-11-08 16:54:31.625 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Starting entry point "Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint" [2023-11-08 16:54:31.625 -06:00] [INF] [1] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: EventMonitorEntryPoint Running [2023-11-08 16:54:31.631 -06:00] [INF] [1] Jellyfin.Plugin.PlaybackReporting.Data.ActivityRepository: Sqlite version: 3.38.3 [2023-11-08 16:54:31.632 -06:00] [INF] [1] Jellyfin.Plugin.PlaybackReporting.Data.ActivityRepository: Sqlite compiler options: ATOMIC_INTRINSICS=1,COMPILER=gcc-7.5.0,DEFAULT_AUTOVACUUM,DEFAULT_CACHE_SIZE=-2000,DEFAULT_FILE_FORMAT=4,DEFAULT_FOREIGN_KEYS,DEFAULT_JOURNAL_SIZE_LIMIT=-1,DEFAULT_MMAP_SIZE=0,DEFAULT_PAGE_SIZE=4096,DEFAULT_PCACHE_INITSZ=20,DEFAULT_RECURSIVE_TRIGGERS,DEFAULT_SECTOR_SIZE=4096,DEFAULT_SYNCHRONOUS=2,DEFAULT_WAL_AUTOCHECKPOINT=1000,DEFAULT_WAL_SYNCHRONOUS=2,DEFAULT_WORKER_THREADS=0,ENABLE_COLUMN_METADATA,ENABLE_FTS3,ENABLE_FTS3_PARENTHESIS,ENABLE_FTS4,ENABLE_FTS5,ENABLE_RTREE,ENABLE_SNAPSHOT,MALLOC_SOFT_LIMIT=1024,MAX_ATTACHED=10,MAX_COLUMN=2000,MAX_COMPOUND_SELECT=500,MAX_DEFAULT_PAGE_SIZE=8192,MAX_EXPR_DEPTH=1000,MAX_FUNCTION_ARG=127,MAX_LENGTH=1000000000,MAX_LIKE_PATTERN_LENGTH=50000,MAX_MMAP_SIZE=0x7fff0000,MAX_PAGE_COUNT=1073741823,MAX_PAGE_SIZE=65536,MAX_SQL_LENGTH=1000000000,MAX_TRIGGER_DEPTH=1000,MAX_VARIABLE_NUMBER=32766,MAX_VDBE_OP=250000000,MAX_WORKER_THREADS=8,MUTEX_PTHREADS,SYSTEM_MALLOC,TEMP_STORE=1,THREADSAFE=1 [2023-11-08 16:54:31.636 -06:00] [INF] [1] Jellyfin.Plugin.PlaybackReporting.Data.ActivityRepository: Default journal_mode for "/config/data/playback_reporting.db" is "delete" [2023-11-08 16:54:31.636 -06:00] [INF] [1] Jellyfin.Plugin.PlaybackReporting.Data.ActivityRepository: Initialize PlaybackActivity Repository [2023-11-08 16:54:31.637 -06:00] [INF] [1] Jellyfin.Plugin.PlaybackReporting.Data.ActivityRepository: PlaybackActivity table schema OK [2023-11-08 16:54:31.637 -06:00] [INF] [1] Jellyfin.Plugin.PlaybackReporting.Data.ActivityRepository: Expected : "datecreated:datetime|userid:text|itemid:text|itemtype:text|itemname:text|playbackmethod:text|clientname:text|devicename:text|playduration:int" [2023-11-08 16:54:31.637 -06:00] [INF] [1] Jellyfin.Plugin.PlaybackReporting.Data.ActivityRepository: Received : "datecreated:datetime|userid:text|itemid:text|itemtype:text|itemname:text|playbackmethod:text|clientname:text|devicename:text|playduration:int" [2023-11-08 16:54:31.639 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Starting entry point "Emby.Server.Implementations.LiveTv.EmbyTV.EntryPoint" [2023-11-08 16:54:31.649 -06:00] [DBG] [1] Emby.Server.Implementations.Library.LibraryManager: Getting topLibraryFolders [2023-11-08 16:54:31.649 -06:00] [DBG] [1] Emby.Server.Implementations.Library.LibraryManager: Creating userRootPath at "/config/root/default" [2023-11-08 16:54:31.681 -06:00] [DBG] [1] Emby.Server.Implementations.Library.LibraryManager: Setting userRootFolder: "Media Folders" [2023-11-08 16:54:31.744 -06:00] [DBG] [1] Emby.Server.Implementations.Data.SqliteItemRepository: "GetItemList" query time (slow): 58.7834ms. Query: "select type,data,StartDate,EndDate,ChannelId,IsMovie,IsSeries,EpisodeTitle,IsRepeat,CommunityRating,CustomRating,IndexNumber,IsLocked,PreferredMetadataLanguage,PreferredMetadataCountryCode,Width,Height,DateLastRefreshed,Name,Path,PremiereDate,Overview,ParentIndexNumber,ProductionYear,OfficialRating,ForcedSortName,RunTimeTicks,Size,DateCreated,DateModified,guid,Genres,ParentId,Audio,ExternalServiceId,IsInMixedFolder,DateLastSaved,LockedFields,Studios,Tags,TrailerTypes,OriginalTitle,PrimaryVersionId,DateLastMediaAdded,Album,CriticRating,IsVirtualItem,SeriesName,SeasonName,SeasonId,SeriesId,PresentationUniqueKey,InheritedParentalRatingValue,ExternalSeriesId,Tagline,ProviderIds,Images,ProductionLocations,ExtraIds,TotalBitrate,ExtraType,Artists,AlbumArtists,ExternalId,SeriesPresentationUniqueKey,ShowId,OwnerId from TypedBaseItems A where ParentId=@ParentId" [2023-11-08 16:54:31.746 -06:00] [DBG] [1] Emby.Server.Implementations.Library.LibraryManager: Getting refreshQueue [2023-11-08 16:54:31.849 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Starting entry point "Emby.Server.Implementations.IO.LibraryMonitorStartup" [2023-11-08 16:54:31.866 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Starting entry point "Emby.Server.Implementations.EntryPoints.ExternalPortForwarding" [2023-11-08 16:54:31.867 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Starting entry point "Emby.Server.Implementations.EntryPoints.LibraryChangedNotifier" [2023-11-08 16:54:31.869 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Starting entry point "Emby.Server.Implementations.EntryPoints.RecordingNotifier" [2023-11-08 16:54:31.870 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Starting entry point "Emby.Server.Implementations.EntryPoints.UdpServerEntryPoint" [2023-11-08 16:54:31.872 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Starting entry point "Emby.Server.Implementations.EntryPoints.UserDataChangeNotifier" [2023-11-08 16:54:31.872 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Starting entry point "Emby.Notifications.NotificationEntryPoint" [2023-11-08 16:54:31.873 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Starting entry point "MediaBrowser.XbmcMetadata.EntryPoint" [2023-11-08 16:54:31.873 -06:00] [DBG] [1] Emby.Server.Implementations.ApplicationHost: Starting entry point "Jellyfin.Server.Implementations.Users.DeviceAccessEntryPoint" [2023-11-08 16:54:31.873 -06:00] [INF] [1] Emby.Server.Implementations.ApplicationHost: Executed all post-startup entry points in 0:00:00.2486998 [2023-11-08 16:54:31.873 -06:00] [INF] [1] Main: Startup complete 0:00:35.3813657 [2023-11-08 16:54:33.778 -06:00] [INF] [15] Emby.Server.Implementations.ScheduledTasks.TaskManager: "StartupTrigger" fired for task: "Update Plugins" [2023-11-08 16:54:33.779 -06:00] [INF] [15] Emby.Server.Implementations.ScheduledTasks.TaskManager: Queuing task "PluginUpdateTask" [2023-11-08 16:54:33.782 -06:00] [INF] [16] Emby.Server.Implementations.ScheduledTasks.TaskManager: Executing "Update Plugins" [2023-11-08 16:54:34.823 -06:00] [INF] [20] Emby.Server.Implementations.ScheduledTasks.TaskManager: "Update Plugins" Completed after 0 minute(s) and 1 seconds [2023-11-08 16:54:34.829 -06:00] [INF] [20] Emby.Server.Implementations.ScheduledTasks.TaskManager: ExecuteQueuedTasks [2023-11-08 16:54:56.490 -06:00] [DBG] [15] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 16:54:56.813 -06:00] [WRN] [17] Microsoft.EntityFrameworkCore.Model.Validation: The entity type '"AccessSchedule"' is configured to use schema '"jellyfin"', but SQLite does not support schemas. This configuration will be ignored by the SQLite provider. [2023-11-08 16:54:56.813 -06:00] [WRN] [17] Microsoft.EntityFrameworkCore.Model.Validation: The entity type '"ActivityLog"' is configured to use schema '"jellyfin"', but SQLite does not support schemas. This configuration will be ignored by the SQLite provider. [2023-11-08 16:54:56.813 -06:00] [WRN] [17] Microsoft.EntityFrameworkCore.Model.Validation: The entity type '"CustomItemDisplayPreferences"' is configured to use schema '"jellyfin"', but SQLite does not support schemas. This configuration will be ignored by the SQLite provider. [2023-11-08 16:54:56.813 -06:00] [WRN] [17] Microsoft.EntityFrameworkCore.Model.Validation: The entity type '"DisplayPreferences"' is configured to use schema '"jellyfin"', but SQLite does not support schemas. This configuration will be ignored by the SQLite provider. [2023-11-08 16:54:56.813 -06:00] [WRN] [17] Microsoft.EntityFrameworkCore.Model.Validation: The entity type '"HomeSection"' is configured to use schema '"jellyfin"', but SQLite does not support schemas. This configuration will be ignored by the SQLite provider. [2023-11-08 16:54:56.813 -06:00] [WRN] [17] Microsoft.EntityFrameworkCore.Model.Validation: The entity type '"ImageInfo"' is configured to use schema '"jellyfin"', but SQLite does not support schemas. This configuration will be ignored by the SQLite provider. [2023-11-08 16:54:56.813 -06:00] [WRN] [17] Microsoft.EntityFrameworkCore.Model.Validation: The entity type '"ItemDisplayPreferences"' is configured to use schema '"jellyfin"', but SQLite does not support schemas. This configuration will be ignored by the SQLite provider. [2023-11-08 16:54:56.813 -06:00] [WRN] [17] Microsoft.EntityFrameworkCore.Model.Validation: The entity type '"Permission"' is configured to use schema '"jellyfin"', but SQLite does not support schemas. This configuration will be ignored by the SQLite provider. [2023-11-08 16:54:56.813 -06:00] [WRN] [17] Microsoft.EntityFrameworkCore.Model.Validation: The entity type '"Preference"' is configured to use schema '"jellyfin"', but SQLite does not support schemas. This configuration will be ignored by the SQLite provider. [2023-11-08 16:54:56.813 -06:00] [WRN] [17] Microsoft.EntityFrameworkCore.Model.Validation: The entity type '"ApiKey"' is configured to use schema '"jellyfin"', but SQLite does not support schemas. This configuration will be ignored by the SQLite provider. [2023-11-08 16:54:56.813 -06:00] [WRN] [17] Microsoft.EntityFrameworkCore.Model.Validation: The entity type '"Device"' is configured to use schema '"jellyfin"', but SQLite does not support schemas. This configuration will be ignored by the SQLite provider. [2023-11-08 16:54:56.813 -06:00] [WRN] [17] Microsoft.EntityFrameworkCore.Model.Validation: The entity type '"DeviceOptions"' is configured to use schema '"jellyfin"', but SQLite does not support schemas. This configuration will be ignored by the SQLite provider. [2023-11-08 16:54:56.813 -06:00] [WRN] [17] Microsoft.EntityFrameworkCore.Model.Validation: The entity type '"User"' is configured to use schema '"jellyfin"', but SQLite does not support schemas. This configuration will be ignored by the SQLite provider. [2023-11-08 16:55:27.131 -06:00] [DBG] [3] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 16:55:57.348 -06:00] [DBG] [15] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 16:56:27.565 -06:00] [DBG] [25] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 16:56:57.784 -06:00] [DBG] [27] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 16:57:28.032 -06:00] [DBG] [23] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 16:57:58.243 -06:00] [DBG] [11] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 16:58:08.391 -06:00] [DBG] [26] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 16:58:08.401 -06:00] [DBG] [26] Jellyfin.Networking.Manager.NetworkManager: GetBindInterface: Source: True, External: False: [2023-11-08 16:58:08.403 -06:00] [DBG] [26] Jellyfin.Networking.Manager.NetworkManager: "10.6.0.1/32": GetBindInterface: Matched first internal interface. "127.0.0.1" [2023-11-08 16:58:08.432 -06:00] [DBG] [8] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 16:58:08.535 -06:00] [DBG] [8] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 16:58:08.538 -06:00] [DBG] [8] Jellyfin.Networking.Manager.NetworkManager: GetBindInterface: Source: True, External: False: [2023-11-08 16:58:08.538 -06:00] [DBG] [8] Jellyfin.Networking.Manager.NetworkManager: "10.6.0.1/32": GetBindInterface: Matched first internal interface. "127.0.0.1" [2023-11-08 16:58:08.907 -06:00] [DBG] [3] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:08.911 -06:00] [DBG] [3] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:08.918 -06:00] [DBG] [3] Jellyfin.Networking.Manager.NetworkManager: GetBindInterface: Source: True, External: False: [2023-11-08 16:58:08.918 -06:00] [DBG] [3] Jellyfin.Networking.Manager.NetworkManager: "10.6.0.1/32": GetBindInterface: Matched first internal interface. "127.0.0.1" [2023-11-08 16:58:08.971 -06:00] [DBG] [8] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:08.971 -06:00] [DBG] [3] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:08.971 -06:00] [DBG] [22] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:08.971 -06:00] [DBG] [8] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:08.971 -06:00] [DBG] [3] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:08.978 -06:00] [INF] [22] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "10.6.0.1" request [2023-11-08 16:58:09.120 -06:00] [DBG] [26] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:09.120 -06:00] [DBG] [26] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:09.184 -06:00] [DBG] [26] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:09.187 -06:00] [DBG] [26] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:09.269 -06:00] [DBG] [3] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:09.269 -06:00] [DBG] [3] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:09.273 -06:00] [DBG] [26] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:09.273 -06:00] [DBG] [26] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:09.274 -06:00] [DBG] [22] Emby.Server.Implementations.Session.SessionManager: Creating new "WebSocketController" [2023-11-08 16:58:09.274 -06:00] [DBG] [22] Emby.Server.Implementations.Session.WebSocketController: Adding websocket to session "333d5db2aa3580a3f6da396fb9b91d98" [2023-11-08 16:58:09.284 -06:00] [DBG] [22] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 16:58:09.306 -06:00] [DBG] [22] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: KeepAlive, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: null } [2023-11-08 16:58:09.311 -06:00] [DBG] [3] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:09.312 -06:00] [DBG] [3] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:11.908 -06:00] [DBG] [22] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:11.908 -06:00] [DBG] [22] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:13.151 -06:00] [DBG] [3] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:13.152 -06:00] [DBG] [3] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:15.246 -06:00] [DBG] [22] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:15.247 -06:00] [DBG] [22] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 16:58:21.286 -06:00] [DBG] [3] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 16:58:28.503 -06:00] [DBG] [8] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 16:58:33.287 -06:00] [DBG] [8] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 16:58:39.511 -06:00] [DBG] [24] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: KeepAlive, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: null } [2023-11-08 16:58:45.288 -06:00] [DBG] [10] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 16:58:57.288 -06:00] [DBG] [10] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 16:58:58.718 -06:00] [DBG] [10] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 16:59:09.289 -06:00] [DBG] [11] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 16:59:09.303 -06:00] [DBG] [11] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: KeepAlive, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: null } [2023-11-08 16:59:21.290 -06:00] [DBG] [24] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 16:59:28.994 -06:00] [DBG] [29] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 16:59:33.291 -06:00] [DBG] [29] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 16:59:39.316 -06:00] [DBG] [10] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: KeepAlive, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: null } [2023-11-08 16:59:45.292 -06:00] [DBG] [30] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 16:59:57.293 -06:00] [DBG] [29] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 16:59:59.278 -06:00] [DBG] [31] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 17:00:09.292 -06:00] [DBG] [30] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:00:09.316 -06:00] [DBG] [8] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: KeepAlive, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: null } [2023-11-08 17:00:21.293 -06:00] [DBG] [8] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:00:29.536 -06:00] [DBG] [33] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 17:00:33.294 -06:00] [DBG] [34] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:00:37.545 -06:00] [DBG] [33] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: SessionsStart, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: JsonElement { ValueKind: String } } [2023-11-08 17:00:37.547 -06:00] [DBG] [33] Jellyfin.Api.WebSocketListeners.SessionInfoWebSocketListener: WS "10.6.0.1" begin transmitting to "SessionInfoWebSocketListener" [2023-11-08 17:00:37.547 -06:00] [DBG] [8] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:00:37.547 -06:00] [DBG] [34] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:00:37.547 -06:00] [DBG] [34] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:00:37.547 -06:00] [DBG] [8] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:00:37.547 -06:00] [DBG] [33] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: ScheduledTasksInfoStart, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: JsonElement { ValueKind: String } } [2023-11-08 17:00:37.547 -06:00] [DBG] [33] Jellyfin.Api.WebSocketListeners.ScheduledTasksWebSocketListener: WS "10.6.0.1" begin transmitting to "ScheduledTasksWebSocketListener" [2023-11-08 17:00:37.547 -06:00] [DBG] [32] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:00:37.548 -06:00] [DBG] [32] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:00:37.548 -06:00] [DBG] [33] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: ActivityLogEntryStart, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: JsonElement { ValueKind: String } } [2023-11-08 17:00:37.548 -06:00] [DBG] [33] Jellyfin.Api.WebSocketListeners.ActivityLogWebSocketListener: WS "10.6.0.1" begin transmitting to "ActivityLogWebSocketListener" [2023-11-08 17:00:37.548 -06:00] [DBG] [33] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: ActivityLogEntryStart, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: JsonElement { ValueKind: String } } [2023-11-08 17:00:37.549 -06:00] [DBG] [33] Jellyfin.Api.WebSocketListeners.ActivityLogWebSocketListener: WS "10.6.0.1" begin transmitting to "ActivityLogWebSocketListener" [2023-11-08 17:00:37.549 -06:00] [DBG] [33] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: ScheduledTasksInfoStart, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: JsonElement { ValueKind: String } } [2023-11-08 17:00:37.549 -06:00] [DBG] [33] Jellyfin.Api.WebSocketListeners.ScheduledTasksWebSocketListener: WS "10.6.0.1" begin transmitting to "ScheduledTasksWebSocketListener" [2023-11-08 17:00:37.550 -06:00] [DBG] [33] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:00:37.550 -06:00] [DBG] [33] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:00:37.574 -06:00] [DBG] [34] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:00:37.574 -06:00] [DBG] [34] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:00:37.574 -06:00] [DBG] [34] Jellyfin.Networking.Manager.NetworkManager: GetBindInterface: Source: True, External: False: [2023-11-08 17:00:37.574 -06:00] [DBG] [34] Jellyfin.Networking.Manager.NetworkManager: "10.6.0.1/32": GetBindInterface: Matched first internal interface. "127.0.0.1" [2023-11-08 17:00:37.575 -06:00] [DBG] [34] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:00:37.576 -06:00] [DBG] [34] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:00:37.601 -06:00] [DBG] [32] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:00:37.601 -06:00] [DBG] [32] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:00:39.300 -06:00] [DBG] [33] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: KeepAlive, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: null } [2023-11-08 17:00:45.294 -06:00] [DBG] [33] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:00:57.295 -06:00] [DBG] [34] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:00:59.756 -06:00] [DBG] [33] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 17:01:09.295 -06:00] [DBG] [33] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:01:09.310 -06:00] [DBG] [31] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: KeepAlive, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: null } [2023-11-08 17:01:21.297 -06:00] [DBG] [31] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:01:29.971 -06:00] [DBG] [27] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 17:01:33.298 -06:00] [DBG] [33] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:01:39.307 -06:00] [DBG] [27] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: KeepAlive, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: null } [2023-11-08 17:01:45.299 -06:00] [DBG] [27] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:01:57.298 -06:00] [DBG] [10] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:02:00.577 -06:00] [DBG] [25] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 17:02:09.299 -06:00] [DBG] [22] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:02:10.255 -06:00] [DBG] [10] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: KeepAlive, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: null } [2023-11-08 17:02:21.300 -06:00] [DBG] [21] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:02:30.801 -06:00] [DBG] [3] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 17:02:33.301 -06:00] [DBG] [10] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:02:39.312 -06:00] [DBG] [10] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: KeepAlive, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: null } [2023-11-08 17:02:45.300 -06:00] [DBG] [35] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:02:57.300 -06:00] [DBG] [35] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:03:01.026 -06:00] [DBG] [38] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 17:03:09.300 -06:00] [DBG] [35] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:03:09.317 -06:00] [DBG] [10] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: KeepAlive, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: null } [2023-11-08 17:03:21.301 -06:00] [DBG] [40] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:03:31.252 -06:00] [DBG] [42] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 17:03:33.301 -06:00] [DBG] [42] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:03:39.312 -06:00] [DBG] [42] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: KeepAlive, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: null } [2023-11-08 17:03:45.301 -06:00] [DBG] [10] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:03:47.728 -06:00] [DBG] [42] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:47.728 -06:00] [DBG] [42] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:47.784 -06:00] [DBG] [42] Emby.Server.Implementations.Data.SqliteItemRepository: "GetItemList" query time (slow): 29.4609ms. Query: "select type,data,EndDate,ChannelId,CommunityRating,IndexNumber,Width,Height,Name,Path,PremiereDate,Overview,ParentIndexNumber,ProductionYear,OfficialRating,RunTimeTicks,Size,DateModified,guid,ParentId,Audio,IsInMixedFolder,PrimaryVersionId,Album,CriticRating,IsVirtualItem,SeriesName,SeasonName,SeasonId,SeriesId,ProviderIds,Images,TotalBitrate,ExtraType,ExternalId,OwnerId from TypedBaseItems A where type=@type AND IsVirtualItem=@IsVirtualItem AND SeriesPresentationUniqueKey=@SeriesPresentationUniqueKey Group by PresentationUniqueKey ORDER BY SortName ASC" [2023-11-08 17:03:48.239 -06:00] [DBG] [10] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:48.239 -06:00] [DBG] [10] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:48.325 -06:00] [DBG] [10] Jellyfin.Api.Controllers.MediaInfoController: GetPostedPlaybackInfo profile: DeviceProfile { Name: "AndroidTV-libVLC", Id: null, Identification: null, FriendlyName: null, Manufacturer: null, ManufacturerUrl: null, ModelName: null, ModelDescription: null, ModelNumber: null, ModelUrl: null, SerialNumber: null, EnableAlbumArtInDidl: False, EnableSingleAlbumArtLimit: False, EnableSingleSubtitleLimit: False, SupportedMediaTypes: "Audio,Photo,Video", UserId: null, AlbumArtPn: null, MaxAlbumArtWidth: 0, MaxAlbumArtHeight: 0, MaxIconWidth: null, MaxIconHeight: null, MaxStreamingBitrate: 20000000, MaxStaticBitrate: 100000000, MusicStreamingTranscodingBitrate: 128000, MaxStaticMusicBitrate: 8000000, SonyAggregationFlags: null, ProtocolInfo: null, TimelineOffsetSeconds: 0, RequiresPlainVideoItems: False, RequiresPlainFolders: False, EnableMSMediaReceiverRegistrar: False, IgnoreTranscodeByteRangeRequests: False, XmlRootAttributes: [], DirectPlayProfiles: [DirectPlayProfile { Container: "m4v,3gp,ts,mpegts,mov,xvid,vob,mkv,wmv,asf,ogm,ogv,m2v,avi,mpg,mpeg,mp4,webm,wtv", AudioCodec: "aac,mp3,mp2,ac3,eac3,wma,wmav2,dca,dts,pcm,pcm_s16le,pcm_s24le,opus,flac,truehd", VideoCodec: null, Type: Video }, DirectPlayProfile { Container: "flac,aac,mp3,mpa,wav,wma,mp2,ogg,oga,webma,ape", AudioCodec: null, VideoCodec: null, Type: Audio }, DirectPlayProfile { Container: "jpg,jpeg,png,gif,webp", AudioCodec: null, VideoCodec: null, Type: Photo }], TranscodingProfiles: [TranscodingProfile { Container: "mkv", Type: Video, VideoCodec: "h264", AudioCodec: "aac,mp3", Protocol: "", EstimateContentLength: False, EnableMpegtsM2TsMode: False, TranscodeSeekInfo: Auto, CopyTimestamps: True, Context: Streaming, EnableSubtitlesInManifest: False, MaxAudioChannels: null, MinSegments: 0, SegmentLength: 0, BreakOnNonKeyFrames: False, Conditions: [] }, TranscodingProfile { Container: "mp3", Type: Audio, VideoCodec: "", AudioCodec: "mp3", Protocol: "", EstimateContentLength: False, EnableMpegtsM2TsMode: False, TranscodeSeekInfo: Auto, CopyTimestamps: False, Context: Streaming, EnableSubtitlesInManifest: False, MaxAudioChannels: null, MinSegments: 0, SegmentLength: 0, BreakOnNonKeyFrames: False, Conditions: [] }], ContainerProfiles: [ContainerProfile { Type: Video, Conditions: [ProfileCondition { Condition: NotEquals, Property: VideoCodecTag, Value: "xvid", IsRequired: False }], Container: "avi" }], CodecProfiles: [CodecProfile { Type: Video, Conditions: [ProfileCondition { Condition: NotEquals, Property: VideoProfile, Value: "none", IsRequired: False }], ApplyConditions: [], Codec: "hevc", Container: null }, CodecProfile { Type: Video, Conditions: [ProfileCondition { Condition: EqualsAny, Property: VideoProfile, Value: "high|main|baseline|constrained baseline", IsRequired: False }, ProfileCondition { Condition: LessThanEqual, Property: VideoLevel, Value: "41", IsRequired: False }], ApplyConditions: [], Codec: "h264", Container: null }, CodecProfile { Type: VideoAudio, Conditions: [ProfileCondition { Condition: LessThanEqual, Property: AudioChannels, Value: "8", IsRequired: False }], ApplyConditions: [], Codec: null, Container: null }], ResponseProfiles: [], SubtitleProfiles: [SubtitleProfile { Format: "srt", Method: External, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "srt", Method: Embed, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "subrip", Method: Embed, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "ass", Method: Embed, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "ssa", Method: Embed, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "pgs", Method: Embed, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "pgssub", Method: Embed, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "dvdsub", Method: Embed, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "vtt", Method: Embed, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "sub", Method: Embed, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "smi", Method: Embed, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "idx", Method: Embed, DidlMode: null, Language: null, Container: null }] } [2023-11-08 17:03:48.382 -06:00] [INF] [10] Jellyfin.Api.Helpers.MediaInfoHelper: User policy for "Peter". EnablePlaybackRemuxing: True EnableVideoPlaybackTranscoding: True EnableAudioPlaybackTranscoding: True [2023-11-08 17:03:48.389 -06:00] [DBG] [10] Jellyfin.Api.Helpers.MediaInfoHelper: Profile: "AndroidTV-libVLC", Path: "/TV SHOWS/TV Shows/Taskmaster (2015)/Season 1/Taskmaster - S01E02 - The Pie Whisperer.m4v", isEligibleForDirectPlay: True, isEligibleForDirectStream: False [2023-11-08 17:03:48.397 -06:00] [DBG] [10] Jellyfin.Api.Helpers.MediaInfoHelper: Profile: "VideoCodecProfile", DirectPlay=false. Reason="AndroidTV-libVLC".VideoLevel Condition: LessThanEqual. ConditionValue: "41". IsRequired: False. Path: "/TV SHOWS/TV Shows/Taskmaster (2015)/Season 1/Taskmaster - S01E02 - The Pie Whisperer.m4v" [2023-11-08 17:03:48.419 -06:00] [DBG] [10] Jellyfin.Api.Helpers.MediaInfoHelper: DirectPlay Result for Profile: "AndroidTV-libVLC", Path: "/TV SHOWS/TV Shows/Taskmaster (2015)/Season 1/Taskmaster - S01E02 - The Pie Whisperer.m4v", PlayMethod: null, AudioStreamIndex: 2, SubtitleStreamIndex: null, Reasons: VideoLevelNotSupported [2023-11-08 17:03:48.452 -06:00] [DBG] [10] Jellyfin.Api.Helpers.MediaInfoHelper: Transcode Result for Profile: "AndroidTV-libVLC", Path: "/TV SHOWS/TV Shows/Taskmaster (2015)/Season 1/Taskmaster - S01E02 - The Pie Whisperer.m4v", PlayMethod: Transcode, AudioStreamIndex: 2, SubtitleStreamIndex: null, Reasons: VideoLevelNotSupported [2023-11-08 17:03:48.465 -06:00] [INF] [10] Jellyfin.Api.Helpers.MediaInfoHelper: StreamBuilder.BuildVideoItem( Profile="AndroidTV-libVLC", Path="/TV SHOWS/TV Shows/Taskmaster (2015)/Season 1/Taskmaster - S01E02 - The Pie Whisperer.m4v", AudioStreamIndex=null, SubtitleStreamIndex=null ) => ( PlayMethod=Transcode, TranscodeReason=VideoLevelNotSupported ) "media:/videos/0e609ca5-e463-91f6-061b-e2a581668b9d/stream.mkv?MediaSourceId=0e609ca5e46391f6061be2a581668b9d&VideoCodec=h264,h264&AudioCodec=aac&AudioStreamIndex=2&VideoBitrate=99872000&AudioBitrate=128000&AudioSampleRate=48000&MaxFramerate=25&api_key=&CopyTimestamps=true&RequireAvc=false&Tag=f31c5075d0c314e6a58887839479d1c7&h264-level=41&h264-videobitdepth=8&h264-profile=main&h264-audiochannels=2&aac-profile=he-aac&aac-audiochannels=8&TranscodeReasons=VideoLevelNotSupported" [2023-11-08 17:03:48.501 -06:00] [DBG] [10] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:48.505 -06:00] [DBG] [10] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:48.509 -06:00] [DBG] [10] Jellyfin.Api.Controllers.MediaInfoController: GetPostedPlaybackInfo profile: DeviceProfile { Name: "AndroidTV-ExoPlayer", Id: null, Identification: null, FriendlyName: null, Manufacturer: null, ManufacturerUrl: null, ModelName: null, ModelDescription: null, ModelNumber: null, ModelUrl: null, SerialNumber: null, EnableAlbumArtInDidl: False, EnableSingleAlbumArtLimit: False, EnableSingleSubtitleLimit: False, SupportedMediaTypes: "Audio,Photo,Video", UserId: null, AlbumArtPn: null, MaxAlbumArtWidth: 0, MaxAlbumArtHeight: 0, MaxIconWidth: null, MaxIconHeight: null, MaxStreamingBitrate: 20000000, MaxStaticBitrate: 100000000, MusicStreamingTranscodingBitrate: 128000, MaxStaticMusicBitrate: 8000000, SonyAggregationFlags: null, ProtocolInfo: null, TimelineOffsetSeconds: 0, RequiresPlainVideoItems: False, RequiresPlainFolders: False, EnableMSMediaReceiverRegistrar: False, IgnoreTranscodeByteRangeRequests: False, XmlRootAttributes: [], DirectPlayProfiles: [DirectPlayProfile { Container: "m4v,mov,xvid,vob,mkv,wmv,asf,ogm,ogv,mp4,webm", AudioCodec: "aac,mp3,mp2,aac_latm,alac,ac3,eac3,dca,dts,mlp,truehd,pcm_alaw,pcm_mulaw", VideoCodec: "h264,hevc,vp8,vp9,mpeg,mpeg2video", Type: Video }, DirectPlayProfile { Container: "aac,mp3,mp2,aac_latm,alac,ac3,eac3,dca,dts,mlp,truehd,pcm_alaw,pcm_mulaw,mpa,flac,wav,wma,ogg,oga,webma,ape,opus", AudioCodec: null, VideoCodec: null, Type: Audio }, DirectPlayProfile { Container: "jpg,jpeg,png,gif,webp", AudioCodec: null, VideoCodec: null, Type: Photo }], TranscodingProfiles: [TranscodingProfile { Container: "ts", Type: Video, VideoCodec: "hevc,h264", AudioCodec: "aac,mp3,mp2,aac_latm,alac,ac3,eac3,dts,mlp,pcm_alaw,pcm_mulaw", Protocol: "hls", EstimateContentLength: False, EnableMpegtsM2TsMode: False, TranscodeSeekInfo: Auto, CopyTimestamps: False, Context: Streaming, EnableSubtitlesInManifest: False, MaxAudioChannels: null, MinSegments: 0, SegmentLength: 0, BreakOnNonKeyFrames: False, Conditions: [] }, TranscodingProfile { Container: "mp3", Type: Audio, VideoCodec: "", AudioCodec: "mp3", Protocol: "", EstimateContentLength: False, EnableMpegtsM2TsMode: False, TranscodeSeekInfo: Auto, CopyTimestamps: False, Context: Streaming, EnableSubtitlesInManifest: False, MaxAudioChannels: null, MinSegments: 0, SegmentLength: 0, BreakOnNonKeyFrames: False, Conditions: [] }], ContainerProfiles: [], CodecProfiles: [CodecProfile { Type: Video, Conditions: [ProfileCondition { Condition: EqualsAny, Property: VideoProfile, Value: "high|main|baseline|constrained baseline", IsRequired: False }, ProfileCondition { Condition: LessThanEqual, Property: VideoLevel, Value: "41", IsRequired: False }], ApplyConditions: [], Codec: "h264", Container: null }, CodecProfile { Type: Video, Conditions: [ProfileCondition { Condition: LessThanEqual, Property: RefFrames, Value: "12", IsRequired: False }], ApplyConditions: [ProfileCondition { Condition: GreaterThanEqual, Property: Width, Value: "1200", IsRequired: False }], Codec: "h264", Container: null }, CodecProfile { Type: Video, Conditions: [ProfileCondition { Condition: LessThanEqual, Property: RefFrames, Value: "4", IsRequired: False }], ApplyConditions: [ProfileCondition { Condition: GreaterThanEqual, Property: Width, Value: "1900", IsRequired: False }], Codec: "h264", Container: null }, CodecProfile { Type: Video, Conditions: [ProfileCondition { Condition: NotEquals, Property: VideoProfile, Value: "none", IsRequired: False }], ApplyConditions: [], Codec: "hevc", Container: null }, CodecProfile { Type: VideoAudio, Conditions: [ProfileCondition { Condition: LessThanEqual, Property: AudioChannels, Value: "8", IsRequired: False }], ApplyConditions: [], Codec: null, Container: null }], ResponseProfiles: [], SubtitleProfiles: [SubtitleProfile { Format: "srt", Method: External, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "subrip", Method: External, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "ass", Method: Encode, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "ssa", Method: Encode, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "pgs", Method: Embed, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "pgssub", Method: Embed, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "dvbsub", Method: Embed, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "dvdsub", Method: Encode, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "vtt", Method: Embed, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "sub", Method: Embed, DidlMode: null, Language: null, Container: null }, SubtitleProfile { Format: "idx", Method: Embed, DidlMode: null, Language: null, Container: null }] } [2023-11-08 17:03:48.510 -06:00] [INF] [10] Jellyfin.Api.Helpers.MediaInfoHelper: User policy for "Peter". EnablePlaybackRemuxing: True EnableVideoPlaybackTranscoding: True EnableAudioPlaybackTranscoding: True [2023-11-08 17:03:48.510 -06:00] [DBG] [10] Jellyfin.Api.Helpers.MediaInfoHelper: Profile: "AndroidTV-ExoPlayer", Path: "/TV SHOWS/TV Shows/Taskmaster (2015)/Season 1/Taskmaster - S01E02 - The Pie Whisperer.m4v", isEligibleForDirectPlay: True, isEligibleForDirectStream: False [2023-11-08 17:03:48.510 -06:00] [DBG] [10] Jellyfin.Api.Helpers.MediaInfoHelper: Profile: "VideoCodecProfile", DirectPlay=false. Reason="AndroidTV-ExoPlayer".VideoLevel Condition: LessThanEqual. ConditionValue: "41". IsRequired: False. Path: "/TV SHOWS/TV Shows/Taskmaster (2015)/Season 1/Taskmaster - S01E02 - The Pie Whisperer.m4v" [2023-11-08 17:03:48.511 -06:00] [DBG] [10] Jellyfin.Api.Helpers.MediaInfoHelper: DirectPlay Result for Profile: "AndroidTV-ExoPlayer", Path: "/TV SHOWS/TV Shows/Taskmaster (2015)/Season 1/Taskmaster - S01E02 - The Pie Whisperer.m4v", PlayMethod: null, AudioStreamIndex: 2, SubtitleStreamIndex: null, Reasons: VideoLevelNotSupported [2023-11-08 17:03:48.511 -06:00] [DBG] [10] Jellyfin.Api.Helpers.MediaInfoHelper: Transcode Result for Profile: "AndroidTV-ExoPlayer", Path: "/TV SHOWS/TV Shows/Taskmaster (2015)/Season 1/Taskmaster - S01E02 - The Pie Whisperer.m4v", PlayMethod: Transcode, AudioStreamIndex: 2, SubtitleStreamIndex: null, Reasons: VideoLevelNotSupported [2023-11-08 17:03:48.512 -06:00] [INF] [10] Jellyfin.Api.Helpers.MediaInfoHelper: StreamBuilder.BuildVideoItem( Profile="AndroidTV-ExoPlayer", Path="/TV SHOWS/TV Shows/Taskmaster (2015)/Season 1/Taskmaster - S01E02 - The Pie Whisperer.m4v", AudioStreamIndex=null, SubtitleStreamIndex=null ) => ( PlayMethod=Transcode, TranscodeReason=VideoLevelNotSupported ) "media:/videos/0e609ca5-e463-91f6-061b-e2a581668b9d/master.m3u8?MediaSourceId=0e609ca5e46391f6061be2a581668b9d&VideoCodec=hevc,h264,h264&AudioCodec=aac&AudioStreamIndex=2&VideoBitrate=99872000&AudioBitrate=128000&AudioSampleRate=48000&MaxFramerate=25&api_key=&RequireAvc=false&Tag=f31c5075d0c314e6a58887839479d1c7&SegmentContainer=ts&BreakOnNonKeyFrames=False&h264-level=41&h264-videobitdepth=8&h264-profile=main&h264-audiochannels=2&aac-profile=he-aac&h264-maxrefframes=4&aac-audiochannels=8&TranscodeReasons=VideoLevelNotSupported" [2023-11-08 17:03:48.580 -06:00] [DBG] [41] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:48.581 -06:00] [DBG] [41] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:48.670 -06:00] [DBG] [40] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:48.670 -06:00] [DBG] [40] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:48.692 -06:00] [DBG] [23] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 17:03:48.749 -06:00] [DBG] [35] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:48.749 -06:00] [DBG] [35] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:48.813 -06:00] [DBG] [42] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:48.813 -06:00] [DBG] [42] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:48.817 -06:00] [DBG] [42] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:48.817 -06:00] [DBG] [42] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:48.941 -06:00] [DBG] [10] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:48.941 -06:00] [DBG] [10] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:48.979 -06:00] [DBG] [40] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:48.982 -06:00] [DBG] [40] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:49.243 -06:00] [WRN] [41] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "https://SERVERURL/Sessions/Playing" to "10.6.0.1" in 0:00:00.6636784 with Status Code 204 [2023-11-08 17:03:49.243 -06:00] [INF] [35] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Adding playback tracker : 0d8f7bea5f23fdbd55c3953b51878bc65e28ec98-de2975e91adf4bb58a91f2c9bb5fb39c-0e609ca5e46391f6061be2a581668b9d [2023-11-08 17:03:49.245 -06:00] [INF] [35] Jellyfin.Plugin.PlaybackReporting.Data.PlaybackTracker: PlaybackTracker : Adding Start Event : 11/08/2023 17:03:49 [2023-11-08 17:03:49.245 -06:00] [INF] [35] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Creating StartPlaybackTimer Task [2023-11-08 17:03:49.247 -06:00] [INF] [41] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : Entered [2023-11-08 17:03:49.247 -06:00] [DBG] [22] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:49.248 -06:00] [DBG] [22] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:49.248 -06:00] [DBG] [42] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:49.249 -06:00] [DBG] [11] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:49.249 -06:00] [DBG] [23] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:49.249 -06:00] [DBG] [11] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:49.249 -06:00] [DBG] [23] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:49.249 -06:00] [DBG] [42] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:49.395 -06:00] [DBG] [35] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:49.396 -06:00] [DBG] [35] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:49.411 -06:00] [DBG] [35] Jellyfin.Api.Controllers.DynamicHlsController: Starting transcoding because currentTranscodingIndex=null [2023-11-08 17:03:49.439 -06:00] [INF] [35] Jellyfin.Api.Helpers.TranscodingJobHelper: "/usr/lib/jellyfin-ffmpeg/ffmpeg" "-analyzeduration 200M -f mov,mp4,m4a,3gp,3g2,mj2 -autorotate 0 -i file:\"/TV SHOWS/TV Shows/Taskmaster (2015)/Season 1/Taskmaster - S01E02 - The Pie Whisperer.m4v\" -map_metadata -1 -map_chapters -1 -threads 0 -map 0:0 -map 0:1 -map -0:s -codec:v:0 libx265 -tag:v:0 hvc1 -preset veryfast -crf 28 -maxrate 4341836 -bufsize 8683672 -x265-params:0 no-info=1 -force_key_frames:0 \"expr:gte(t,0+n_forced*3)\" -bf 0 -vf \"setparams=color_primaries=bt709:color_trc=bt709:colorspace=bt709,scale=trunc(min(max(iw\,ih*a)\,min(1920\,1080*a))/2)*2:trunc(min(max(iw/a\,ih)\,min(1920/a\,1080))/2)*2,format=yuv420p\" -codec:a:0 copy -copyts -avoid_negative_ts disabled -max_muxing_queue_size 2048 -f hls -max_delay 5000000 -hls_time 3 -hls_segment_type mpegts -start_number 0 -hls_segment_filename \"/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec%d.ts\" -hls_playlist_type vod -hls_list_size 0 -y \"/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec.m3u8\"" [2023-11-08 17:03:49.443 -06:00] [DBG] [40] Jellyfin.Api.Helpers.TranscodingJobHelper: Launched FFmpeg process [2023-11-08 17:03:49.479 -06:00] [DBG] [40] Jellyfin.Api.Helpers.TranscodingJobHelper: Waiting for the creation of "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec0.ts" [2023-11-08 17:03:49.738 -06:00] [INF] [43] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "0d8f7bea5f23fdbd55c3953b51878bc65e28ec98-de2975e91adf4bb58a91f2c9bb5fb39c-0e609ca5e46391f6061be2a581668b9d" [2023-11-08 17:03:52.493 -06:00] [DBG] [43] Jellyfin.Api.Helpers.TranscodingJobHelper: File "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec0.ts" created or transcoding has finished [2023-11-08 17:03:52.494 -06:00] [DBG] [43] Jellyfin.Api.Helpers.TranscodingJobHelper: StartFfMpeg() finished successfully [2023-11-08 17:03:52.495 -06:00] [DBG] [43] Jellyfin.Api.Controllers.DynamicHlsController: returning "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec0.ts" [general case] [2023-11-08 17:03:55.922 -06:00] [DBG] [35] Jellyfin.Api.Controllers.DynamicHlsController: Serving up "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec0.ts" as it deemed ready [2023-11-08 17:03:55.926 -06:00] [WRN] [35] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "https://SERVERURL/videos/0e609ca5-e463-91f6-061b-e2a581668b9d/hls1/main/0.ts?DeviceId=0d8f7bea5f23fdbd55c3953b51878bc65e28ec98&MediaSourceId=0e609ca5e46391f6061be2a581668b9d&VideoCodec=hevc,h264,h264&AudioCodec=aac&AudioStreamIndex=2&VideoBitrate=99872000&AudioBitrate=128000&AudioSampleRate=48000&MaxFramerate=25&PlaySessionId=1f5775eb62a54a6e956c507b1d91292f&api_key=2a47a23de1b54656bea081e5b6c8e702&RequireAvc=false&Tag=f31c5075d0c314e6a58887839479d1c7&SegmentContainer=ts&BreakOnNonKeyFrames=False&h264-level=41&h264-videobitdepth=8&h264-profile=main&h264-audiochannels=2&aac-profile=he-aac&h264-maxrefframes=4&aac-audiochannels=8&TranscodeReasons=VideoLevelNotSupported&runtimeTicks=0&actualSegmentLengthTicks=30000000" to "10.6.0.1" in 0:00:06.5376717 with Status Code 200 [2023-11-08 17:03:55.930 -06:00] [DBG] [35] Jellyfin.Api.Controllers.DynamicHlsController: Finished serving "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec0.ts" [2023-11-08 17:03:55.931 -06:00] [DBG] [35] Jellyfin.Api.Helpers.TranscodingJobHelper: OnTranscodeEndRequest job.ActiveRequestCount=0 [2023-11-08 17:03:55.931 -06:00] [DBG] [35] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Starting kill timer at 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:03:56.276 -06:00] [DBG] [23] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:56.277 -06:00] [DBG] [23] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:56.299 -06:00] [DBG] [22] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:56.300 -06:00] [DBG] [22] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:03:56.303 -06:00] [DBG] [22] Jellyfin.Api.Controllers.DynamicHlsController: returning "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec1.ts" [it exists, try 1] [2023-11-08 17:03:56.635 -06:00] [DBG] [23] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:03:56.635 -06:00] [DBG] [23] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:03:56.635 -06:00] [DBG] [23] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:03:57.302 -06:00] [DBG] [36] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:03:57.635 -06:00] [DBG] [36] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:03:57.635 -06:00] [DBG] [36] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:03:57.636 -06:00] [DBG] [36] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:03:58.636 -06:00] [DBG] [23] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:03:58.636 -06:00] [DBG] [23] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:03:58.636 -06:00] [DBG] [23] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:03:59.635 -06:00] [DBG] [22] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:03:59.635 -06:00] [DBG] [22] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:03:59.636 -06:00] [DBG] [22] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:03:59.929 -06:00] [DBG] [35] Jellyfin.Api.Controllers.DynamicHlsController: Serving up "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec1.ts" as it deemed ready [2023-11-08 17:03:59.935 -06:00] [WRN] [35] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "https://SERVERURL/videos/0e609ca5-e463-91f6-061b-e2a581668b9d/hls1/main/1.ts?DeviceId=0d8f7bea5f23fdbd55c3953b51878bc65e28ec98&MediaSourceId=0e609ca5e46391f6061be2a581668b9d&VideoCodec=hevc,h264,h264&AudioCodec=aac&AudioStreamIndex=2&VideoBitrate=99872000&AudioBitrate=128000&AudioSampleRate=48000&MaxFramerate=25&PlaySessionId=1f5775eb62a54a6e956c507b1d91292f&api_key=2a47a23de1b54656bea081e5b6c8e702&RequireAvc=false&Tag=f31c5075d0c314e6a58887839479d1c7&SegmentContainer=ts&BreakOnNonKeyFrames=False&h264-level=41&h264-videobitdepth=8&h264-profile=main&h264-audiochannels=2&aac-profile=he-aac&h264-maxrefframes=4&aac-audiochannels=8&TranscodeReasons=VideoLevelNotSupported&runtimeTicks=30000000&actualSegmentLengthTicks=30000000" to "10.6.0.1" in 0:00:03.6357499 with Status Code 200 [2023-11-08 17:03:59.938 -06:00] [DBG] [10] Jellyfin.Api.Controllers.DynamicHlsController: Finished serving "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec1.ts" [2023-11-08 17:03:59.938 -06:00] [DBG] [10] Jellyfin.Api.Helpers.TranscodingJobHelper: OnTranscodeEndRequest job.ActiveRequestCount=0 [2023-11-08 17:03:59.938 -06:00] [DBG] [10] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:00.021 -06:00] [DBG] [11] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:00.021 -06:00] [DBG] [11] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:00.022 -06:00] [DBG] [11] Jellyfin.Api.Controllers.DynamicHlsController: returning "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec2.ts" [it exists, try 1] [2023-11-08 17:04:00.636 -06:00] [DBG] [37] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:00.636 -06:00] [DBG] [37] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:00.637 -06:00] [DBG] [37] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:01.475 -06:00] [DBG] [37] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 17:04:01.636 -06:00] [DBG] [35] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:01.636 -06:00] [DBG] [35] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:01.636 -06:00] [DBG] [35] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:02.636 -06:00] [DBG] [40] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:02.636 -06:00] [DBG] [40] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:02.646 -06:00] [DBG] [40] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:03.637 -06:00] [DBG] [40] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:03.637 -06:00] [DBG] [40] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:03.637 -06:00] [DBG] [40] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:03.653 -06:00] [DBG] [40] Jellyfin.Api.Controllers.DynamicHlsController: Serving up "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec2.ts" as it deemed ready [2023-11-08 17:04:03.655 -06:00] [WRN] [40] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "https://SERVERURL/videos/0e609ca5-e463-91f6-061b-e2a581668b9d/hls1/main/2.ts?DeviceId=0d8f7bea5f23fdbd55c3953b51878bc65e28ec98&MediaSourceId=0e609ca5e46391f6061be2a581668b9d&VideoCodec=hevc,h264,h264&AudioCodec=aac&AudioStreamIndex=2&VideoBitrate=99872000&AudioBitrate=128000&AudioSampleRate=48000&MaxFramerate=25&PlaySessionId=1f5775eb62a54a6e956c507b1d91292f&api_key=2a47a23de1b54656bea081e5b6c8e702&RequireAvc=false&Tag=f31c5075d0c314e6a58887839479d1c7&SegmentContainer=ts&BreakOnNonKeyFrames=False&h264-level=41&h264-videobitdepth=8&h264-profile=main&h264-audiochannels=2&aac-profile=he-aac&h264-maxrefframes=4&aac-audiochannels=8&TranscodeReasons=VideoLevelNotSupported&runtimeTicks=60000000&actualSegmentLengthTicks=30000000" to "10.6.0.1" in 0:00:03.6349326 with Status Code 200 [2023-11-08 17:04:03.664 -06:00] [DBG] [11] Jellyfin.Api.Controllers.DynamicHlsController: Finished serving "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec2.ts" [2023-11-08 17:04:03.665 -06:00] [DBG] [11] Jellyfin.Api.Helpers.TranscodingJobHelper: OnTranscodeEndRequest job.ActiveRequestCount=0 [2023-11-08 17:04:03.665 -06:00] [DBG] [11] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:03.840 -06:00] [DBG] [36] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:03.840 -06:00] [DBG] [36] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:03.841 -06:00] [DBG] [36] Jellyfin.Api.Controllers.DynamicHlsController: returning "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec3.ts" [it exists, try 1] [2023-11-08 17:04:04.637 -06:00] [DBG] [36] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:04.637 -06:00] [DBG] [36] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:04.637 -06:00] [DBG] [36] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:05.313 -06:00] [DBG] [36] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:05.315 -06:00] [DBG] [36] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:05.636 -06:00] [DBG] [11] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:05.636 -06:00] [DBG] [11] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:05.636 -06:00] [DBG] [11] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:05.644 -06:00] [DBG] [36] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:05.644 -06:00] [DBG] [36] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:05.644 -06:00] [DBG] [36] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:06.645 -06:00] [DBG] [35] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:06.645 -06:00] [DBG] [35] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:06.645 -06:00] [DBG] [35] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:06.959 -06:00] [DBG] [36] Jellyfin.Api.Controllers.DynamicHlsController: Serving up "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec3.ts" as it deemed ready [2023-11-08 17:04:06.960 -06:00] [WRN] [36] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "https://SERVERURL/videos/0e609ca5-e463-91f6-061b-e2a581668b9d/hls1/main/3.ts?DeviceId=0d8f7bea5f23fdbd55c3953b51878bc65e28ec98&MediaSourceId=0e609ca5e46391f6061be2a581668b9d&VideoCodec=hevc,h264,h264&AudioCodec=aac&AudioStreamIndex=2&VideoBitrate=99872000&AudioBitrate=128000&AudioSampleRate=48000&MaxFramerate=25&PlaySessionId=1f5775eb62a54a6e956c507b1d91292f&api_key=2a47a23de1b54656bea081e5b6c8e702&RequireAvc=false&Tag=f31c5075d0c314e6a58887839479d1c7&SegmentContainer=ts&BreakOnNonKeyFrames=False&h264-level=41&h264-videobitdepth=8&h264-profile=main&h264-audiochannels=2&aac-profile=he-aac&h264-maxrefframes=4&aac-audiochannels=8&TranscodeReasons=VideoLevelNotSupported&runtimeTicks=90000000&actualSegmentLengthTicks=30000000" to "10.6.0.1" in 0:00:03.1213419 with Status Code 200 [2023-11-08 17:04:06.968 -06:00] [DBG] [22] Jellyfin.Api.Controllers.DynamicHlsController: Finished serving "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec3.ts" [2023-11-08 17:04:06.968 -06:00] [DBG] [22] Jellyfin.Api.Helpers.TranscodingJobHelper: OnTranscodeEndRequest job.ActiveRequestCount=0 [2023-11-08 17:04:06.968 -06:00] [DBG] [22] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:07.097 -06:00] [DBG] [43] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:07.097 -06:00] [DBG] [43] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:07.098 -06:00] [DBG] [43] Jellyfin.Api.Controllers.DynamicHlsController: returning "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec4.ts" [it exists, try 1] [2023-11-08 17:04:07.646 -06:00] [DBG] [35] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:07.647 -06:00] [DBG] [35] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:07.647 -06:00] [DBG] [35] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:08.315 -06:00] [DBG] [35] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:08.316 -06:00] [DBG] [35] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:08.634 -06:00] [DBG] [35] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:08.634 -06:00] [DBG] [35] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:08.634 -06:00] [DBG] [35] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:09.248 -06:00] [INF] [11] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: session.RemoteEndPoint : "10.6.0.1" [2023-11-08 17:04:09.250 -06:00] [INF] [11] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : event_playing_id = "0e609ca5e46391f6061be2a581668b9d" [2023-11-08 17:04:09.251 -06:00] [INF] [11] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : event_user_id = "de2975e91adf4bb58a91f2c9bb5fb39c" [2023-11-08 17:04:09.251 -06:00] [INF] [11] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : event_user_id_int = 3 [2023-11-08 17:04:09.251 -06:00] [INF] [11] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : session_playing_id = "0e609ca5e46391f6061be2a581668b9d" [2023-11-08 17:04:09.251 -06:00] [INF] [11] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : session_user_id = "de2975e91adf4bb58a91f2c9bb5fb39c" [2023-11-08 17:04:09.251 -06:00] [INF] [11] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : play_method = "Transcode (v:hevc a:direct)" [2023-11-08 17:04:09.252 -06:00] [INF] [11] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : e.ClientName = "Android TV" [2023-11-08 17:04:09.252 -06:00] [INF] [11] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : e.DeviceName = "AFTKM" [2023-11-08 17:04:09.252 -06:00] [INF] [11] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : ItemName = "Taskmaster - s01e02 - The Pie Whisperer" [2023-11-08 17:04:09.252 -06:00] [INF] [11] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : ItemId = "0e609ca5e46391f6061be2a581668b9d" [2023-11-08 17:04:09.252 -06:00] [INF] [11] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : ItemType = "Episode" [2023-11-08 17:04:09.253 -06:00] [INF] [11] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : All matches, playback registered [2023-11-08 17:04:09.253 -06:00] [INF] [11] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Playback tracker found, adding playback info : "0d8f7bea5f23fdbd55c3953b51878bc65e28ec98-de2975e91adf4bb58a91f2c9bb5fb39c-0e609ca5e46391f6061be2a581668b9d" [2023-11-08 17:04:09.253 -06:00] [INF] [11] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Saving playback tracking activity in DB [2023-11-08 17:04:09.303 -06:00] [DBG] [36] Emby.Server.Implementations.Session.SessionWebSocketListener: Watching 1 WebSockets. [2023-11-08 17:04:09.306 -06:00] [DBG] [40] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: KeepAlive, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: null } [2023-11-08 17:04:09.325 -06:00] [INF] [11] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: StartPlaybackTimer : Exited [2023-11-08 17:04:09.635 -06:00] [DBG] [11] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:09.635 -06:00] [DBG] [11] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:09.635 -06:00] [DBG] [11] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:10.213 -06:00] [DBG] [40] Jellyfin.Api.Controllers.DynamicHlsController: Serving up "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec4.ts" as it deemed ready [2023-11-08 17:04:10.214 -06:00] [WRN] [36] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "https://SERVERURL/videos/0e609ca5-e463-91f6-061b-e2a581668b9d/hls1/main/4.ts?DeviceId=0d8f7bea5f23fdbd55c3953b51878bc65e28ec98&MediaSourceId=0e609ca5e46391f6061be2a581668b9d&VideoCodec=hevc,h264,h264&AudioCodec=aac&AudioStreamIndex=2&VideoBitrate=99872000&AudioBitrate=128000&AudioSampleRate=48000&MaxFramerate=25&PlaySessionId=1f5775eb62a54a6e956c507b1d91292f&api_key=2a47a23de1b54656bea081e5b6c8e702&RequireAvc=false&Tag=f31c5075d0c314e6a58887839479d1c7&SegmentContainer=ts&BreakOnNonKeyFrames=False&h264-level=41&h264-videobitdepth=8&h264-profile=main&h264-audiochannels=2&aac-profile=he-aac&h264-maxrefframes=4&aac-audiochannels=8&TranscodeReasons=VideoLevelNotSupported&runtimeTicks=120000000&actualSegmentLengthTicks=30000000" to "10.6.0.1" in 0:00:03.1184335 with Status Code 200 [2023-11-08 17:04:10.217 -06:00] [DBG] [35] Jellyfin.Api.Controllers.DynamicHlsController: Finished serving "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec4.ts" [2023-11-08 17:04:10.218 -06:00] [DBG] [35] Jellyfin.Api.Helpers.TranscodingJobHelper: OnTranscodeEndRequest job.ActiveRequestCount=0 [2023-11-08 17:04:10.218 -06:00] [DBG] [35] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:10.289 -06:00] [DBG] [35] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:10.290 -06:00] [DBG] [35] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:10.291 -06:00] [DBG] [35] Jellyfin.Api.Controllers.DynamicHlsController: returning "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec5.ts" [it exists, try 1] [2023-11-08 17:04:10.587 -06:00] [DBG] [35] Emby.Server.Implementations.Session.WebSocketController: Removing websocket from session "333d5db2aa3580a3f6da396fb9b91d98" [2023-11-08 17:04:10.624 -06:00] [DBG] [35] Emby.Server.Implementations.Session.SessionManager: Disposing session controller synchronously "WebSocketController" [2023-11-08 17:04:10.625 -06:00] [DBG] [35] Emby.Server.Implementations.Session.SessionWebSocketListener: WebSocket "Emby.Server.Implementations.HttpServer.WebSocketConnection" is closed. [2023-11-08 17:04:10.626 -06:00] [INF] [35] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "10.6.0.1" closed [2023-11-08 17:04:10.634 -06:00] [INF] [35] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: Processing playback tracker : "0d8f7bea5f23fdbd55c3953b51878bc65e28ec98-de2975e91adf4bb58a91f2c9bb5fb39c-0e609ca5e46391f6061be2a581668b9d" [2023-11-08 17:04:10.635 -06:00] [DBG] [35] Jellyfin.Plugin.PlaybackReporting.EventMonitorEntryPoint: ProcessProgress : "EventCount(2)Event(11/08/2023 17:03:49,START)Event(11/08/2023 17:04:10,STOP)Diff(21,21)Total(21)" [2023-11-08 17:04:10.708 -06:00] [DBG] [35] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:10.708 -06:00] [DBG] [35] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:10.708 -06:00] [DBG] [35] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:10.882 -06:00] [DBG] [35] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 17:04:10.883 -06:00] [DBG] [35] Jellyfin.Networking.Manager.NetworkManager: GetBindInterface: Source: True, External: False: [2023-11-08 17:04:10.883 -06:00] [DBG] [35] Jellyfin.Networking.Manager.NetworkManager: "10.6.0.1/32": GetBindInterface: Matched first internal interface. "127.0.0.1" [2023-11-08 17:04:10.912 -06:00] [DBG] [35] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 17:04:11.017 -06:00] [DBG] [35] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 17:04:11.017 -06:00] [DBG] [35] Jellyfin.Networking.Manager.NetworkManager: GetBindInterface: Source: True, External: False: [2023-11-08 17:04:11.018 -06:00] [DBG] [35] Jellyfin.Networking.Manager.NetworkManager: "10.6.0.1/32": GetBindInterface: Matched first internal interface. "127.0.0.1" [2023-11-08 17:04:11.024 -06:00] [DBG] [35] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.024 -06:00] [DBG] [35] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.024 -06:00] [DBG] [35] Jellyfin.Networking.Manager.NetworkManager: GetBindInterface: Source: True, External: False: [2023-11-08 17:04:11.025 -06:00] [DBG] [35] Jellyfin.Networking.Manager.NetworkManager: "10.6.0.1/32": GetBindInterface: Matched first internal interface. "127.0.0.1" [2023-11-08 17:04:11.034 -06:00] [DBG] [36] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.035 -06:00] [DBG] [36] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.035 -06:00] [DBG] [11] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.035 -06:00] [DBG] [11] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.037 -06:00] [DBG] [35] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.040 -06:00] [DBG] [36] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.041 -06:00] [DBG] [35] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.042 -06:00] [INF] [36] Emby.Server.Implementations.HttpServer.WebSocketManager: WS "10.6.0.1" request [2023-11-08 17:04:11.096 -06:00] [DBG] [40] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.096 -06:00] [DBG] [40] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.096 -06:00] [DBG] [22] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.097 -06:00] [DBG] [22] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.098 -06:00] [DBG] [37] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.098 -06:00] [DBG] [11] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.098 -06:00] [DBG] [11] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.099 -06:00] [DBG] [41] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.100 -06:00] [DBG] [41] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.100 -06:00] [DBG] [3] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.097 -06:00] [DBG] [43] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.101 -06:00] [DBG] [43] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.098 -06:00] [DBG] [37] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.101 -06:00] [DBG] [43] Jellyfin.Networking.Manager.NetworkManager: GetBindInterface: Source: True, External: False: [2023-11-08 17:04:11.102 -06:00] [DBG] [43] Jellyfin.Networking.Manager.NetworkManager: "10.6.0.1/32": GetBindInterface: Matched first internal interface. "127.0.0.1" [2023-11-08 17:04:11.108 -06:00] [DBG] [3] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.134 -06:00] [DBG] [36] Emby.Server.Implementations.Session.SessionManager: Creating new "WebSocketController" [2023-11-08 17:04:11.134 -06:00] [DBG] [36] Emby.Server.Implementations.Session.WebSocketController: Adding websocket to session "333d5db2aa3580a3f6da396fb9b91d98" [2023-11-08 17:04:11.134 -06:00] [DBG] [23] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.134 -06:00] [DBG] [10] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.136 -06:00] [DBG] [10] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.137 -06:00] [DBG] [36] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: SessionsStart, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: JsonElement { ValueKind: String } } [2023-11-08 17:04:11.139 -06:00] [DBG] [36] Jellyfin.Api.WebSocketListeners.SessionInfoWebSocketListener: WS "10.6.0.1" begin transmitting to "SessionInfoWebSocketListener" [2023-11-08 17:04:11.140 -06:00] [DBG] [36] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: ScheduledTasksInfoStart, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: JsonElement { ValueKind: String } } [2023-11-08 17:04:11.140 -06:00] [DBG] [36] Jellyfin.Api.WebSocketListeners.ScheduledTasksWebSocketListener: WS "10.6.0.1" begin transmitting to "ScheduledTasksWebSocketListener" [2023-11-08 17:04:11.140 -06:00] [DBG] [36] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: ActivityLogEntryStart, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: JsonElement { ValueKind: String } } [2023-11-08 17:04:11.140 -06:00] [DBG] [36] Jellyfin.Api.WebSocketListeners.ActivityLogWebSocketListener: WS "10.6.0.1" begin transmitting to "ActivityLogWebSocketListener" [2023-11-08 17:04:11.140 -06:00] [DBG] [36] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: ActivityLogEntryStart, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: JsonElement { ValueKind: String } } [2023-11-08 17:04:11.140 -06:00] [DBG] [36] Jellyfin.Api.WebSocketListeners.ActivityLogWebSocketListener: WS "10.6.0.1" begin transmitting to "ActivityLogWebSocketListener" [2023-11-08 17:04:11.141 -06:00] [DBG] [36] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: ScheduledTasksInfoStart, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: JsonElement { ValueKind: String } } [2023-11-08 17:04:11.141 -06:00] [DBG] [36] Jellyfin.Api.WebSocketListeners.ScheduledTasksWebSocketListener: WS "10.6.0.1" begin transmitting to "ScheduledTasksWebSocketListener" [2023-11-08 17:04:11.141 -06:00] [DBG] [23] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.148 -06:00] [DBG] [23] Emby.Server.Implementations.HttpServer.WebSocketConnection: WS "10.6.0.1" received message: WebSocketMessage`1 { MessageType: KeepAlive, MessageId: 00000000-0000-0000-0000-000000000000, ServerId: null, Data: null } [2023-11-08 17:04:11.149 -06:00] [DBG] [23] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 17:04:11.317 -06:00] [DBG] [40] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.317 -06:00] [DBG] [40] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:11.643 -06:00] [DBG] [41] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:11.643 -06:00] [DBG] [41] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:11.644 -06:00] [DBG] [41] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:11.656 -06:00] [DBG] [40] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:11.656 -06:00] [DBG] [40] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:11.656 -06:00] [DBG] [40] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:12.657 -06:00] [DBG] [41] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:12.657 -06:00] [DBG] [41] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:12.657 -06:00] [DBG] [41] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:13.657 -06:00] [DBG] [41] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:13.657 -06:00] [DBG] [41] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:13.657 -06:00] [DBG] [41] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:13.719 -06:00] [DBG] [35] Jellyfin.Api.Controllers.DynamicHlsController: Serving up "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec5.ts" as it deemed ready [2023-11-08 17:04:13.720 -06:00] [WRN] [35] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "https://SERVERURL/videos/0e609ca5-e463-91f6-061b-e2a581668b9d/hls1/main/5.ts?DeviceId=0d8f7bea5f23fdbd55c3953b51878bc65e28ec98&MediaSourceId=0e609ca5e46391f6061be2a581668b9d&VideoCodec=hevc,h264,h264&AudioCodec=aac&AudioStreamIndex=2&VideoBitrate=99872000&AudioBitrate=128000&AudioSampleRate=48000&MaxFramerate=25&PlaySessionId=1f5775eb62a54a6e956c507b1d91292f&api_key=2a47a23de1b54656bea081e5b6c8e702&RequireAvc=false&Tag=f31c5075d0c314e6a58887839479d1c7&SegmentContainer=ts&BreakOnNonKeyFrames=False&h264-level=41&h264-videobitdepth=8&h264-profile=main&h264-audiochannels=2&aac-profile=he-aac&h264-maxrefframes=4&aac-audiochannels=8&TranscodeReasons=VideoLevelNotSupported&runtimeTicks=150000000&actualSegmentLengthTicks=30000000" to "10.6.0.1" in 0:00:03.4312336 with Status Code 200 [2023-11-08 17:04:13.722 -06:00] [DBG] [35] Jellyfin.Api.Controllers.DynamicHlsController: Finished serving "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec5.ts" [2023-11-08 17:04:13.722 -06:00] [DBG] [35] Jellyfin.Api.Helpers.TranscodingJobHelper: OnTranscodeEndRequest job.ActiveRequestCount=0 [2023-11-08 17:04:13.722 -06:00] [DBG] [35] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:13.797 -06:00] [DBG] [33] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:13.797 -06:00] [DBG] [33] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:13.798 -06:00] [DBG] [33] Jellyfin.Api.Controllers.DynamicHlsController: returning "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec6.ts" [it exists, try 1] [2023-11-08 17:04:14.299 -06:00] [DBG] [36] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:14.299 -06:00] [DBG] [36] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:14.624 -06:00] [DBG] [36] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:14.625 -06:00] [DBG] [36] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:14.625 -06:00] [DBG] [36] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:14.679 -06:00] [DBG] [41] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 17:04:14.680 -06:00] [DBG] [41] Jellyfin.Networking.Manager.NetworkManager: GetBindInterface: Source: True, External: True: [2023-11-08 17:04:14.682 -06:00] [DBG] [41] Jellyfin.Networking.Manager.NetworkManager: "66.244.74.68/32": GetBindInterface: Selected first external interface. "172.18.0.6" [2023-11-08 17:04:15.625 -06:00] [DBG] [35] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:15.626 -06:00] [DBG] [35] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:15.626 -06:00] [DBG] [35] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:16.626 -06:00] [DBG] [36] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:16.626 -06:00] [DBG] [36] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:16.626 -06:00] [DBG] [36] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:17.037 -06:00] [DBG] [35] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:17.037 -06:00] [DBG] [35] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:17.046 -06:00] [DBG] [35] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was not authenticated. [2023-11-08 17:04:17.310 -06:00] [DBG] [35] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:17.310 -06:00] [DBG] [35] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:17.527 -06:00] [DBG] [33] Jellyfin.Api.Controllers.DynamicHlsController: Serving up "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec6.ts" as it deemed ready [2023-11-08 17:04:17.530 -06:00] [WRN] [33] Jellyfin.Server.Middleware.ResponseTimeMiddleware: Slow HTTP Response from "https://SERVERURL/videos/0e609ca5-e463-91f6-061b-e2a581668b9d/hls1/main/6.ts?DeviceId=0d8f7bea5f23fdbd55c3953b51878bc65e28ec98&MediaSourceId=0e609ca5e46391f6061be2a581668b9d&VideoCodec=hevc,h264,h264&AudioCodec=aac&AudioStreamIndex=2&VideoBitrate=99872000&AudioBitrate=128000&AudioSampleRate=48000&MaxFramerate=25&PlaySessionId=1f5775eb62a54a6e956c507b1d91292f&api_key=2a47a23de1b54656bea081e5b6c8e702&RequireAvc=false&Tag=f31c5075d0c314e6a58887839479d1c7&SegmentContainer=ts&BreakOnNonKeyFrames=False&h264-level=41&h264-videobitdepth=8&h264-profile=main&h264-audiochannels=2&aac-profile=he-aac&h264-maxrefframes=4&aac-audiochannels=8&TranscodeReasons=VideoLevelNotSupported&runtimeTicks=180000000&actualSegmentLengthTicks=30000000" to "10.6.0.1" in 0:00:03.7337874 with Status Code 200 [2023-11-08 17:04:17.534 -06:00] [DBG] [33] Jellyfin.Api.Controllers.DynamicHlsController: Finished serving "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec6.ts" [2023-11-08 17:04:17.535 -06:00] [DBG] [33] Jellyfin.Api.Helpers.TranscodingJobHelper: OnTranscodeEndRequest job.ActiveRequestCount=0 [2023-11-08 17:04:17.535 -06:00] [DBG] [33] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:17.589 -06:00] [DBG] [37] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:17.590 -06:00] [DBG] [37] Jellyfin.Api.Auth.CustomAuthenticationHandler: AuthenticationScheme: "CustomAuthentication" was successfully authenticated. [2023-11-08 17:04:17.594 -06:00] [DBG] [37] Jellyfin.Api.Controllers.DynamicHlsController: returning "/config/transcodes/19cdc5d3806ffcb548d6cb1d425c50ec7.ts" [it exists, try 1] [2023-11-08 17:04:17.627 -06:00] [DBG] [33] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:17.627 -06:00] [DBG] [33] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:17.629 -06:00] [DBG] [33] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f" [2023-11-08 17:04:17.794 -06:00] [DBG] [35] Jellyfin.Api.Helpers.TranscodingJobHelper: PingTranscodingJob PlaySessionId="1f5775eb62a54a6e956c507b1d91292f" isUsedPaused: False [2023-11-08 17:04:17.794 -06:00] [DBG] [35] Jellyfin.Api.Helpers.TranscodingJobHelper: Setting job.IsUserPaused to False. jobId: "b73b7a94633b4250b1887ea7c8237a82" [2023-11-08 17:04:17.795 -06:00] [DBG] [35] Jellyfin.Api.Models.PlaybackDtos.TranscodingJobDto: Changing kill timer to 60000ms. JobId "b73b7a94633b4250b1887ea7c8237a82" PlaySessionId "1f5775eb62a54a6e956c507b1d91292f"