search

jellyfin / jellyfin-mpv-shim

MPV Cast Client for Jellyfin
Other
1.64k stars 93 forks source link

Broken reconnect handling with 10.8.0-beta3 #268

Closed calmcacil closed 2 years ago

calmcacil commented 2 years ago

Describe the bug If the server for any reason becomes unreachable, either due to a restart, network disconnection etc, even if you refresh the browser, it does not show up as an available player anymore. Though the application log shows it reconnecting, websockets and the whole "KeepAlive received from server." every 30 seconds, so by looking at shim's log, it does seem to reconnect, but not register itself as a player upon doing so.

I'm not entirely sure if this is applicable only to 10.8.0 or if it also applies to older versions, I'm simply using this after changing from plex-shim.

Restarting the application makes it reappear as a player so I'm not entirely sure what the difference between doing that, and it automatically reconnecting is.

To Reproduce

  1. Select player.
  2. Restart the jellyfin container (or lose connection, if the internet drops for any reason it seems to behave the same way.)
  3. Refresh the browser window or try to start playing back content.

Expected behavior Player should reappear in the player selection upon reconnecting, and again be available as a choice without restarting the shim client.

Desktop (please complete the following information):

Error Messages No error messages in the log, though the following is the snippet it shows from the reconnection attempt, after this its just keepalive from server every 30 seconds until you restart it and it is again available as a player.

2022-06-10 03:23:12,260 [ ERROR] JELLYFIN.jellyfin_apiclient_python.connection_manager: Failed to connect to server: https://jellyfin.REDACTED.tld 2022-06-10 03:23:12,260 [ DEBUG] JELLYFIN.jellyfin_apiclient_python.connection_manager: resolving connect with result: {'State': 0} 2022-06-10 03:23:12,260 [ INFO] clients: No connection to server. Next try in 4 second(s) 2022-06-10 03:23:16,271 [ DEBUG] JELLYFIN.jellyfin_apiclient_python.client: JellyfinClient initializing... 2022-06-10 03:23:16,271 [ DEBUG] JELLYFIN.jellyfin_apiclient_python.configuration: Configuration initializing... 2022-06-10 03:23:16,271 [ DEBUG] JELLYFIN.jellyfin_apiclient_python.configuration: Begin http constructor. 2022-06-10 03:23:16,271 [ DEBUG] JELLYFIN.jellyfin_apiclient_python.ws_client: WSClient initializing... 2022-06-10 03:23:16,271 [ DEBUG] JELLYFIN.jellyfin_apiclient_python.connection_manager: ConnectionManager initializing... 2022-06-10 03:23:16,271 [ DEBUG] JELLYFIN.jellyfin_apiclient_python.credentials: Credentials initializing... 2022-06-10 03:23:16,271 [ DEBUG] JELLYFIN.jellyfin_apiclient_python.configuration: Begin app constructor. 2022-06-10 03:23:16,271 [ INFO] JELLYFIN.jellyfin_apiclient_python.connection_manager: Begin connect 2022-06-10 03:23:16,271 [ INFO] JELLYFIN.jellyfin_apiclient_python.connection_manager: Begin getAvailableServers 2022-06-10 03:23:16,271 [ DEBUG] JELLYFIN.jellyfin_apiclient_python.credentials: credentialsupdated 2022-06-10 03:23:16,271 [ INFO] JELLYFIN.jellyfin_apiclient_python.connection_manager: connect has 1 servers 2022-06-10 03:23:16,271 [ INFO] JELLYFIN.jellyfin_apiclient_python.connection_manager: begin connectToServer 2022-06-10 03:23:16,272 [ INFO] JELLYFIN.jellyfin_apiclient_python.api: Sending get request to system/info/public 2022-06-10 03:23:16,272 [ DEBUG] JELLYFIN.jellyfin_apiclient_python.api: 5 2022-06-10 03:23:16,272 [ DEBUG] JELLYFIN.jellyfin_apiclient_python.api: {'Accept': 'application/json', 'Content-type': 'application/x-www-form-urlencoded; charset=UTF-8', 'X-Application': 'Jellyfin MPV Shim/2.0.2', 'Accept-Charset': 'UTF-8,*', 'Accept-encoding': 'gzip', 'User-Agent': 'Jellyfin-MPV-Shim/2.0.2', 'x-emby-authorization': 'MediaBrowser Client=Jellyfin MPV Shim, Device=Raion, DeviceId=281de7bc-ad85-4590-8d76-bd428a3c2800, Version=2.0.2'} 2022-06-10 03:23:16,273 [ DEBUG] urllib3.connectionpool: Starting new HTTPS connection (1): jellyfin.REDACTED.tld:443 2022-06-10 03:23:16,658 [ DEBUG] urllib3.connectionpool: https://jellyfin.REDACTED.tld:443 "GET /system/info/public HTTP/1.1" 200 None 2022-06-10 03:23:16,661 [ INFO] JELLYFIN.jellyfin_apiclient_python.connection_manager: calling onSuccessfulConnection with server Lucoa 2022-06-10 03:23:16,661 [ INFO] JELLYFIN.jellyfin_apiclient_python.api: Sending get request to system/info 2022-06-10 03:23:16,661 [ DEBUG] JELLYFIN.jellyfin_apiclient_python.api: 5 2022-06-10 03:23:16,661 [ DEBUG] JELLYFIN.jellyfin_apiclient_python.api: {'Accept': 'application/json', 'Content-type': 'application/x-www-form-urlencoded; charset=UTF-8', 'X-Application': 'Jellyfin MPV Shim/2.0.2', 'Accept-Charset': 'UTF-8,*', 'Accept-encoding': 'gzip', 'User-Agent': 'Jellyfin-MPV-Shim/2.0.2', 'x-emby-authorization': 'MediaBrowser Client=Jellyfin MPV Shim, Device=Raion, DeviceId=281de7bc-ad85-4590-8d76-bd428a3c2800, Version=2.0.2', 'X-MediaBrowser-Token': 'REDACTED'} 2022-06-10 03:23:16,663 [ DEBUG] urllib3.connectionpool: Starting new HTTPS connection (1): jellyfin.REDACTED.tld:443 2022-06-10 03:23:16,900 [ DEBUG] urllib3.connectionpool: https://jellyfin.REDACTED.tld:443 "GET /system/info HTTP/1.1" 200 None 2022-06-10 03:23:16,902 [ DEBUG] JELLYFIN.jellyfin_apiclient_python.credentials: credentialsupdated 2022-06-10 03:23:16,902 [ DEBUG] JELLYFIN.jellyfin_apiclient_python.connection_manager: resolving connect with result: {'Servers': "[{'address': 'https://jellyfin.REDACTED.tld', 'Name': 'Lucoa', 'Id': '89c73f54a1854b67ad002d756b3f6f1a', 'DateLastAccessed': '2022-06-10T03:23:16Z', 'UserId': '8b861089adc84288ad9bbca4a68c07a2', 'AccessToken': 'REDACTED', 'Users': [{'Id': '8b861089adc84288ad9bbca4a68c07a2', 'IsSignedInOffline': True}], 'uuid': 'c1f3206d-10d5-4715-8a26-eb095b93454b', 'username': 'Calmcacil', 'connected': False}]", 'State': 3} 2022-06-10 03:23:16,902 [ INFO] JELLYFIN.jellyfin_apiclient_python.client: User is authenticated. 2022-06-10 03:23:16,902 [ DEBUG] urllib3.util.retry: Converted retries value: 3 -> 'Retry(total=3, connect=None, read=None, redirect=None, status=None)' 2022-06-10 03:23:16,902 [ DEBUG] urllib3.util.retry: Converted retries value: 3 -> 'Retry(total=3, connect=None, read=None, redirect=None, status=None)' 2022-06-10 03:23:16,903 [ INFO] JELLYFIN.jellyfin_apiclient_python.ws_client: Websocket url: wss://jellyfin.REDACTED.tld/socket?api_key=REDACTEDREDACTED&device_id=281de7bc-ad85-4590-8d76-bd428a3c2800 2022-06-10 03:23:16,904 [ DEBUG] urllib3.connectionpool: Starting new HTTPS connection (1): jellyfin.REDACTED.tld:443 2022-06-10 03:23:17,025 [ INFO] JELLYFIN.jellyfin_apiclient_python.ws_client: --->[ websocket ] 2022-06-10 03:23:17,025 [ DEBUG] event_handler: Unhandled Event WebSocketConnect: None 2022-06-10 03:23:17,243 [ DEBUG] urllib3.connectionpool: https://jellyfin.REDACTED.tld:443 "POST /Sessions/Capabilities/Full HTTP/1.1" 204 0 2022-06-10 03:23:17,323 [ DEBUG] JELLYFIN.jellyfin_apiclient_python.ws_client: ForceKeepAlive received from server. 2022-06-10 03:23:17,363 [ DEBUG] JELLYFIN.jellyfin_apiclient_python.ws_client: KeepAlive received from server.

iwalton3 commented 2 years ago

POST /Sessions/Capabilities/Full

I just checked the code and it literally does the full client registration on a reconnect attempt. I wonder if there are any associated Jellyfin server logs that might show what the problem could be.

calmcacil commented 2 years ago

Upon further testing it seems this might have something to do with the reverse proxy, (Caddy in my case) it seems to deliver two 502 errors when the client reconnects, but it seems like its not halting, i have a feeling this could be fixed in my case at least with a definable timeout before trying to reconnect after a server goes down.

If i restart just Caddy, it'll reconncet just fine, if i restart just jellyfin, it'll fail to properly reconnect, though the client seems to think its connected, restarting jellyfin first, then caddy, it will not reconnect. There are no error messages in the server log

caddy log for attempted reconnect. 2022-06-11T11:27:57.619667185Z {"level":"error","ts":1654946877.6195848,"logger":"http.log.error.log0","msg":"dial tcp 172.20.0.6:8096: connect: connection refused","request":{"remote_ip":"redacted","remote_port":"59323","proto":"HTTP/1.1","method":"GET","host":"jellyfin.redacted.tld","uri":"/system/info/public","headers":{"User-Agent":["Jellyfin-MPV-Shim/2.0.2"],"X-Emby-Authorization":["MediaBrowser Client=Jellyfin MPV Shim, Device=Taiga, DeviceId=967adda9-3c97-478b-9c50-f3a22df365d3, Version=2.0.2"],"Content-Type":["application/x-www-form-urlencoded; charset=UTF-8"],"X-Application":["Jellyfin MPV Shim/2.0.2"],"Accept-Charset":["UTF-8,*"],"Accept-Encoding":["gzip"],"Accept":["application/json"],"Connection":["keep-alive"]},"tls":{"resumed":false,"version":772,"cipher_suite":4865,"proto":"http/1.1","server_name":"jellyfin.redacted.tld"}},"duration":0.000952532,"status":502,"err_id":"e3a8xp00g","err_trace":"reverseproxy.statusError (reverseproxy.go:1196)"} 2022-06-11T11:27:59.746005668Z {"level":"error","ts":1654946879.7458923,"logger":"http.log.error.log0","msg":"dial tcp 172.20.0.6:8096: connect: connection refused","request":{"remote_ip":"redacted","remote_port":"59326","proto":"HTTP/1.1","method":"GET","host":"jellyfin.redacted.tld","uri":"/system/info/public","headers":{"Connection":["keep-alive"],"X-Application":["Jellyfin MPV Shim/2.0.2"],"Accept-Charset":["UTF-8,*"],"X-Emby-Authorization":["MediaBrowser Client=Jellyfin MPV Shim, Device=Taiga, DeviceId=967adda9-3c97-478b-9c50-f3a22df365d3, Version=2.0.2"],"User-Agent":["Jellyfin-MPV-Shim/2.0.2"],"Accept-Encoding":["gzip"],"Content-Type":["application/x-www-form-urlencoded; charset=UTF-8"],"Accept":["application/json"]},"tls":{"resumed":false,"version":772,"cipher_suite":4865,"proto":"http/1.1","server_name":"jellyfin.redacted.tld"}},"duration":0.000854082,"status":502,"err_id":"t6a1bmr6v","err_trace":"reverseproxy.statusError (reverseproxy.go:1196)"}

jellyfin log,

2022-06-11T11:39:32.561938481Z [11:39:32] [INF] [2] Main: Received a SIGTERM signal, shutting down
2022-06-11T11:39:32.562307332Z [11:39:32] [INF] [2] Main: Running query planner optimizations in the database... This might take a while
2022-06-11T11:39:32.567875173Z [11:39:32] [INF] [2] Emby.Server.Implementations.ApplicationHost: Disposing CoreAppHost
2022-06-11T11:39:32.568520544Z [11:39:32] [INF] [2] Emby.Server.Implementations.ApplicationHost: Disposing TvdbMissingEpisodeProvider
2022-06-11T11:39:32.569387476Z [11:39:32] [INF] [2] Emby.Server.Implementations.ApplicationHost: Disposing PlaybackScrobbler
2022-06-11T11:39:32.570035457Z [11:39:32] [INF] [2] Emby.Server.Implementations.ApplicationHost: Disposing EmbyTV
2022-06-11T11:39:32.570850069Z [11:39:32] [INF] [2] Emby.Server.Implementations.ApplicationHost: Disposing MusicBrainzAlbumProvider
2022-06-11T11:39:32.571012519Z [11:39:32] [INF] [2] Emby.Server.Implementations.ApplicationHost: Disposing EntryPoint
2022-06-11T11:39:32.571065799Z [11:39:32] [INF] [2] Emby.Server.Implementations.ApplicationHost: Disposing EntryPoint
2022-06-11T11:39:32.571415160Z [11:39:32] [INF] [2] Emby.Server.Implementations.ApplicationHost: Disposing ServerMediator
2022-06-11T11:39:32.572352621Z [11:39:32] [INF] [2] Emby.Server.Implementations.ApplicationHost: Disposing UdpServerEntryPoint
2022-06-11T11:39:32.572616862Z [11:39:32] [INF] [2] Emby.Server.Implementations.ApplicationHost: Disposing ExternalPortForwarding
2022-06-11T11:39:32.573233343Z [11:39:32] [INF] [2] Emby.Server.Implementations.EntryPoints.ExternalPortForwarding: Stopping NAT discovery
2022-06-11T11:39:32.575689817Z [11:39:32] [INF] [2] Emby.Server.Implementations.ApplicationHost: Disposing DeviceAccessEntryPoint
2022-06-11T11:39:32.575748128Z [11:39:32] [INF] [2] Emby.Server.Implementations.ApplicationHost: Disposing DlnaEntryPoint
2022-06-11T11:39:32.576141038Z [11:39:32] [INF] [2] Emby.Dlna.Main.DlnaEntryPoint: Disposing DeviceDiscovery
2022-06-11T11:39:32.576237458Z [11:39:32] [INF] [2] Emby.Dlna.Main.DlnaEntryPoint: Disposing SsdpCommunicationsServer
2022-06-11T11:39:32.577595901Z [11:39:32] [INF] [2] Emby.Server.Implementations.ApplicationHost: Disposing UserDataChangeNotifier
2022-06-11T11:39:32.577747741Z [11:39:32] [INF] [2] Emby.Server.Implementations.ApplicationHost: Disposing SchedulesDirect
2022-06-11T11:39:32.577894162Z [11:39:32] [INF] [2] Emby.Server.Implementations.ApplicationHost: Disposing LibraryMonitorStartup
2022-06-11T11:39:32.577950202Z [11:39:32] [INF] [2] Emby.Server.Implementations.ApplicationHost: Disposing NotificationEntryPoint
2022-06-11T11:39:32.578608603Z [11:39:32] [INF] [2] Emby.Server.Implementations.ApplicationHost: Disposing RecordingNotifier
2022-06-11T11:39:32.579557785Z [11:39:32] [INF] [2] Emby.Server.Implementations.ApplicationHost: Disposing LibraryChangedNotifier
2022-06-11T11:39:33.364744201Z [11:39:33] [INF] [1] Main: Jellyfin version: 10.8.0
2022-06-11T11:39:33.370216762Z [11:39:33] [INF] [1] Main: Environment Variables: ["[JELLYFIN_CONFIG_DIR, /config/config]", "[JELLYFIN_WEB_DIR, /jellyfin/jellyfin-web]", "[JELLYFIN_LOG_DIR, /config/log]", "[JELLYFIN_FFMPEG, /usr/lib/jellyfin-ffmpeg/ffmpeg]", "[JELLYFIN_CACHE_DIR, /cache]", "[JELLYFIN_PublishedServerUrl, https://jellyfin.redacted.tld]", "[DOTNET_SYSTEM_GLOBALIZATION_INVARIANT, 1]", "[JELLYFIN_DATA_DIR, /config]"]
2022-06-11T11:39:33.370248812Z [11:39:33] [INF] [1] Main: Arguments: ["/jellyfin/jellyfin.dll"]
2022-06-11T11:39:33.370720163Z [11:39:33] [INF] [1] Main: Operating system: Linux
2022-06-11T11:39:33.371132004Z [11:39:33] [INF] [1] Main: Architecture: X64
2022-06-11T11:39:33.371152224Z [11:39:33] [INF] [1] Main: 64-Bit Process: True
2022-06-11T11:39:33.371158664Z [11:39:33] [INF] [1] Main: User Interactive: True
2022-06-11T11:39:33.371166424Z [11:39:33] [INF] [1] Main: Processor count: 12
2022-06-11T11:39:33.371172314Z [11:39:33] [INF] [1] Main: Program data path: /config
2022-06-11T11:39:33.371218284Z [11:39:33] [INF] [1] Main: Web resources path: /jellyfin/jellyfin-web
2022-06-11T11:39:33.371244764Z [11:39:33] [INF] [1] Main: Application directory: /jellyfin/
2022-06-11T11:39:33.494259501Z [11:39:33] [INF] [1] Emby.Server.Implementations.AppBase.BaseConfigurationManager: Setting cache path: /config/cache
2022-06-11T11:39:33.522392855Z [11:39:33] [INF] [1] Emby.Server.Implementations.ApplicationHost: Loading assemblies
2022-06-11T11:39:33.529755060Z [11:39:33] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded assembly Jellyfin.Plugin.AniList, Version=5.0.0.0, Culture=neutral, PublicKeyToken=null from /config/plugins/AniList_5.0.0.0/Jellyfin.Plugin.AniList.dll
2022-06-11T11:39:33.530769312Z [11:39:33] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded assembly Jellyfin.Plugin.AnilistSync, Version=2.0.0.0, Culture=neutral, PublicKeyToken=null from /config/plugins/AnilistSync/Jellyfin.Plugin.AnilistSync.dll
2022-06-11T11:39:33.530799592Z [11:39:33] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Skipping disabled plugin 8.0.0.0 of Kodi Sync Queue
2022-06-11T11:39:33.531085443Z [11:39:33] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded assembly Jellyfin.Plugin.SessionCleaner, Version=2.0.0.0, Culture=neutral, PublicKeyToken=null from /config/plugins/Session Cleaner_2.0.0.0/Jellyfin.Plugin.SessionCleaner.dll
2022-06-11T11:39:33.533048436Z [11:39:33] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded assembly Jellyfin.Plugin.Tvdb, Version=8.0.0.0, Culture=neutral, PublicKeyToken=null from /config/plugins/TheTVDB_8.0.0.0/Jellyfin.Plugin.Tvdb.dll
2022-06-11T11:39:33.534041618Z [11:39:33] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded assembly TvDbSharper, Version=3.2.2.0, Culture=neutral, PublicKeyToken=null from /config/plugins/TheTVDB_8.0.0.0/TvDbSharper.dll
2022-06-11T11:39:33.536428083Z [11:39:33] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded assembly Trakt, Version=19.0.0.0, Culture=neutral, PublicKeyToken=null from /config/plugins/Trakt_19.0.0.0/Trakt.dll
2022-06-11T11:39:33.585245757Z [11:39:33] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN addresses : [fc00::/7,fe80::/10,10.0.0.0/8,172.16.0.0/12,192.168.0.0/16]
2022-06-11T11:39:33.585263487Z [11:39:33] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN exclusions : []
2022-06-11T11:39:33.585732378Z [11:39:33] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Using LAN addresses: [fc00::/7,fe80::/10,10.0.0.0/8,172.16.0.0/12,192.168.0.0/16]
2022-06-11T11:39:33.591996730Z [11:39:33] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Using bind addresses: []
2022-06-11T11:39:33.592011540Z [11:39:33] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Using bind exclusions: []
2022-06-11T11:39:33.593000522Z [11:39:33] [ERR] [1] Jellyfin.Networking.Manager.NetworkManager: Unable to parse bind override: https://jellyfin.redacted.tld
2022-06-11T11:39:37.656064256Z [11:39:37] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: AniList 5.0.0.0
2022-06-11T11:39:37.656503317Z [11:39:37] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: AnilistSync 2.0.0.0
2022-06-11T11:39:37.656910698Z [11:39:37] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: Session Cleaner 2.0.0.0
2022-06-11T11:39:37.657204998Z [11:39:37] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: TheTVDB 8.0.0.0
2022-06-11T11:39:37.657592399Z [11:39:37] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: Trakt 19.0.0.0
2022-06-11T11:39:37.657852770Z [11:39:37] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: TMDb 10.8.0.0
2022-06-11T11:39:37.658022470Z [11:39:37] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: Studio Images 10.8.0.0
2022-06-11T11:39:37.658186630Z [11:39:37] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: OMDb 10.8.0.0
2022-06-11T11:39:37.658355121Z [11:39:37] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: MusicBrainz 10.8.0.0
2022-06-11T11:39:37.658539631Z [11:39:37] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: AudioDB 10.8.0.0
2022-06-11T11:39:37.696272344Z [11:39:37] [INF] [1] Main: Kestrel listening on All Addresses
2022-06-11T11:39:37.730843451Z [11:39:37] [WRN] [1] Microsoft.AspNetCore.DataProtection.Repositories.EphemeralXmlRepository: Using an in-memory repository. Keys will not be persisted to storage.
2022-06-11T11:39:37.730954161Z [11:39:37] [WRN] [1] Microsoft.AspNetCore.DataProtection.KeyManagement.XmlKeyManager: Neither user profile nor HKLM registry available. Using an ephemeral key repository. Protected data will be unavailable when application exits.
2022-06-11T11:39:37.922879111Z [11:39:37] [WRN] [1] Microsoft.AspNetCore.DataProtection.KeyManagement.XmlKeyManager: No XML encryptor configured. Key {af523379-19bb-47bb-a646-787190c42584} may be persisted to storage in unencrypted form.
2022-06-11T11:39:37.936362357Z [11:39:37] [INF] [1] Emby.Server.Implementations.ApplicationHost: Running startup tasks
2022-06-11T11:39:37.961650816Z [11:39:37] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Extract Chapter Images set to fire at 2022-06-12 02:00:00.000 +00:00, which is 14:20:22.0386713 from now.
2022-06-11T11:39:37.995737812Z [11:39:37] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Found ffmpeg version 4.4.1
2022-06-11T11:39:38.021388382Z [11:39:38] [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", "flac", "mp3"]
2022-06-11T11:39:38.030332119Z [11:39:38] [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", "ac3", "flac", "libmp3lame", "libopus", "libvorbis", "srt"]
2022-06-11T11:39:38.044473626Z [11:39:38] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available filters: ["deinterlace_qsv", "deinterlace_vaapi", "hwupload_cuda", "hwupload_vaapi", "overlay_opencl", "overlay_qsv", "overlay_vaapi", "overlay_cuda", "scale_cuda", "scale_opencl", "scale_qsv", "scale_vaapi", "tonemap_cuda", "tonemap_opencl", "tonemap_vaapi", "vpp_qsv", "yadif_cuda", "zscale", "alphasrc"]
2022-06-11T11:39:38.108486260Z [11:39:38] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available hwaccel types: ["vdpau", "cuda", "vaapi", "qsv", "drm", "opencl"]
2022-06-11T11:39:38.121077084Z [11:39:38] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: FFmpeg: /usr/lib/jellyfin-ffmpeg/ffmpeg
2022-06-11T11:39:38.121896516Z [11:39:38] [INF] [1] Emby.Server.Implementations.ApplicationHost: ServerId: 89c73f54a1854b67ad002d756b3f6f1a
2022-06-11T11:39:38.238967361Z [11:39:38] [INF] [1] Emby.Server.Implementations.ApplicationHost: Executed all pre-startup entry points in 0:00:00.1041721
2022-06-11T11:39:38.239002231Z [11:39:38] [INF] [1] Emby.Server.Implementations.ApplicationHost: Core startup complete
2022-06-11T11:39:38.355223486Z [11:39:38] [INF] [1] Emby.Server.Implementations.ApplicationHost: Executed all post-startup entry points in 0:00:00.1161602
2022-06-11T11:39:38.355251656Z [11:39:38] [INF] [1] Main: Startup complete 0:00:05.1128054
2022-06-11T11:39:40.702519317Z [11:39:40] [INF] [14] Emby.Server.Implementations.HttpServer.WebSocketManager: WS redacted request
2022-06-11T11:39:40.967742529Z [11:39:40] [INF] [8] Emby.Server.Implementations.ScheduledTasks.TaskManager: StartupTrigger fired for task: Update Plugins
2022-06-11T11:39:40.968460340Z [11:39:40] [INF] [8] Emby.Server.Implementations.ScheduledTasks.TaskManager: Queuing task PluginUpdateTask
2022-06-11T11:39:40.971234276Z [11:39:40] [INF] [11] Emby.Server.Implementations.ScheduledTasks.TaskManager: Executing Update Plugins
2022-06-11T11:39:42.500383987Z [11:39:42] [INF] [15] Emby.Server.Implementations.ScheduledTasks.TaskManager: Update Plugins Completed after 0 minute(s) and 1 seconds
2022-06-11T11:39:42.506601900Z [11:39:42] [INF] [15] Emby.Server.Implementations.ScheduledTasks.TaskManager: ExecuteQueuedTasks
iwalton3 commented 2 years ago

I can confirm this is an issue in the new 10.8.0 server version and is a regression not related to any changes I made.

gerroon commented 2 years ago

I can confirm that this issue exists on the Linux Flatpak client

iwalton3 commented 2 years ago

This has been fixed in the latest release.

kraM1tt commented 2 months ago

This bug seems back? Latest JF and mpv shim, sometimes on system boot it will fail to connect (idk why the server is active) and just never reconnect