Server Version#: 1.24.5.5173
Windows 10 Pro 20H2
Been trying to re-join an old Plex instance (albeit, now updated to the latest version) to my account.
Every attempt in doing so now generates a ‘Not Authorised’ after attempting to sign-in/claim the server to my existing Plex account.
The only way I can regain access is by going to ‘Authorized devices’ and removing this Plex Media Server, restarting the service and then that allows local access once again.
I’ve included the main element of the logs where it seems to encounter an issue - replacing my email address with ‘USERID’.
2021 16:14:15.908 [1588] INFO - AutoUpdate: no updates available
Nov 20, 2021 16:14:15.909 [0032] DEBUG - Completed: [127.0.0.1:59129] 200 PUT /updater/check?download=0 (8 live) GZIP 53ms 323 bytes (pipelined: 17)
Nov 20, 2021 16:14:17.680 [7192] DEBUG - Request: [127.0.0.1:59129 (Loopback)] POST /myplex/claim?token=xxxxxxxxxxxxxxxxxxxx_jr-HC (8 live) GZIP
Nov 20, 2021 16:14:17.680 [7192] DEBUG - HTTP requesting POST https://plex.tv/api/claim/exchange?token=xxxxxxxxxxxxxxxxxxxx_jr-HC
Nov 20, 2021 16:14:17.840 [7192] DEBUG - HTTP/1.1 (0.2s) 200 response from POST https://plex.tv/api/claim/exchange?token=xxxxxxxxxxxxxxxxxxxx_jr-HC (reused)
Nov 20, 2021 16:14:17.840 [7192] DEBUG - MyPlex: Did token exchange for claim (returnCode: 200)
Nov 20, 2021 16:14:17.840 [7192] DEBUG - MyPlex: Stored token xxxxxxxxxxxxxxxxxxxx
Nov 20, 2021 16:14:17.840 [7192] DEBUG - MyPlex: Got a token poked, let's act on it.
Nov 20, 2021 16:14:17.841 [7192] DEBUG - MyPlex: username is USERID, login is USERID, home is 1, no pin
Nov 20, 2021 16:14:17.842 [7192] DEBUG - Disabling DLNA server because the server is now in a home.
Nov 20, 2021 16:14:17.842 [7192] WARN - [Analytics] Attempting to send unrecognized metric (dlna:settings). Skipping.
Nov 20, 2021 16:14:17.842 [7192] DEBUG - Sync: onMyPlexSignIn
Nov 20, 2021 16:14:17.842 [7192] WARN - Sync: Couldn't get sync lists without subscription.
Nov 20, 2021 16:14:17.843 [7192] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Nov 20, 2021 16:14:17.882 [7192] DEBUG - HTTP/1.1 (0.0s) 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (reused)
Nov 20, 2021 16:14:17.884 [7192] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Nov 20, 2021 16:14:17.927 [7192] DEBUG - HTTP/1.1 (0.0s) 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (reused)
Nov 20, 2021 16:14:17.929 [7192] DEBUG - HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Nov 20, 2021 16:14:18.052 [7192] DEBUG - HTTP/1.1 (0.1s) 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (reused)
Nov 20, 2021 16:14:18.056 [7192] DEBUG - Activity: registered new activity cec2fdd5-63a9-4fdc-a14e-4cd9464b9194 - "Processing subscriptions"
Nov 20, 2021 16:14:18.056 [7192] DEBUG - [EventSourceClient/pubsub] Resolving 176.58.127.172 port 443
Nov 20, 2021 16:14:18.056 [7904] DEBUG - Grabber: Cleaning up orphaned grabs.
Nov 20, 2021 16:14:18.057 [7192] DEBUG - MyPlex: sendMapping resetting state - previous mapping state: 'Unknown'.
Nov 20, 2021 16:14:18.057 [7192] DEBUG - MyPlex: mapping state set to 'Unknown'.
Nov 20, 2021 16:14:18.057 [8816] DEBUG - HTTP requesting GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Nov 20, 2021 16:14:18.057 [0032] DEBUG - [EventSourceClient/pubsub] Resolved 176.58.127.172 to 176.58.127.172
Nov 20, 2021 16:14:18.058 [12380] DEBUG - [MediaProviderManager] HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Nov 20, 2021 16:14:18.059 [7904] DEBUG - Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
Nov 20, 2021 16:14:18.059 [7904] DEBUG - Activity: registered new activity 9c41ded1-9ff2-4042-baa5-78ee1c5d5de9 - "Processing subscriptions"
Nov 20, 2021 16:14:18.059 [7904] DEBUG - Subscription: Scheduling subscriptions.
Nov 20, 2021 16:14:18.060 [7904] DEBUG - Subscription: Initialized 0 schedulers.
Nov 20, 2021 16:14:18.060 [7904] DEBUG - Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
Nov 20, 2021 16:14:18.060 [7904] DEBUG - Subscription: Scheduled all subscriptions in 0 ms.
Nov 20, 2021 16:14:18.060 [7904] DEBUG - Activity: Ended activity 9c41ded1-9ff2-4042-baa5-78ee1c5d5de9.
Nov 20, 2021 16:14:18.061 [7904] DEBUG - Subscription: There are 0 active grabs at the end.
Nov 20, 2021 16:14:18.061 [7904] DEBUG - Activity: Ended activity cec2fdd5-63a9-4fdc-a14e-4cd9464b9194.
Nov 20, 2021 16:14:18.062 [7904] DEBUG - Subscription: Refreshed all content in 5 ms.
Nov 20, 2021 16:14:18.075 [7192] DEBUG - MyPlex: Sending Server Info to myPlex (user=USERID, ip=, port=0)
Nov 20, 2021 16:14:18.075 [7192] DEBUG - HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx
Nov 20, 2021 16:14:18.100 [8816] DEBUG - HTTP/1.1 (0.0s) 403 response from GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (reused)
Nov 20, 2021 16:14:18.184 [7316] DEBUG - [EventSourceClient/pubsub] Connected in 87 ms.
Nov 20, 2021 16:14:18.184 [0032] DEBUG - [EventSourceClient/pubsub] Wrote data, reading reply.
Nov 20, 2021 16:14:18.263 [0032] DEBUG - [EventSourceClient/pubsub] Read HTTP reply header.
Nov 20, 2021 16:14:18.263 [0032] DEBUG - [EventSourceClient/pubsub] MyPlex: We appear to have regained Internet connectivity.
Nov 20, 2021 16:14:18.345 [12380] DEBUG - [MediaProviderManager] HTTP/1.1 (0.3s) 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Nov 20, 2021 16:14:18.346 [12380] DEBUG - [MediaProviderManager] discovered cloud provider (Movies & TV)
Nov 20, 2021 16:14:18.346 [12380] DEBUG - [MediaProviderManager] discovered cloud provider (Music)
Nov 20, 2021 16:14:18.346 [12380] DEBUG - [MediaProviderManager] discovered cloud provider (Metadata)
Nov 20, 2021 16:14:18.346 [12380] DEBUG - [MediaProviderManager] loading cloud provider details (Metadata) (alive: 0)
Nov 20, 2021 16:14:18.346 [8816] DEBUG - [MediaProviderManager] HTTP requesting GET https://vod.provider.plex.tv
Nov 20, 2021 16:14:18.346 [7904] DEBUG - [MediaProviderManager] HTTP requesting GET https://music.provider.plex.tv
Nov 20, 2021 16:14:18.398 [7192] DEBUG - HTTP/1.1 (0.3s) 422 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx
Nov 20, 2021 16:14:18.399 [7192] DEBUG - MyPlex: Published Mapping State response was 422
Nov 20, 2021 16:14:18.399 [7192] DEBUG - MyPlex: Got response for 97b454138b45f52a60718d03e23679b04ec047dd ~ registered :0
Nov 20, 2021 16:14:18.399 [7192] DEBUG - MyPlex: updating mapped state - current state: 'Unknown'
Nov 20, 2021 16:14:18.399 [7192] DEBUG - MyPlex: mapping state set to 'Mapped - Publishing'.
Nov 20, 2021 16:14:18.399 [7192] DEBUG - MyPlex: async reachability check - current mapped state: 'Mapped - Publishing'.
Nov 20, 2021 16:14:18.399 [7192] DEBUG - MyPlex: Requesting reachability check.
Nov 20, 2021 16:14:18.399 [7192] DEBUG - HTTP requesting PUT https://plex.tv/api/servers/97b454138b45f52a60718d03e23679b04ec047dd/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=442ad173-315e-4811-b89a-7015c26740d0
Nov 20, 2021 16:14:18.441 [7192] DEBUG - HTTP/1.1 (0.0s) 200 response from PUT https://plex.tv/api/servers/97b454138b45f52a60718d03e23679b04ec047dd/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=442ad173-315e-4811-b89a-7015c26740d0 (reused)
Nov 20, 2021 16:14:18.441 [0032] DEBUG - [EventSourceClient/pubsub] MyPlex: async reachability check - current mapped state: 'Mapped - Publishing'.
Nov 20, 2021 16:14:18.441 [0032] DEBUG - [EventSourceClient/pubsub] MyPlex: we already have requested a connectivity refresh for async identifier 442ad173-315e-4811-b89a-7015c26740d0 which has not yet expired.
Nov 20, 2021 16:14:18.442 [0032] DEBUG - [EventSourceClient/pubsub] Successfully connected to 176.58.127.172.
Nov 20, 2021 16:14:18.447 [12004] WARN - [MediaProviderManager] Unrecognized MediaProvider feature: availability
Nov 20, 2021 16:14:18.447 [12004] WARN - [MediaProviderManager] Unrecognized MediaProvider feature: availability-platforms
Nov 20, 2021 16:14:18.455 [7192] DEBUG - MyPlex: Updating device connections (from timer: 0)
Nov 20, 2021 16:14:18.455 [7192] DEBUG - HTTP requesting PUT https://plex.tv/devices/97b454138b45f52a60718d03e23679b04ec047dd?httpsEnabled=0&httpsRequired=0&dnsRebindingProtection=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Nov 20, 2021 16:14:18.469 [8816] DEBUG - [MediaProviderManager] HTTP/2.0 (0.1s) 200 response from GET https://vod.provider.plex.tv
Nov 20, 2021 16:14:18.493 [12380] WARN - [MediaProviderManager] Unrecognized MediaProvider feature: availability
Nov 20, 2021 16:14:18.493 [12380] WARN - [MediaProviderManager] Unrecognized MediaProvider feature: availability-platforms
Nov 20, 2021 16:14:18.493 [12380] DEBUG - [MediaProviderManager] loading cloud provider details (Music) (alive: 0)
Nov 20, 2021 16:14:18.493 [12380] DEBUG - [MediaProviderManager] HTTP requesting GET https://music.provider.plex.tv
Nov 20, 2021 16:14:18.497 [7904] DEBUG - [MediaProviderManager] HTTP/2.0 (0.1s) 200 response from GET https://music.provider.plex.tv
Nov 20, 2021 16:14:18.532 [7192] DEBUG - HTTP/1.1 (0.1s) 200 response from PUT https://plex.tv/devices/97b454138b45f52a60718d03e23679b04ec047dd?httpsEnabled=0&httpsRequired=0&dnsRebindingProtection=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (reused)
Nov 20, 2021 16:14:18.534 [7192] DEBUG - CERT: secure connections newly enabled, fetching a cert.
Nov 20, 2021 16:14:18.534 [7192] DEBUG - HTTP requesting GET https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/subject
Nov 20, 2021 16:14:18.583 [12380] DEBUG - [MediaProviderManager] HTTP/2.0 (0.1s) 200 response from GET https://music.provider.plex.tv
Nov 20, 2021 16:14:18.584 [12380] DEBUG - [MediaProviderManager] loading cloud provider details (Movies & TV) (alive: 1)
Nov 20, 2021 16:14:18.584 [12380] DEBUG - [MediaProviderManager] we had 1 cloud providers online, we now have 3
Nov 20, 2021 16:14:18.584 [12380] DEBUG - [MediaProviderManager] cloud provider (Metadata) is online and available
Nov 20, 2021 16:14:18.584 [12380] DEBUG - [MediaProviderManager] cloud provider (Music) is online and available
Nov 20, 2021 16:14:18.584 [12380] DEBUG - [MediaProviderManager] cloud provider (Movies & TV) is online and available
Nov 20, 2021 16:14:18.597 [7192] DEBUG - HTTP/1.1 (0.1s) 200 response from GET https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/subject (reused)
Nov 20, 2021 16:14:18.758 [7192] DEBUG - HTTP requesting PUT https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/csr?reason=new&invalidIn=0
Nov 20, 2021 16:14:18.854 [7192] DEBUG - HTTP/1.1 (0.1s) 204 response from PUT https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/csr?reason=new&invalidIn=0 (reused)
Nov 20, 2021 16:14:18.854 [7192] DEBUG - HTTP requesting GET https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/download
Nov 20, 2021 16:14:18.899 [7192] DEBUG - HTTP/1.1 (0.0s) 202 response from GET https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/download (reused)
Nov 20, 2021 16:14:19.906 [7192] DEBUG - HTTP requesting GET https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/download
Nov 20, 2021 16:14:19.956 [7192] DEBUG - HTTP/1.1 (0.0s) 202 response from GET https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/download (reused)
Nov 20, 2021 16:14:20.965 [7192] DEBUG - HTTP requesting GET https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/download
Nov 20, 2021 16:14:21.022 [7192] DEBUG - HTTP/1.1 (0.1s) 202 response from GET https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/download (reused)
Nov 20, 2021 16:14:22.025 [7192] DEBUG - HTTP requesting GET https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/download
Nov 20, 2021 16:14:22.080 [7192] DEBUG - HTTP/1.1 (0.1s) 202 response from GET https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/download (reused)
Nov 20, 2021 16:14:23.069 [0256] DEBUG - [PubsubServerManager/process] HTTP requesting GET https://plex.tv/services/pubsub/servers
Nov 20, 2021 16:14:23.085 [7192] DEBUG - HTTP requesting GET https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/download
Nov 20, 2021 16:14:23.135 [0256] DEBUG - [PubsubServerManager/process] HTTP/1.1 (0.1s) 200 response from GET https://plex.tv/services/pubsub/servers (reused)
Nov 20, 2021 16:14:23.223 [7192] DEBUG - HTTP/1.1 (0.1s) 202 response from GET https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/download
Nov 20, 2021 16:14:24.143 [0256] DEBUG - [PubsubServerManager/process] Time to connect to 139.162.219.82 was 49 ms.
Nov 20, 2021 16:14:24.143 [0256] DEBUG - [PubsubServerManager/process] Time to connect to 176.58.127.172 was 52 ms.
Nov 20, 2021 16:14:24.143 [0256] DEBUG - [PubsubServerManager/process] Time to connect to 172.104.133.220 was 67 ms.
Nov 20, 2021 16:14:24.143 [0256] DEBUG - [PubsubServerManager/process] Time to connect to 50.116.51.249 was 225 ms.
Nov 20, 2021 16:14:24.143 [0256] DEBUG - [PubsubServerManager/process] Time to connect to 45.33.103.230 was 227 ms.
Nov 20, 2021 16:14:24.143 [0256] DEBUG - [PubsubServerManager/process] Time to connect to 172.105.29.107 was 250 ms.
Nov 20, 2021 16:14:24.143 [0256] DEBUG - [PubsubServerManager/process] Time to connect to 45.33.119.35 was 271 ms.
Nov 20, 2021 16:14:24.143 [0256] DEBUG - [PubsubServerManager/process] Time to connect to 184.105.148.113 was 316 ms.
Nov 20, 2021 16:14:24.143 [0256] DEBUG - [PubsubServerManager/process] Time to connect to 139.162.120.52 was 525 ms.
Nov 20, 2021 16:14:24.143 [0256] DEBUG - [PubsubServerManager/process] Time to connect to 139.162.54.192 was 665 ms.
Nov 20, 2021 16:14:24.143 [0256] DEBUG - [PubsubServerManager/process] Updating best ping time for 176.58.127.172 to 52 ms.
Nov 20, 2021 16:14:24.143 [0256] DEBUG - [PubsubServerManager/process/EventSourceClient/base] Stopping.
Nov 20, 2021 16:14:24.143 [0256] DEBUG - [PubsubServerManager/process/EventSourceClient/base] Stopping.
Nov 20, 2021 16:14:24.143 [0256] DEBUG - [PubsubServerManager/process/EventSourceClient/base] Stopping.
Nov 20, 2021 16:14:24.144 [0256] DEBUG - [PubsubServerManager/process/EventSourceClient/base] Stopping.
Nov 20, 2021 16:14:24.144 [0256] DEBUG - [PubsubServerManager/process/EventSourceClient/base] Stopping.
Nov 20, 2021 16:14:24.144 [0256] DEBUG - [PubsubServerManager/process/EventSourceClient/base] Stopping.
Nov 20, 2021 16:14:24.144 [0256] DEBUG - [PubsubServerManager/process/EventSourceClient/base] Stopping.
Nov 20, 2021 16:14:24.144 [0256] DEBUG - [PubsubServerManager/process/EventSourceClient/base] Stopping.
Nov 20, 2021 16:14:24.144 [0256] DEBUG - [PubsubServerManager/process/EventSourceClient/base] Stopping.
Nov 20, 2021 16:14:24.144 [0256] DEBUG - [PubsubServerManager/process/EventSourceClient/base] Stopping.
Nov 20, 2021 16:14:24.251 [7192] DEBUG - HTTP requesting GET https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/download
Nov 20, 2021 16:14:24.317 [7192] DEBUG - HTTP/1.1 (0.1s) 202 response from GET https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/download (reused)
Nov 20, 2021 16:14:25.325 [7192] DEBUG - HTTP requesting GET https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/download
Nov 20, 2021 16:14:25.378 [7192] DEBUG - HTTP/1.1 (0.1s) 202 response from GET https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/download (reused)
Nov 20, 2021 16:14:26.379 [7192] DEBUG - HTTP requesting GET https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/download
Nov 20, 2021 16:14:26.439 [7192] DEBUG - HTTP/1.1 (0.1s) 202 response from GET https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/download (reused)
Nov 20, 2021 16:14:27.451 [7192] DEBUG - HTTP requesting GET https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/download
Nov 20, 2021 16:14:27.506 [7192] DEBUG - HTTP/1.1 (0.1s) 202 response from GET https://plex.tv/api/v2/devices/97b454138b45f52a60718d03e23679b04ec047dd/certificate/download (reused)
Nov 20, 2021 16:14:28.061 [12004] DEBUG - HTTP requesting GET https://plex.tv/api/resources.xml?includeHttps=1&includeRelay=1&auth_token=xxxxxxxxxxxxxxxxxxxx
Nov 20, 2021 16:14:28.157 [12004] DEBUG - HTTP/1.1 (0.1s) 200 response from GET https://plex.tv/api/resources.xml?includeHttps=1&includeRelay=1&auth_token=xxxxxxxxxxxxxxxxxxxx (reused)
Nov 20, 2021 16:14:28.158 [8676] DEBUG - Connection Tester: Testing the server NL1 - HS with 2 URLs.
