Skip to content
View in the app

A better way to browse. Learn more.
Unraid

A full-screen app on your home screen with push notifications, badges and more.

To install this app on iOS and iPadOS
  1. Tap the Share icon in Safari
  2. Scroll the menu and tap Add to Home Screen.
  3. Tap Add in the top-right corner.
To install this app on Android
  1. Tap the 3-dot menu (⋮) in the top-right corner of the browser.
  2. Tap Add to Home screen or Install app.
  3. Confirm by tapping Install.
Unraid Summer Sale in live: 20% off Starter and Unleashed + buy one, get one 50% off

Bullhead28

Members

  • Joined

  • Last visited

View Profile Find content

Everything posted by Bullhead28

  1. Plex Container Crash-Loop on Fresh Install - 500 Error on Claim (Exhaustive Troubleshooting Inside)

    Bullhead28 replied to Bullhead28's topic in General Support
    Once I specified the 32500 public port, remote access seems to have worked. I was able to access it from outside my network (for now). It seems there are connection issues with Plex though. It is repeatedly failing with HTTP 408 Request Timeout errors: Oct 14, 2025 09:28:44.260 [22999257205560] Debug — Request: [192.168.1.158:59897 (Subnet)] GET /:/websockets/notifications?filters=log (9 live) #30 TLS GZIP Signed-in Token (assphace) Oct 14, 2025 09:28:44.260 [22999257205560] Debug — WebSocket: Performing handshake from origin http://192.168.1.167:32400 Oct 14, 2025 09:28:44.260 [22999257205560] Debug — Beginning read from WebSocket Oct 14, 2025 09:28:44.267 [22999259315000] Debug — Request: [192.168.1.158:59895 (Subnet)] GET /activities (9 live) #33 TLS GZIP Signed-in Token (assphace) Oct 14, 2025 09:28:44.268 [22999306230584] Debug — Completed: [192.168.1.158:59895] 200 GET /activities (9 live) #33 TLS GZIP 0ms 482 bytes (pipelined: 2) Oct 14, 2025 09:28:54.798 [22999289043768] Debug — [HttpClient/HCl#22] HTTP simulating 408 after curl timeout Oct 14, 2025 09:28:54.798 [22999274081080] Warning — MyPlex: failed to update country code, response returned code 408 Oct 14, 2025 09:28:54.799 [22999308659512] Debug — [HCl#24] HTTP requesting GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:28:54.799 [22999274081080] Debug — Activity: registered new activity 3b9cb029-8714-4f0a-9c18-b6bf2ed8728d - "Processing subscriptions" Oct 14, 2025 09:28:54.799 [22999274081080] Debug — [HCl#25] HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:28:54.799 [22999252986680] Debug — Grabber: Cleaning up orphaned grabs. Oct 14, 2025 09:28:54.800 [22999252986680] Debug — Grabber: Cleaned up 0 decrepit directories in 0.0 sec. Oct 14, 2025 09:28:54.800 [22999252986680] Debug — Activity: registered new activity c0b624ac-af05-4f42-b99f-6e0fa9e15e54 - "Processing subscriptions" Oct 14, 2025 09:28:54.800 [22999252986680] Debug — Subscription: Scheduling subscriptions. Oct 14, 2025 09:28:54.800 [22999252986680] Debug — Subscription: Initialized 0 schedulers. Oct 14, 2025 09:28:54.800 [22999252986680] Debug — Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively. Oct 14, 2025 09:28:54.800 [22999252986680] Debug — Subscription: Scheduled all subscriptions in 0 ms. Oct 14, 2025 09:28:54.800 [22999252986680] Debug — Activity: Ended activity c0b624ac-af05-4f42-b99f-6e0fa9e15e54. Oct 14, 2025 09:28:54.800 [22999252986680] Debug — Subscription: There are 0 active grabs at the end. Oct 14, 2025 09:28:54.800 [22999252986680] Debug — Activity: Ended activity 3b9cb029-8714-4f0a-9c18-b6bf2ed8728d. Oct 14, 2025 09:28:54.800 [22999252986680] Debug — Subscription: Refreshed all content in 0 ms. Oct 14, 2025 09:28:57.733 [22999289043768] Debug — [HttpClient/HCl#23] HTTP simulating 408 after curl timeout Oct 14, 2025 09:28:57.733 [22999280409400] Error — [Req#2d] HTTP 408 downloading url https://plex.tv/updater/products/5/check.xml?build=linux-x86_64&channel=16&distribution=debian&version=1.42.2.10156-f737b826c Oct 14, 2025 09:28:57.733 [22999255096120] Info — [Req#2d] AutoUpdate: error getting data from plex.tv Oct 14, 2025 09:28:57.733 [22999304121144] Debug — Completed: [192.168.1.158:59887] 200 PUT /updater/check?download=0 (9 live) #2d TLS GZIP 15004ms 327 bytes (pipelined: 3) Oct 14, 2025 09:28:57.799 [22999291153208] Debug — [MediaProviderManager] Refreshing media providers Oct 14, 2025 09:28:57.799 [22999291153208] Debug — [MediaProviderManager/Response::fetch/HCl#27] HTTP requesting GET https://plex.tv/media/providers Oct 14, 2025 09:28:59.799 [22999250877240] Debug — [PubsubServerManager/process/HCl#28] HTTP requesting GET https://plex.tv/services/pubsub/servers Oct 14, 2025 09:29:04.800 [22999265643320] Debug — [HCl#29] HTTP requesting GET https://plex.tv/api/resources.xml?includeHttps=1&includeRelay=1&auth_token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:29:09.040 [22999280409400] Debug — BPQ: [Starting] -> [Processing] Oct 14, 2025 09:29:09.041 [22999280409400] Debug — BPQ: generating queue items from 0 generator(s) Oct 14, 2025 09:29:09.041 [22999280409400] Debug — BPQ: generated 0 item(s) for queue Oct 14, 2025 09:29:09.041 [22999280409400] Debug — BPQ: [Processing] -> [Idle] Oct 14, 2025 09:29:09.783 [22999255096120] Debug — Request: [127.0.0.1:50568 (Loopback)] GET /servers (5 live) #34 GZIP Signed-in Token (assphace) Oct 14, 2025 09:29:09.783 [22999306230584] Debug — Completed: [127.0.0.1:50568] 200 GET /servers (5 live) #34 GZIP 0ms 499 bytes Oct 14, 2025 09:29:09.784 [22999255096120] Debug — Request: [127.0.0.1:50578 (Loopback)] GET / (5 live) #8e GZIP Signed-in Token (assphace) Oct 14, 2025 09:29:09.785 [22999306230584] Debug — Completed: [127.0.0.1:50578] 200 GET / (5 live) #8e GZIP 0ms 5190 bytes Oct 14, 2025 09:29:09.786 [22999255096120] Debug — Request: [127.0.0.1:50592 (Loopback)] GET /library/sections (5 live) #94 GZIP Signed-in Token (assphace) Oct 14, 2025 09:29:09.787 [22999306230584] Debug — Completed: [127.0.0.1:50592] 200 GET /library/sections (5 live) #94 GZIP 0ms 632 bytes Oct 14, 2025 09:29:09.787 [22999255096120] Debug — Request: [127.0.0.1:50602 (Loopback)] GET /channels/all (5 live) #9a GZIP Signed-in Token (assphace) Oct 14, 2025 09:29:09.787 [22999306230584] Debug — Completed: [127.0.0.1:50602] 200 GET /channels/all (5 live) #9a GZIP 0ms 387 bytes Oct 14, 2025 09:29:09.789 [22999255096120] Debug — Request: [127.0.0.1:50606 (Loopback)] GET /library/sections (5 live) #a0 GZIP Signed-in Token (assphace) Oct 14, 2025 09:29:09.789 [22999306230584] Debug — Completed: [127.0.0.1:50606] 200 GET /library/sections (5 live) #a0 GZIP 0ms 632 bytes Oct 14, 2025 09:29:09.790 [22999255096120] Debug — Request: [127.0.0.1:50608 (Loopback)] GET /channels/all (5 live) #a6 GZIP Signed-in Token (assphace) Oct 14, 2025 09:29:09.790 [22999306230584] Debug — Completed: [127.0.0.1:50608] 200 GET /channels/all (5 live) #a6 GZIP 0ms 387 bytes Oct 14, 2025 09:29:09.801 [22999289043768] Debug — [HttpClient/HCl#24] HTTP simulating 408 after curl timeout Oct 14, 2025 09:29:09.801 [22999289043768] Debug — [HttpClient/HCl#25] HTTP simulating 408 after curl timeout Oct 14, 2025 09:29:09.801 [22999274081080] Debug — [Analytics] Using cached data for privacy preferences Oct 14, 2025 09:29:09.802 [22999274081080] Debug — [HCl#2a] HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:29:12.802 [22999289043768] Debug — [HttpClient/HCl#27] HTTP simulating 408 after curl timeout Oct 14, 2025 09:29:12.802 [22999257205560] Error — [MediaProviderManager/Response::fetch] failed to complete query (408) Oct 14, 2025 09:29:12.802 [22999291153208] Debug — [MediaProviderManager] Media provider refresh complete Oct 14, 2025 09:29:14.803 [22999289043768] Debug — [HttpClient/HCl#28] HTTP simulating 408 after curl timeout Oct 14, 2025 09:29:19.803 [22999289043768] Debug — [HttpClient/HCl#29] HTTP simulating 408 after curl timeout Oct 14, 2025 09:29:19.803 [22999265643320] Error — Error parsing content. Oct 14, 2025 09:29:19.803 [22999265643320] Error — Error parsing XML: Error parsing file. Oct 14, 2025 09:29:19.804 [22999265643320] Debug — Media Server: Tested all servers in 0.0 seconds. Oct 14, 2025 09:29:19.807 [22999252986680] Debug — MyPlex: Updating device connections (from timer: 1) Oct 14, 2025 09:29:19.807 [22999252986680] Debug — [HCl#2b] HTTP requesting PUT https://plex.tv/devices/763210fd8637e6f1363aacb93db194df4a6254fd?Connection[][uri]=http://192.168.1.167:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&natLoopbackSupported=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:29:24.806 [22999289043768] Debug — [HttpClient/HCl#2a] HTTP simulating 408 after curl timeout Oct 14, 2025 09:29:24.806 [22999274081080] Debug — [AutoUpdateRequestHandler] Using cached data for update channels Oct 14, 2025 09:29:24.806 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolving 97.107.133.152 port 443 Oct 14, 2025 09:29:24.807 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolved 97.107.133.152 to 97.107.133.152:443 Oct 14, 2025 09:29:24.807 [22999274081080] Debug — [HCl#2c] HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:29:34.810 [22999289043768] Debug — [HttpClient/HCl#2b] HTTP simulating 408 after curl timeout Oct 14, 2025 09:29:34.810 [22999252986680] Warning — MyPlex: Updating device connections failed, retrying in 80 seconds. Oct 14, 2025 09:29:39.810 [22999289043768] Debug — [HttpClient/HCl#2c] HTTP simulating 408 after curl timeout Oct 14, 2025 09:29:39.810 [22999274081080] Debug — FeatureManager: Using cached data for features list Oct 14, 2025 09:29:39.811 [22999274081080] Debug — [HCl#2d] HTTP requesting GET https://plex.tv/api/v2/server/users/features Oct 14, 2025 09:29:44.807 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:29:44.807 [22999306230584] Error — [EventSourceClient/pubsub/97.107.133.152:443] Retrying in 15 seconds. Oct 14, 2025 09:29:54.815 [22999289043768] Debug — [HttpClient/HCl#2d] HTTP simulating 408 after curl timeout Oct 14, 2025 09:29:54.815 [22999291153208] Debug — MyPlex: using cached data for request for https://plex.tv/api/v2/server/users/features Oct 14, 2025 09:29:54.817 [22999274081080] Debug — CERT: Certificate will not expire soon; we'll check again in a week. Oct 14, 2025 09:29:54.817 [22999263533880] Debug — MyPlex: sendMapping resetting state - previous mapping state: 'Not Mapped'. Oct 14, 2025 09:29:54.817 [22999263533880] Debug — MyPlex: mapping state set to 'Unknown'. Oct 14, 2025 09:29:54.817 [22999274081080] Debug — MyPlex: Updating device connections (from timer: 0) Oct 14, 2025 09:29:54.817 [22999274081080] Debug — [HCl#2e] HTTP requesting PUT https://plex.tv/devices/763210fd8637e6f1363aacb93db194df4a6254fd?Connection[][uri]=http://192.168.1.167:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&natLoopbackSupported=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:29:54.817 [22999263533880] Debug — MyPlex: Sending Server Info to myPlex ([email protected], ip=, port=0) Oct 14, 2025 09:29:54.817 [22999263533880] Debug — [HCl#2f] HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:29:59.812 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolving 97.107.133.152 port 443 Oct 14, 2025 09:29:59.812 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolved 97.107.133.152 to 97.107.133.152:443 Oct 14, 2025 09:30:09.820 [22999289043768] Debug — [HttpClient/HCl#2e] HTTP simulating 408 after curl timeout Oct 14, 2025 09:30:09.820 [22999289043768] Debug — [HttpClient/HCl#2f] HTTP simulating 408 after curl timeout Oct 14, 2025 09:30:09.821 [22999263533880] Debug — MyPlex: Published Mapping State response was 408 Oct 14, 2025 09:30:09.821 [22999274081080] Warning — MyPlex: Updating device connections failed, retrying in 10 seconds. Oct 14, 2025 09:30:09.821 [22999263533880] Warning — MyPlex: Invalid response when mapping state (code=408): Oct 14, 2025 09:30:09.821 [22999263533880] Debug — MyPlex: updating mapped state - current state: 'Not Mapped' Oct 14, 2025 09:30:09.821 [22999263533880] Debug — MyPlex: mapping state set to 'Not Mapped'. Oct 14, 2025 09:30:19.817 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:30:19.817 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr] 0 total hosts available in region, starting tests Oct 14, 2025 09:30:19.817 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr] No working hosts in region, refreshing full host list Oct 14, 2025 09:30:19.821 [22999248767800] Debug — MyPlex: Updating device connections (from timer: 1) Oct 14, 2025 09:30:19.821 [22999248767800] Debug — [HCl#30] HTTP requesting PUT https://plex.tv/devices/763210fd8637e6f1363aacb93db194df4a6254fd?Connection[][uri]=http://192.168.1.167:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&natLoopbackSupported=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:30:24.817 [22999306230584] Warning — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr/process] Connection to 97.107.133.152 failed: Operation timed out. Oct 14, 2025 09:30:24.817 [22999306230584] Error — [EventSourceClient/pubsub/97.107.133.152:443] Retrying in 30 seconds. Oct 14, 2025 09:30:34.825 [22999289043768] Debug — [HttpClient/HCl#30] HTTP simulating 408 after curl timeout Oct 14, 2025 09:30:34.825 [22999248767800] Warning — MyPlex: Updating device connections failed, retrying in 20 seconds. Oct 14, 2025 09:30:39.821 [22999306230584] Debug — [EventSourceClient/connectiontester/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:30:54.817 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolving 97.107.133.152 port 443 Oct 14, 2025 09:30:54.817 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolved 97.107.133.152 to 97.107.133.152:443 Oct 14, 2025 09:30:54.826 [22999265643320] Debug — MyPlex: Updating device connections (from timer: 1) Oct 14, 2025 09:30:54.826 [22999265643320] Debug — [HCl#31] HTTP requesting PUT https://plex.tv/devices/763210fd8637e6f1363aacb93db194df4a6254fd?Connection[][uri]=http://192.168.1.167:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&natLoopbackSupported=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:31:09.830 [22999289043768] Debug — [HttpClient/HCl#31] HTTP simulating 408 after curl timeout Oct 14, 2025 09:31:09.830 [22999265643320] Warning — MyPlex: Updating device connections failed, retrying in 40 seconds. Oct 14, 2025 09:31:10.249 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Connected in 15431 ms. Oct 14, 2025 09:31:10.249 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Wrote data, reading reply. Oct 14, 2025 09:31:10.303 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Read HTTP reply header. Oct 14, 2025 09:31:10.303 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: We appear to have regained Internet connectivity. Oct 14, 2025 09:31:10.325 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] PublicAddressManager: Obtaining public address and mapping port. Oct 14, 2025 09:31:10.325 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: async reachability check - current mapped state: 'Not Mapped'. Oct 14, 2025 09:31:10.325 [22999304121144] Warning — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: attempted a reachability check but we're not yet mapped. Oct 14, 2025 09:31:10.325 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Successfully connected to 97.107.133.152. Oct 14, 2025 09:31:10.325 [22999252986680] Debug — [EventSourceClient/pubsub/97.107.133.152:443] PublicAddressManager: Obtaining public IP. Oct 14, 2025 09:31:10.325 [22999252986680] Debug — [EventSourceClient/pubsub/97.107.133.152:443/HCl#32] HTTP requesting GET https://v4.plex.tv/pms/:/ip Oct 14, 2025 09:31:14.818 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:31:14.818 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: We appear to have lost Internet connectivity, resetting device URL cache. Oct 14, 2025 09:31:14.818 [22999306230584] Error — [EventSourceClient/pubsub/97.107.133.152:443] Retrying in 15 seconds. Oct 14, 2025 09:31:25.328 [22999289043768] Debug — [HttpClient/HCl#32] HTTP simulating 408 after curl timeout Oct 14, 2025 09:31:25.328 [22999252986680] Error — [EventSourceClient/pubsub/97.107.133.152:443] PublicAddressManager: Unable to get public IP adddress from myPlex (httpCode=408): Oct 14, 2025 09:31:25.329 [22999252986680] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections (from timer: 0) Oct 14, 2025 09:31:25.329 [22999252986680] Debug — [EventSourceClient/pubsub/97.107.133.152:443/HCl#33] HTTP requesting PUT https://plex.tv/devices/763210fd8637e6f1363aacb93db194df4a6254fd?Connection[][uri]=http://192.168.1.167:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&natLoopbackSupported=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:31:29.823 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolving 97.107.133.152 port 443 Oct 14, 2025 09:31:29.823 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolved 97.107.133.152 to 97.107.133.152:443 Oct 14, 2025 09:31:40.331 [22999289043768] Debug — [HttpClient/HCl#33] HTTP simulating 408 after curl timeout Oct 14, 2025 09:31:40.332 [22999252986680] Warning — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections failed, retrying in 10 seconds. Oct 14, 2025 09:31:49.828 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:31:49.828 [22999306230584] Error — [EventSourceClient/pubsub/97.107.133.152:443] Retrying in 30 seconds. Oct 14, 2025 09:31:50.332 [22999248767800] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections (from timer: 1) Oct 14, 2025 09:31:50.332 [22999248767800] Debug — [EventSourceClient/pubsub/97.107.133.152:443/HCl#34] HTTP requesting PUT https://plex.tv/devices/763210fd8637e6f1363aacb93db194df4a6254fd?Connection[][uri]=http://192.168.1.167:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&natLoopbackSupported=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:32:05.336 [22999289043768] Debug — [HttpClient/HCl#34] HTTP simulating 408 after curl timeout Oct 14, 2025 09:32:05.336 [22999248767800] Warning — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections failed, retrying in 20 seconds. Oct 14, 2025 09:32:19.833 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolving 97.107.133.152 port 443 Oct 14, 2025 09:32:19.833 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolved 97.107.133.152 to 97.107.133.152:443 Oct 14, 2025 09:32:25.337 [22999248767800] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections (from timer: 1) Oct 14, 2025 09:32:25.337 [22999248767800] Debug — [EventSourceClient/pubsub/97.107.133.152:443/HCl#35] HTTP requesting PUT https://plex.tv/devices/763210fd8637e6f1363aacb93db194df4a6254fd?Connection[][uri]=http://192.168.1.167:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&natLoopbackSupported=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:32:39.838 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:32:39.838 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr] 0 total hosts available in region, starting tests Oct 14, 2025 09:32:39.838 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr] No working hosts in region, refreshing full host list Oct 14, 2025 09:32:40.340 [22999289043768] Debug — [HttpClient/HCl#35] HTTP simulating 408 after curl timeout Oct 14, 2025 09:32:40.340 [22999248767800] Warning — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections failed, retrying in 40 seconds. Oct 14, 2025 09:32:44.838 [22999304121144] Warning — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr/process] Connection to 97.107.133.152 failed: Operation timed out. Oct 14, 2025 09:32:44.838 [22999304121144] Error — [EventSourceClient/pubsub/97.107.133.152:443] Retrying in 60 seconds. Oct 14, 2025 09:32:59.843 [22999304121144] Debug — [EventSourceClient/connectiontester/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:33:20.342 [22999252986680] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections (from timer: 1) Oct 14, 2025 09:33:20.342 [22999252986680] Debug — [EventSourceClient/pubsub/97.107.133.152:443/HCl#36] HTTP requesting PUT https://plex.tv/devices/763210fd8637e6f1363aacb93db194df4a6254fd?Connection[][uri]=http://192.168.1.167:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&natLoopbackSupported=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:33:35.345 [22999289043768] Debug — [HttpClient/HCl#36] HTTP simulating 408 after curl timeout Oct 14, 2025 09:33:35.345 [22999252986680] Warning — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections failed, retrying in 80 seconds. Oct 14, 2025 09:33:44.839 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolving 97.107.133.152 port 443 Oct 14, 2025 09:33:44.839 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolved 97.107.133.152 to 97.107.133.152:443 Oct 14, 2025 09:34:04.839 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:34:04.839 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr] 0 total hosts available in region, starting tests Oct 14, 2025 09:34:04.839 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr] No working hosts in region, refreshing full host list Oct 14, 2025 09:34:09.838 [22999306230584] Warning — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr/process] Connection to 97.107.133.152 failed: Operation timed out. Oct 14, 2025 09:34:09.838 [22999306230584] Error — [EventSourceClient/pubsub/97.107.133.152:443] Retrying in 120 seconds. Oct 14, 2025 09:34:12.803 [22999265643320] Debug — [MediaProviderManager] Refreshing media providers Oct 14, 2025 09:34:12.803 [22999265643320] Debug — [MediaProviderManager/Response::fetch/HCl#37] HTTP requesting GET https://plex.tv/media/providers Oct 14, 2025 09:34:24.839 [22999306230584] Debug — [EventSourceClient/connectiontester/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:34:27.807 [22999289043768] Debug — [HttpClient/HCl#37] HTTP simulating 408 after curl timeout Oct 14, 2025 09:34:27.807 [22999308659512] Error — [MediaProviderManager/Response::fetch] failed to complete query (408) Oct 14, 2025 09:34:27.807 [22999265643320] Debug — [MediaProviderManager] Media provider refresh complete Oct 14, 2025 09:34:55.346 [22999250877240] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections (from timer: 1) Oct 14, 2025 09:34:55.346 [22999250877240] Debug — [EventSourceClient/pubsub/97.107.133.152:443/HCl#38] HTTP requesting PUT https://plex.tv/devices/763210fd8637e6f1363aacb93db194df4a6254fd?Connection[][uri]=http://192.168.1.167:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&natLoopbackSupported=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:35:10.350 [22999289043768] Debug — [HttpClient/HCl#38] HTTP simulating 408 after curl timeout Oct 14, 2025 09:35:10.350 [22999250877240] Warning — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections failed, retrying in 160 seconds. Oct 14, 2025 09:35:18.800 [22999250877240] Debug — [ViewStateSync] Starting Oct 14, 2025 09:35:18.800 [22999250877240] Debug — [ViewStateSync] Fetching user tokens Oct 14, 2025 09:35:18.800 [22999250877240] Debug — [ViewStateSync/HCl#39] HTTP requesting GET https://plex.tv/api/v2/server/users/services?auth_token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:35:19.132 [22999289043768] Debug — [HttpClient/HCl#39] HTTP/1.1 (0.3s) 200 response from GET https://plex.tv/api/v2/server/users/services?auth_token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:35:19.133 [22999308659512] Debug — [ViewStateSync] Got token for user 1 (assphace) Oct 14, 2025 09:35:19.133 [22999308659512] Debug — [ViewStateSync/HCl#3a] HTTP requesting GET https://plex.tv/api/v2/server/users/9597898/view_state_sync Oct 14, 2025 09:35:19.233 [22999289043768] Debug — [HttpClient/HCl#3a] HTTP/1.1 (0.1s) 200 response from GET https://plex.tv/api/v2/server/users/9597898/view_state_sync (reused) Oct 14, 2025 09:35:19.234 [22999308659512] Debug — [ViewStateSync] Starting for user 1 Oct 14, 2025 09:35:19.234 [22999308659512] Debug — [ViewStateSync] Sending state Oct 14, 2025 09:35:19.234 [22999308659512] Debug — [ViewStateSync] All state sent Oct 14, 2025 09:35:19.234 [22999308659512] Debug — [ViewStateSync] Getting state Oct 14, 2025 09:35:19.234 [22999308659512] Debug — [ViewStateSync/HCl#3b] HTTP requesting GET https://scrobbles.plex.tv/state/9597898?after=MTc2MDQyNTA4NzI1OC02MmUwNjJlYmRjYjUwNjhhYjEwZjA2MWY%3D&count=100 Oct 14, 2025 09:35:19.331 [22999289043768] Debug — [HttpClient/HCl#3b] HTTP/2.0 (0.1s) 200 response from GET https://scrobbles.plex.tv/state/9597898?after=MTc2MDQyNTA4NzI1OC02MmUwNjJlYmRjYjUwNjhhYjEwZjA2MWY%3D&count=100 Oct 14, 2025 09:35:19.331 [22999308659512] Debug — [ViewStateSync] Reading 0 states Oct 14, 2025 09:35:19.331 [22999308659512] Debug — [ViewStateSync] Done getting states Oct 14, 2025 09:35:19.331 [22999308659512] Debug — [ViewStateSync] Finished Oct 14, 2025 09:36:09.843 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolving 97.107.133.152 port 443 Oct 14, 2025 09:36:09.843 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolved 97.107.133.152 to 97.107.133.152:443 Oct 14, 2025 09:36:09.867 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Connected in 23 ms. Oct 14, 2025 09:36:09.867 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Wrote data, reading reply. Oct 14, 2025 09:36:09.908 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Read HTTP reply header. Oct 14, 2025 09:36:09.908 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: We appear to have regained Internet connectivity. Oct 14, 2025 09:36:09.927 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] PublicAddressManager: Obtaining public address and mapping port. Oct 14, 2025 09:36:09.927 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: async reachability check - current mapped state: 'Not Mapped'. Oct 14, 2025 09:36:09.927 [22999306230584] Warning — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: attempted a reachability check but we're not yet mapped. Oct 14, 2025 09:36:09.927 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Successfully connected to 97.107.133.152. Oct 14, 2025 09:36:09.928 [22999248767800] Debug — [EventSourceClient/pubsub/97.107.133.152:443] PublicAddressManager: Obtaining public IP. Oct 14, 2025 09:36:09.928 [22999248767800] Debug — [EventSourceClient/pubsub/97.107.133.152:443/HCl#3c] HTTP requesting GET https://v4.plex.tv/pms/:/ip Oct 14, 2025 09:36:24.931 [22999289043768] Debug — [HttpClient/HCl#3c] HTTP simulating 408 after curl timeout Oct 14, 2025 09:36:24.932 [22999248767800] Error — [EventSourceClient/pubsub/97.107.133.152:443] PublicAddressManager: Unable to get public IP adddress from myPlex (httpCode=408): Oct 14, 2025 09:36:24.932 [22999248767800] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections (from timer: 0) Oct 14, 2025 09:36:24.932 [22999248767800] Debug — [EventSourceClient/pubsub/97.107.133.152:443/HCl#3d] HTTP requesting PUT https://plex.tv/devices/763210fd8637e6f1363aacb93db194df4a6254fd?Connection[][uri]=http://192.168.1.167:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&natLoopbackSupported=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:36:29.846 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:36:29.846 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: We appear to have lost Internet connectivity, resetting device URL cache. Oct 14, 2025 09:36:29.846 [22999304121144] Error — [EventSourceClient/pubsub/97.107.133.152:443] Retrying in 15 seconds. Oct 14, 2025 09:36:39.936 [22999289043768] Debug — [HttpClient/HCl#3d] HTTP simulating 408 after curl timeout Oct 14, 2025 09:36:39.936 [22999248767800] Warning — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections failed, retrying in 10 seconds. Oct 14, 2025 09:36:44.847 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolving 97.107.133.152 port 443 Oct 14, 2025 09:36:44.847 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolved 97.107.133.152 to 97.107.133.152:443 Oct 14, 2025 09:36:49.938 [22999248767800] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections (from timer: 1) Oct 14, 2025 09:36:49.938 [22999248767800] Debug — [EventSourceClient/pubsub/97.107.133.152:443/HCl#3e] HTTP requesting PUT https://plex.tv/devices/763210fd8637e6f1363aacb93db194df4a6254fd?Connection[][uri]=http://192.168.1.167:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&natLoopbackSupported=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:37:04.848 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:37:04.848 [22999304121144] Error — [EventSourceClient/pubsub/97.107.133.152:443] Retrying in 30 seconds. Oct 14, 2025 09:37:04.941 [22999289043768] Debug — [HttpClient/HCl#3e] HTTP simulating 408 after curl timeout Oct 14, 2025 09:37:04.942 [22999248767800] Warning — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections failed, retrying in 20 seconds. Oct 14, 2025 09:37:24.942 [22999265643320] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections (from timer: 1) Oct 14, 2025 09:37:24.942 [22999265643320] Debug — [EventSourceClient/pubsub/97.107.133.152:443/HCl#3f] HTTP requesting PUT https://plex.tv/devices/763210fd8637e6f1363aacb93db194df4a6254fd?Connection[][uri]=http://192.168.1.167:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&natLoopbackSupported=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:37:34.849 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolving 97.107.133.152 port 443 Oct 14, 2025 09:37:34.850 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolved 97.107.133.152 to 97.107.133.152:443 Oct 14, 2025 09:37:39.946 [22999289043768] Debug — [HttpClient/HCl#3f] HTTP simulating 408 after curl timeout Oct 14, 2025 09:37:39.946 [22999265643320] Warning — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections failed, retrying in 40 seconds. Oct 14, 2025 09:37:54.851 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:37:54.851 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr] 0 total hosts available in region, starting tests Oct 14, 2025 09:37:54.851 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr] No working hosts in region, refreshing full host list Oct 14, 2025 09:37:59.850 [22999306230584] Warning — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr/process] Connection to 97.107.133.152 failed: Operation timed out. Oct 14, 2025 09:37:59.850 [22999306230584] Error — [EventSourceClient/pubsub/97.107.133.152:443] Retrying in 60 seconds. Oct 14, 2025 09:38:14.851 [22999304121144] Debug — [EventSourceClient/connectiontester/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:38:19.947 [22999252986680] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections (from timer: 1) Oct 14, 2025 09:38:19.948 [22999252986680] Debug — [EventSourceClient/pubsub/97.107.133.152:443/HCl#40] HTTP requesting PUT https://plex.tv/devices/763210fd8637e6f1363aacb93db194df4a6254fd?Connection[][uri]=http://192.168.1.167:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&natLoopbackSupported=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:38:34.952 [22999289043768] Debug — [HttpClient/HCl#40] HTTP simulating 408 after curl timeout Oct 14, 2025 09:38:34.952 [22999252986680] Warning — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections failed, retrying in 80 seconds. Oct 14, 2025 09:38:59.854 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolving 97.107.133.152 port 443 Oct 14, 2025 09:38:59.855 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolved 97.107.133.152 to 97.107.133.152:443 Oct 14, 2025 09:39:19.859 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:39:19.859 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr] 0 total hosts available in region, starting tests Oct 14, 2025 09:39:19.859 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr] No working hosts in region, refreshing full host list Oct 14, 2025 09:39:24.858 [22999304121144] Warning — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr/process] Connection to 97.107.133.152 failed: Operation timed out. Oct 14, 2025 09:39:24.858 [22999304121144] Error — [EventSourceClient/pubsub/97.107.133.152:443] Retrying in 120 seconds. Oct 14, 2025 09:39:27.807 [22999248767800] Debug — [MediaProviderManager] Refreshing media providers Oct 14, 2025 09:39:27.808 [22999248767800] Debug — [MediaProviderManager/Response::fetch/HCl#41] HTTP requesting GET https://plex.tv/media/providers Oct 14, 2025 09:39:39.861 [22999304121144] Debug — [EventSourceClient/connectiontester/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:39:42.811 [22999289043768] Debug — [HttpClient/HCl#41] HTTP simulating 408 after curl timeout Oct 14, 2025 09:39:42.812 [22999274081080] Error — [MediaProviderManager/Response::fetch] failed to complete query (408) Oct 14, 2025 09:39:42.812 [22999248767800] Debug — [MediaProviderManager] Media provider refresh complete Oct 14, 2025 09:39:54.953 [22999248767800] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections (from timer: 1) Oct 14, 2025 09:39:54.954 [22999248767800] Debug — [EventSourceClient/pubsub/97.107.133.152:443/HCl#42] HTTP requesting PUT https://plex.tv/devices/763210fd8637e6f1363aacb93db194df4a6254fd?Connection[][uri]=http://192.168.1.167:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&natLoopbackSupported=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:40:09.958 [22999289043768] Debug — [HttpClient/HCl#42] HTTP simulating 408 after curl timeout Oct 14, 2025 09:40:09.958 [22999248767800] Warning — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections failed, retrying in 160 seconds. Oct 14, 2025 09:41:24.859 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolving 97.107.133.152 port 443 Oct 14, 2025 09:41:24.859 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolved 97.107.133.152 to 97.107.133.152:443 Oct 14, 2025 09:41:24.891 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Connected in 31 ms. Oct 14, 2025 09:41:24.891 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Wrote data, reading reply. Oct 14, 2025 09:41:24.940 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Read HTTP reply header. Oct 14, 2025 09:41:24.940 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: We appear to have regained Internet connectivity. Oct 14, 2025 09:41:24.959 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] PublicAddressManager: Obtaining public address and mapping port. Oct 14, 2025 09:41:24.959 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: async reachability check - current mapped state: 'Not Mapped'. Oct 14, 2025 09:41:24.959 [22999304121144] Warning — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: attempted a reachability check but we're not yet mapped. Oct 14, 2025 09:41:24.959 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Successfully connected to 97.107.133.152. Oct 14, 2025 09:41:24.959 [22999252986680] Debug — [EventSourceClient/pubsub/97.107.133.152:443] PublicAddressManager: Obtaining public IP. Oct 14, 2025 09:41:24.959 [22999252986680] Debug — [EventSourceClient/pubsub/97.107.133.152:443/HCl#43] HTTP requesting GET https://v4.plex.tv/pms/:/ip Oct 14, 2025 09:41:39.963 [22999289043768] Debug — [HttpClient/HCl#43] HTTP simulating 408 after curl timeout Oct 14, 2025 09:41:39.964 [22999252986680] Error — [EventSourceClient/pubsub/97.107.133.152:443] PublicAddressManager: Unable to get public IP adddress from myPlex (httpCode=408): Oct 14, 2025 09:41:39.964 [22999252986680] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections (from timer: 0) Oct 14, 2025 09:41:39.964 [22999252986680] Debug — [EventSourceClient/pubsub/97.107.133.152:443/HCl#44] HTTP requesting PUT https://plex.tv/devices/763210fd8637e6f1363aacb93db194df4a6254fd?Connection[][uri]=http://192.168.1.167:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&natLoopbackSupported=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:41:44.860 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:41:44.860 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: We appear to have lost Internet connectivity, resetting device URL cache. Oct 14, 2025 09:41:44.860 [22999304121144] Error — [EventSourceClient/pubsub/97.107.133.152:443] Retrying in 15 seconds. Oct 14, 2025 09:41:54.967 [22999289043768] Debug — [HttpClient/HCl#44] HTTP simulating 408 after curl timeout Oct 14, 2025 09:41:54.968 [22999252986680] Warning — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections failed, retrying in 10 seconds. Oct 14, 2025 09:41:59.864 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolving 97.107.133.152 port 443 Oct 14, 2025 09:41:59.864 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolved 97.107.133.152 to 97.107.133.152:443 Oct 14, 2025 09:42:04.968 [22999248767800] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections (from timer: 1) Oct 14, 2025 09:42:04.968 [22999248767800] Debug — [EventSourceClient/pubsub/97.107.133.152:443/HCl#45] HTTP requesting PUT https://plex.tv/devices/763210fd8637e6f1363aacb93db194df4a6254fd?Connection[][uri]=http://192.168.1.167:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&natLoopbackSupported=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:42:19.866 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:42:19.866 [22999306230584] Error — [EventSourceClient/pubsub/97.107.133.152:443] Retrying in 30 seconds. Oct 14, 2025 09:42:19.972 [22999289043768] Debug — [HttpClient/HCl#45] HTTP simulating 408 after curl timeout Oct 14, 2025 09:42:19.972 [22999248767800] Warning — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections failed, retrying in 20 seconds. Oct 14, 2025 09:42:39.973 [22999248767800] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections (from timer: 1) Oct 14, 2025 09:42:39.973 [22999248767800] Debug — [EventSourceClient/pubsub/97.107.133.152:443/HCl#46] HTTP requesting PUT https://plex.tv/devices/763210fd8637e6f1363aacb93db194df4a6254fd?Connection[][uri]=http://192.168.1.167:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&natLoopbackSupported=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:42:49.871 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolving 97.107.133.152 port 443 Oct 14, 2025 09:42:49.872 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolved 97.107.133.152 to 97.107.133.152:443 Oct 14, 2025 09:42:54.976 [22999289043768] Debug — [HttpClient/HCl#46] HTTP simulating 408 after curl timeout Oct 14, 2025 09:42:54.976 [22999248767800] Warning — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections failed, retrying in 40 seconds. Oct 14, 2025 09:43:09.877 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:43:09.877 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr] 0 total hosts available in region, starting tests Oct 14, 2025 09:43:09.877 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr] No working hosts in region, refreshing full host list Oct 14, 2025 09:43:14.876 [22999304121144] Warning — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr/process] Connection to 97.107.133.152 failed: Operation timed out. Oct 14, 2025 09:43:14.876 [22999304121144] Error — [EventSourceClient/pubsub/97.107.133.152:443] Retrying in 60 seconds. Oct 14, 2025 09:43:29.882 [22999304121144] Debug — [EventSourceClient/connectiontester/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:43:34.977 [22999265643320] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections (from timer: 1) Oct 14, 2025 09:43:34.977 [22999265643320] Debug — [EventSourceClient/pubsub/97.107.133.152:443/HCl#47] HTTP requesting PUT https://plex.tv/devices/763210fd8637e6f1363aacb93db194df4a6254fd?Connection[][uri]=http://192.168.1.167:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&natLoopbackSupported=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:43:49.981 [22999289043768] Debug — [HttpClient/HCl#47] HTTP simulating 408 after curl timeout Oct 14, 2025 09:43:49.981 [22999265643320] Warning — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections failed, retrying in 80 seconds. Oct 14, 2025 09:44:14.877 [22999304121144] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolving 97.107.133.152 port 443 Oct 14, 2025 09:44:14.877 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Resolved 97.107.133.152 to 97.107.133.152:443 Oct 14, 2025 09:44:34.878 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:44:34.878 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr] 0 total hosts available in region, starting tests Oct 14, 2025 09:44:34.878 [22999306230584] Debug — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr] No working hosts in region, refreshing full host list Oct 14, 2025 09:44:39.877 [22999306230584] Warning — [EventSourceClient/pubsub/97.107.133.152:443/PubsubServerManager/getNextWorkingHost/ewr/process] Connection to 97.107.133.152 failed: Operation timed out. Oct 14, 2025 09:44:39.878 [22999306230584] Error — [EventSourceClient/pubsub/97.107.133.152:443] Retrying in 120 seconds. Oct 14, 2025 09:44:42.812 [22999248767800] Debug — [MediaProviderManager] Refreshing media providers Oct 14, 2025 09:44:42.812 [22999248767800] Debug — [MediaProviderManager/Response::fetch/HCl#48] HTTP requesting GET https://plex.tv/media/providers Oct 14, 2025 09:44:54.879 [22999304121144] Debug — [EventSourceClient/connectiontester/97.107.133.152:443] Failure: 125 - Operation canceled. Oct 14, 2025 09:44:57.816 [22999289043768] Debug — [HttpClient/HCl#48] HTTP simulating 408 after curl timeout Oct 14, 2025 09:44:57.816 [22999274081080] Error — [MediaProviderManager/Response::fetch] failed to complete query (408) Oct 14, 2025 09:44:57.816 [22999248767800] Debug — [MediaProviderManager] Media provider refresh complete Oct 14, 2025 09:45:09.982 [22999265643320] Debug — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections (from timer: 1) Oct 14, 2025 09:45:09.982 [22999265643320] Debug — [EventSourceClient/pubsub/97.107.133.152:443/HCl#49] HTTP requesting PUT https://plex.tv/devices/763210fd8637e6f1363aacb93db194df4a6254fd?Connection[][uri]=http://192.168.1.167:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=0&natLoopbackSupported=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx Oct 14, 2025 09:45:24.987 [22999289043768] Debug — [HttpClient/HCl#49] HTTP simulating 408 after curl timeout Oct 14, 2025 09:45:24.987 [22999265643320] Warning — [EventSourceClient/pubsub/97.107.133.152:443] MyPlex: Updating device connections failed, retrying in 160 seconds.

  2. Plex Container Crash-Loop on Fresh Install - 500 Error on Claim (Exhaustive Troubleshooting Inside)

    Bullhead28 replied to Bullhead28's topic in General Support
    Works on Wi-Fi but not on 5G

  3. Plex Container Crash-Loop on Fresh Install - 500 Error on Claim (Exhaustive Troubleshooting Inside)

    Bullhead28 replied to Bullhead28's topic in General Support
    Tried that... it just spins and spins and then goes back to the same screen.

  4. Plex Container Crash-Loop on Fresh Install - 500 Error on Claim (Exhaustive Troubleshooting Inside)

    Bullhead28 replied to Bullhead28's topic in General Support
    Tried the other option I must be doing something wrong

  5. Plex Container Crash-Loop on Fresh Install - 500 Error on Claim (Exhaustive Troubleshooting Inside)

    Bullhead28 replied to Bullhead28's topic in General Support
    It says it worked but the UI doesn't show claimed: root@AssPhaceServer:~# #!/bin/bash set -euo pipefail # ========================= # Set these for your box # ========================= CONTAINER="plex" # Docker container name (linuxserver/plex) APPDATA_BASE="/mnt/user/appdata/plex" # your container's /config on host PREFS="$APPDATA_BASE/Library/Application Support/Plex Media Server/Preferences.xml" # ======= Paste fresh token from https://plex.tv/claim ======= CLAIM_TOKEN="claim-11zympyvYWLrJ8WxDMqw" # ============================================================ # Quick validation if [[ -z "$CLAIM_TOKEN" || "$CLAIM_TOKEN" != claim-* ]]; then echo "ERROR: Set CLAIM_TOKEN to a valid value starting with 'claim-'." exit 1 fi command -v docker >/dev/null || { echo "ERROR: docker not found."; exit 1; } command -v curl >/dev/null || echo "WARN: curl not found on host; will try container." [[ -f "$PREFS" ]] || { echo "ERROR: Preferences.xml not found: $PREFS"; exit 1; } echo "==> Stopping container: ${CONTAINER}" docker stop "${CONTAINER}" >/dev/null || true # Backup Preferences.xml ts="$(date +%F_%H-%M-%S)" backup="${PREFS}.bak.${ts}" echo "==> Backing up Preferences.xml -> ${backup}" cp -f "${PREFS}" "${backup}" # Read ProcessedMachineIdentifier (nice-to-have for claim exchange) echo "==> Reading ProcessedMachineIdentifier" PMI="$(grep -o 'ProcessedMachineIdentifier=\"[^"]*\"' "${PREFS}" | cut -d'"' -f2 || true)" if [[ -z "${PMI}" ]]; then echo "WARN: No ProcessedMachineIdentifier found. We'll rely on POST /myplex/claim after start." else echo " PMI=${PMI}" fi # ---- Scrub all PlexOnline* “claimed” markers so linuxserver will accept a new claim ---- # (Per multiple reports, these four block re-claim if present.) echo "==> Removing PlexOnline* attributes (Username/Mail/Home/Token) from Preferences.xml" awk '{ gsub(/ PlexOnlineUsername="[^"]*"/,""); gsub(/ PlexOnlineMail="[^"]*"/,""); gsub(/ PlexOnlineHome="[^"]*"/,""); gsub(/ PlexOnlineToken="[^"]*"/,""); print echo "Backup kept at: ${backup}"signed in and claimed."-> General"fine; 4xx means expired claim)."lex/claim?token=${CLAIM_TOKEN}" || true)"rue)"CODE="" ==> Stopping container: plex ==> Backing up Preferences.xml -> /mnt/user/appdata/plex/Library/Application Support/Plex Media Server/Preferences.xml.bak.2025-10-13_21-39-08 ==> Reading ProcessedMachineIdentifier grep: warning: stray \ before " grep: warning: stray \ before " PMI=763210fd8637e6f1363aacb93db194df4a6254fd ==> Removing PlexOnline* attributes (Username/Mail/Home/Token) from Preferences.xml ==> Ensuring ownership nobody:users on appdata (optional) ==> Starting container: plex ==> Waiting for Plex to come up... Plex is responding. ==> Exchanging claim token with plex.tv (using PMI) curl: (22) The requested URL returned error: 404 WARN: Claim exchange failed (token expired or PMI mismatch). Will try POST /myplex/claim. ==> POSTing claim token to local /myplex/claim as final nudge Claim POST returned HTTP 200 (200/201/204 are fine; 4xx means expired claim). ==> Done. Open: http://<UNRAID-IP>:32400/web -> Settings -> General You should see the server signed in and claimed. Backup kept at: /mnt/user/appdata/plex/Library/Application Support/Plex Media Server/Preferences.xml.bak.2025-10-13_21-39-08 root@AssPhaceServer:~#

  6. Plex Container Crash-Loop on Fresh Install - 500 Error on Claim (Exhaustive Troubleshooting Inside)

    Bullhead28 replied to Bullhead28's topic in General Support
    Trying to reach it from outside my network. I was able to successfully claim in via the Web UI after a couple of attempts but now it's back to this.

  7. Plex Container Crash-Loop on Fresh Install - 500 Error on Claim (Exhaustive Troubleshooting Inside)

    Bullhead28 replied to Bullhead28's topic in General Support
    ....... back to the unclaimed loop 🤦‍♂️

  8. Plex Container Crash-Loop on Fresh Install - 500 Error on Claim (Exhaustive Troubleshooting Inside)

    Bullhead28 replied to Bullhead28's topic in General Support
    For some reason it seems to be trying to use the tv.plex.agents.movie agent even though Plex Movie is selected for both Scanner and Agent Oct 11, 2025 17:05:28.418 [22781388442424] Debug — Request: [192.168.1.158:54199 (Subnet)] GET /:/websockets/notifications?filters=log (7 live) #515 GZIP Oct 11, 2025 17:05:28.418 [22781388442424] Debug — WebSocket: Performing handshake from origin http://192.168.1.167:32400 Oct 11, 2025 17:05:28.418 [22781388442424] Debug — Beginning read from WebSocket Oct 11, 2025 17:05:28.544 [22781388442424] Debug — Request: [192.168.1.158:54198 (Subnet)] GET /activities (7 live) #516 GZIP Oct 11, 2025 17:05:28.544 [22781427813176] Debug — Completed: [192.168.1.158:54198] 200 GET /activities (7 live) #516 GZIP 0ms 508 bytes (pipelined: 2) Oct 11, 2025 17:05:32.921 [22781388442424] Debug — Request: [127.0.0.1:42752 (Loopback)] GET /library/changestamp (7 live) #50e GZIP Token () Oct 11, 2025 17:05:32.921 [22781425703736] Debug — Completed: [127.0.0.1:42752] 200 GET /library/changestamp (7 live) #50e GZIP 0ms 417 bytes (pipelined: 4) Oct 11, 2025 17:05:32.922 [22781388442424] Debug — Request: [127.0.0.1:42752 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=59&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (7 live) #525 GZIP Token () Oct 11, 2025 17:05:32.922 [22781427813176] Debug — Completed: [127.0.0.1:42752] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=59&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (7 live) #525 GZIP 0ms 195 bytes (pipelined: 5) Oct 11, 2025 17:05:32.923 [22781388442424] Debug — Request: [127.0.0.1:42752 (Loopback)] GET /library/changestamp (7 live) #528 GZIP Token () Oct 11, 2025 17:05:32.923 [22781425703736] Debug — Completed: [127.0.0.1:42752] 200 GET /library/changestamp (7 live) #528 GZIP 0ms 417 bytes (pipelined: 6) Oct 11, 2025 17:05:32.924 [22781388442424] Debug — Request: [127.0.0.1:42752 (Loopback)] GET /library/changestamp (7 live) #531 GZIP Token () Oct 11, 2025 17:05:32.924 [22781427813176] Debug — Completed: [127.0.0.1:42752] 200 GET /library/changestamp (7 live) #531 GZIP 0ms 417 bytes (pipelined: 7) Oct 11, 2025 17:05:32.927 [22781433453368] Debug — Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 777 is 0 (success) Oct 11, 2025 17:05:32.927 [22781382019896] Debug — Killing job. Oct 11, 2025 17:05:32.927 [22781382019896] Debug — Signalling job ID 777 with 9 Oct 11, 2025 17:05:32.927 [22781382019896] Debug — Media Analyzer: Background analysis completed in 5.6 seconds, removing 1 IDs Oct 11, 2025 17:05:32.927 [22781384129336] Debug — MetadataAgentPostProcessor: adding id 59 to refresh queue (0 items in queue) Oct 11, 2025 17:05:32.927 [22781384129336] Debug — Native Scanner: Executed Media Analysis stage in 5.58 sec. Oct 11, 2025 17:05:32.927 [22781382019896] Debug — Media Analyzer: Performing on-the-fly analysis on 1 item. Oct 11, 2025 17:05:32.927 [22781382019896] Debug — [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/46f74ab-560174306fe167a5978a79dd-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Media Scanner" --analyze --log-file-suffix " Analysis" --item 60 Oct 11, 2025 17:05:32.927 [22781390551864] Debug — Native Scanner: Executed Local Metadata stage in 0.00 sec. Oct 11, 2025 17:05:32.927 [22781382019896] Debug — [JobRunner] Jobs: Starting child process with pid 783 Oct 11, 2025 17:05:32.928 [22781397056312] Debug — Skipping hash check, no size match for 74384948125 bytes. Oct 11, 2025 17:05:32.928 [22781397056312] Debug — No match for hash. Oct 11, 2025 17:05:32.928 [22781372742456] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:05:32.928 [22781372742456] Error — MetadataAgentPostProcessor: failed to find agent for identifier tv.plex.agents.movie Oct 11, 2025 17:05:32.928 [22781397056312] Debug — Added new metadata item (American Gangster) with ID 61 Oct 11, 2025 17:05:32.928 [22781386312504] Debug — [LibraryTimeline] Scanner activity on section 1: 61 added, 0 deleted Oct 11, 2025 17:05:32.930 [22781397056312] Debug — Native Scanner: Executed Add to Database stage in 0.00 sec. Oct 11, 2025 17:05:32.957 [22781394848568] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:05:32.957 [22781394848568] Warning — Scanner [Plex Movie]: unable to find agent match for item file '/data/Movies/American Gangster (2007) - UHD {Theatrical Film}.mkv' Oct 11, 2025 17:05:32.957 [22781394848568] Debug — Native Scanner: Executed Agent Match stage in 0.03 sec. Oct 11, 2025 17:05:32.989 [22781388442424] Debug — Request: [127.0.0.1:60466 (Loopback)] GET /library/changestamp (7 live) #517 GZIP Token () Oct 11, 2025 17:05:32.989 [22781427813176] Debug — Completed: [127.0.0.1:60466] 200 GET /library/changestamp (7 live) #517 GZIP 0ms 417 bytes (pipelined: 1) Oct 11, 2025 17:05:33.122 [22781388442424] Debug — Request: [127.0.0.1:60466 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=60&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (7 live) #569 GZIP Token () Oct 11, 2025 17:05:33.122 [22781427813176] Debug — Completed: [127.0.0.1:60466] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=60&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (7 live) #569 GZIP 0ms 195 bytes (pipelined: 2) Oct 11, 2025 17:05:33.122 [22781388442424] Debug — Request: [127.0.0.1:60466 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=60&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (7 live) #56e GZIP Token () Oct 11, 2025 17:05:33.123 [22781427813176] Debug — Completed: [127.0.0.1:60466] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=60&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (7 live) #56e GZIP 0ms 195 bytes (pipelined: 3) Oct 11, 2025 17:05:34.642 [22781388442424] Debug — Request: [127.0.0.1:60466 (Loopback)] GET /library/changestamp (7 live) #575 GZIP Token () Oct 11, 2025 17:05:34.643 [22781425703736] Debug — Completed: [127.0.0.1:60466] 200 GET /library/changestamp (7 live) #575 GZIP 0ms 417 bytes (pipelined: 4) Oct 11, 2025 17:05:34.644 [22781388442424] Debug — Request: [127.0.0.1:60466 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=60&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (7 live) #57c GZIP Token () Oct 11, 2025 17:05:34.644 [22781425703736] Debug — Completed: [127.0.0.1:60466] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=60&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (7 live) #57c GZIP 0ms 195 bytes (pipelined: 5) Oct 11, 2025 17:05:34.645 [22781388442424] Debug — Request: [127.0.0.1:60466 (Loopback)] GET /library/changestamp (7 live) #581 GZIP Token () Oct 11, 2025 17:05:34.645 [22781425703736] Debug — Completed: [127.0.0.1:60466] 200 GET /library/changestamp (7 live) #581 GZIP 0ms 417 bytes (pipelined: 6) Oct 11, 2025 17:05:34.646 [22781388442424] Debug — Request: [127.0.0.1:60466 (Loopback)] GET /library/changestamp (7 live) #588 GZIP Token () Oct 11, 2025 17:05:34.646 [22781427813176] Debug — Completed: [127.0.0.1:60466] 200 GET /library/changestamp (7 live) #588 GZIP 0ms 417 bytes (pipelined: 7) Oct 11, 2025 17:05:34.649 [22781433453368] Debug — Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 783 is 0 (success) Oct 11, 2025 17:05:34.649 [22781382019896] Debug — Killing job. Oct 11, 2025 17:05:34.649 [22781382019896] Debug — Signalling job ID 783 with 9 Oct 11, 2025 17:05:34.649 [22781382019896] Debug — Media Analyzer: Background analysis completed in 1.7 seconds, removing 1 IDs Oct 11, 2025 17:05:34.649 [22781384129336] Debug — MetadataAgentPostProcessor: adding id 60 to refresh queue (0 items in queue) Oct 11, 2025 17:05:34.649 [22781384129336] Debug — Native Scanner: Executed Media Analysis stage in 1.72 sec. Oct 11, 2025 17:05:34.649 [22781382019896] Debug — Media Analyzer: Performing on-the-fly analysis on 1 item. Oct 11, 2025 17:05:34.649 [22781382019896] Debug — [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/46f74ab-560174306fe167a5978a79dd-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Media Scanner" --analyze --log-file-suffix " Analysis" --item 61 Oct 11, 2025 17:05:34.649 [22781384129336] Debug — Native Scanner: Executed Local Metadata stage in 0.00 sec. Oct 11, 2025 17:05:34.649 [22781382019896] Debug — [JobRunner] Jobs: Starting child process with pid 789 Oct 11, 2025 17:05:34.650 [22781394848568] Debug — Skipping hash check, no size match for 66375602316 bytes. Oct 11, 2025 17:05:34.650 [22781394848568] Debug — No match for hash. Oct 11, 2025 17:05:34.651 [22781372742456] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:05:34.651 [22781372742456] Error — MetadataAgentPostProcessor: failed to find agent for identifier tv.plex.agents.movie Oct 11, 2025 17:05:34.651 [22781394848568] Debug — Added new metadata item (American Gangster) with ID 62 Oct 11, 2025 17:05:34.651 [22781386312504] Debug — [LibraryTimeline] Scanner activity on section 1: 62 added, 0 deleted Oct 11, 2025 17:05:34.675 [22781390551864] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:05:34.675 [22781390551864] Warning — Scanner [Plex Movie]: unable to find agent match for item file '/data/Movies/American Gangster (2007).mkv' Oct 11, 2025 17:05:34.675 [22781390551864] Debug — Native Scanner: Executed Agent Match stage in 0.03 sec. Oct 11, 2025 17:05:34.686 [22781394848568] Debug — Native Scanner: Executed Add to Database stage in 0.04 sec. Oct 11, 2025 17:05:34.707 [22781388442424] Debug — Request: [127.0.0.1:60478 (Loopback)] GET /library/changestamp (7 live) #566 GZIP Token () Oct 11, 2025 17:05:34.707 [22781427813176] Debug — Completed: [127.0.0.1:60478] 200 GET /library/changestamp (7 live) #566 GZIP 0ms 417 bytes (pipelined: 1) Oct 11, 2025 17:05:34.832 [22781388442424] Debug — Request: [127.0.0.1:60478 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=61&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (7 live) #5c0 GZIP Token () Oct 11, 2025 17:05:34.832 [22781425703736] Debug — Completed: [127.0.0.1:60478] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=61&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (7 live) #5c0 GZIP 0ms 195 bytes (pipelined: 2) Oct 11, 2025 17:05:34.833 [22781388442424] Debug — Request: [127.0.0.1:60478 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=61&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (7 live) #5c6 GZIP Token () Oct 11, 2025 17:05:34.833 [22781425703736] Debug — Completed: [127.0.0.1:60478] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=61&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (7 live) #5c6 GZIP 0ms 195 bytes (pipelined: 3) Oct 11, 2025 17:05:40.779 [22781388442424] Debug — Request: [127.0.0.1:60478 (Loopback)] GET /library/changestamp (7 live) #5ca GZIP Token () Oct 11, 2025 17:05:40.779 [22781425703736] Debug — Completed: [127.0.0.1:60478] 200 GET /library/changestamp (7 live) #5ca GZIP 0ms 417 bytes (pipelined: 4) Oct 11, 2025 17:05:40.780 [22781388442424] Debug — Request: [127.0.0.1:60478 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=61&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (7 live) #5d3 GZIP Token () Oct 11, 2025 17:05:40.780 [22781425703736] Debug — Completed: [127.0.0.1:60478] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=61&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (7 live) #5d3 GZIP 0ms 195 bytes (pipelined: 5) Oct 11, 2025 17:05:40.781 [22781388442424] Debug — Request: [127.0.0.1:60478 (Loopback)] GET /library/changestamp (7 live) #5d6 GZIP Token () Oct 11, 2025 17:05:40.781 [22781427813176] Debug — Completed: [127.0.0.1:60478] 200 GET /library/changestamp (7 live) #5d6 GZIP 0ms 417 bytes (pipelined: 6) Oct 11, 2025 17:05:40.783 [22781388442424] Debug — Request: [127.0.0.1:60478 (Loopback)] GET /library/changestamp (7 live) #5df GZIP Token () Oct 11, 2025 17:05:40.783 [22781427813176] Debug — Completed: [127.0.0.1:60478] 200 GET /library/changestamp (7 live) #5df GZIP 0ms 417 bytes (pipelined: 7) Oct 11, 2025 17:05:40.785 [22781433453368] Debug — Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 789 is 0 (success) Oct 11, 2025 17:05:40.785 [22781382019896] Debug — Killing job. Oct 11, 2025 17:05:40.785 [22781382019896] Debug — Signalling job ID 789 with 9 Oct 11, 2025 17:05:40.785 [22781382019896] Debug — Media Analyzer: Background analysis completed in 6.1 seconds, removing 1 IDs Oct 11, 2025 17:05:40.785 [22781397056312] Debug — MetadataAgentPostProcessor: adding id 61 to refresh queue (0 items in queue) Oct 11, 2025 17:05:40.785 [22781397056312] Debug — Native Scanner: Executed Media Analysis stage in 6.14 sec. Oct 11, 2025 17:05:40.786 [22781382019896] Debug — Media Analyzer: Performing on-the-fly analysis on 1 item. Oct 11, 2025 17:05:40.786 [22781382019896] Debug — [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/46f74ab-560174306fe167a5978a79dd-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Media Scanner" --analyze --log-file-suffix " Analysis" --item 62 Oct 11, 2025 17:05:40.786 [22781384129336] Debug — Native Scanner: Executed Local Metadata stage in 0.00 sec. Oct 11, 2025 17:05:40.786 [22781382019896] Debug — [JobRunner] Jobs: Starting child process with pid 795 Oct 11, 2025 17:05:40.786 [22781390551864] Debug — Skipping hash check, no size match for 29954766626 bytes. Oct 11, 2025 17:05:40.786 [22781390551864] Debug — No match for hash. Oct 11, 2025 17:05:40.787 [22781390551864] Debug — Added new metadata item (American Gangster) with ID 63 Oct 11, 2025 17:05:40.787 [22781386312504] Debug — [LibraryTimeline] Scanner activity on section 1: 63 added, 0 deleted Oct 11, 2025 17:05:40.787 [22781372742456] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:05:40.787 [22781372742456] Error — MetadataAgentPostProcessor: failed to find agent for identifier tv.plex.agents.movie Oct 11, 2025 17:05:40.788 [22781390551864] Debug — Native Scanner: Executed Add to Database stage in 0.00 sec. Oct 11, 2025 17:05:40.839 [22781397056312] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:05:40.839 [22781397056312] Warning — Scanner [Plex Movie]: unable to find agent match for item file '/data/Movies/American History X (1998).mkv' Oct 11, 2025 17:05:40.839 [22781397056312] Debug — Native Scanner: Executed Agent Match stage in 0.05 sec. Oct 11, 2025 17:05:40.842 [22781388442424] Debug — Request: [127.0.0.1:60488 (Loopback)] GET /library/changestamp (7 live) #5bd GZIP Token () Oct 11, 2025 17:05:40.842 [22781425703736] Debug — Completed: [127.0.0.1:60488] 200 GET /library/changestamp (7 live) #5bd GZIP 0ms 417 bytes (pipelined: 1) Oct 11, 2025 17:05:40.952 [22781388442424] Debug — Request: [127.0.0.1:60488 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=62&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (7 live) #617 GZIP Token () Oct 11, 2025 17:05:40.952 [22781425703736] Debug — Completed: [127.0.0.1:60488] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=62&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (7 live) #617 GZIP 0ms 195 bytes (pipelined: 2) Oct 11, 2025 17:05:40.953 [22781388442424] Debug — Request: [127.0.0.1:60488 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=62&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (7 live) #61c GZIP Token () Oct 11, 2025 17:05:40.953 [22781427813176] Debug — Completed: [127.0.0.1:60488] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=62&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (7 live) #61c GZIP 0ms 195 bytes (pipelined: 3) Oct 11, 2025 17:05:44.668 [22781388442424] Debug — Request: [127.0.0.1:60488 (Loopback)] GET /library/changestamp (7 live) #621 GZIP Token () Oct 11, 2025 17:05:44.668 [22781427813176] Debug — Completed: [127.0.0.1:60488] 200 GET /library/changestamp (7 live) #621 GZIP 0ms 417 bytes (pipelined: 4) Oct 11, 2025 17:05:44.669 [22781388442424] Debug — Request: [127.0.0.1:60488 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=62&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (7 live) #62a GZIP Token () Oct 11, 2025 17:05:44.669 [22781425703736] Debug — Completed: [127.0.0.1:60488] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=62&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (7 live) #62a GZIP 0ms 195 bytes (pipelined: 5) Oct 11, 2025 17:05:44.670 [22781388442424] Debug — Request: [127.0.0.1:60488 (Loopback)] GET /library/changestamp (7 live) #62d GZIP Token () Oct 11, 2025 17:05:44.670 [22781425703736] Debug — Completed: [127.0.0.1:60488] 200 GET /library/changestamp (7 live) #62d GZIP 0ms 417 bytes (pipelined: 6) Oct 11, 2025 17:05:44.672 [22781388442424] Debug — Request: [127.0.0.1:60488 (Loopback)] GET /library/changestamp (7 live) #636 GZIP Token () Oct 11, 2025 17:05:44.672 [22781425703736] Debug — Completed: [127.0.0.1:60488] 200 GET /library/changestamp (7 live) #636 GZIP 0ms 417 bytes (pipelined: 7) Oct 11, 2025 17:05:44.675 [22781433453368] Debug — Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 795 is 0 (success) Oct 11, 2025 17:05:44.675 [22781382019896] Debug — Killing job. Oct 11, 2025 17:05:44.675 [22781382019896] Debug — Signalling job ID 795 with 9 Oct 11, 2025 17:05:44.675 [22781382019896] Debug — Media Analyzer: Background analysis completed in 3.9 seconds, removing 1 IDs Oct 11, 2025 17:05:44.675 [22781394848568] Debug — MetadataAgentPostProcessor: adding id 62 to refresh queue (0 items in queue) Oct 11, 2025 17:05:44.675 [22781394848568] Debug — Native Scanner: Executed Media Analysis stage in 3.89 sec. Oct 11, 2025 17:05:44.675 [22781382019896] Debug — Media Analyzer: Performing on-the-fly analysis on 1 item. Oct 11, 2025 17:05:44.675 [22781382019896] Debug — [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/46f74ab-560174306fe167a5978a79dd-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Media Scanner" --analyze --log-file-suffix " Analysis" --item 63 Oct 11, 2025 17:05:44.675 [22781394848568] Debug — Native Scanner: Executed Local Metadata stage in 0.00 sec. Oct 11, 2025 17:05:44.675 [22781382019896] Debug — [JobRunner] Jobs: Starting child process with pid 801 Oct 11, 2025 17:05:44.675 [22781397056312] Debug — Skipping hash check, no size match for 25557972145 bytes. Oct 11, 2025 17:05:44.675 [22781397056312] Debug — No match for hash. Oct 11, 2025 17:05:44.676 [22781397056312] Debug — Added new metadata item (American History X) with ID 64 Oct 11, 2025 17:05:44.676 [22781386312504] Debug — [LibraryTimeline] Scanner activity on section 1: 64 added, 0 deleted Oct 11, 2025 17:05:44.676 [22781372742456] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:05:44.676 [22781372742456] Error — MetadataAgentPostProcessor: failed to find agent for identifier tv.plex.agents.movie Oct 11, 2025 17:05:44.677 [22781397056312] Debug — Native Scanner: Executed Add to Database stage in 0.00 sec. Oct 11, 2025 17:05:44.736 [22781388442424] Debug — Request: [127.0.0.1:47420 (Loopback)] GET /library/changestamp (7 live) #614 GZIP Token () Oct 11, 2025 17:05:44.736 [22781425703736] Debug — Completed: [127.0.0.1:47420] 200 GET /library/changestamp (7 live) #614 GZIP 0ms 417 bytes (pipelined: 1) Oct 11, 2025 17:05:44.743 [22781388442424] Debug — Request: [127.0.0.1:47420 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=63&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (7 live) #668 GZIP Token () Oct 11, 2025 17:05:44.743 [22781425703736] Debug — Completed: [127.0.0.1:47420] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=63&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (7 live) #668 GZIP 0ms 195 bytes (pipelined: 2) Oct 11, 2025 17:05:44.744 [22781388442424] Debug — Request: [127.0.0.1:47420 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=63&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (7 live) #66c GZIP Token () Oct 11, 2025 17:05:44.744 [22781427813176] Debug — Completed: [127.0.0.1:47420] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=63&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (7 live) #66c GZIP 0ms 195 bytes (pipelined: 3) Oct 11, 2025 17:05:44.923 [22781384129336] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:05:44.923 [22781384129336] Warning — Scanner [Plex Movie]: unable to find agent match for item file '/data/Movies/American Hustle (2013).mkv' Oct 11, 2025 17:05:44.923 [22781384129336] Debug — Native Scanner: Executed Agent Match stage in 0.25 sec. Oct 11, 2025 17:05:46.038 [22781388442424] Debug — Request: [127.0.0.1:47420 (Loopback)] GET /library/changestamp (7 live) #672 GZIP Token () Oct 11, 2025 17:05:46.038 [22781425703736] Debug — Completed: [127.0.0.1:47420] 200 GET /library/changestamp (7 live) #672 GZIP 0ms 417 bytes (pipelined: 4) Oct 11, 2025 17:05:46.039 [22781388442424] Debug — Request: [127.0.0.1:47420 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=63&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (7 live) #681 GZIP Token () Oct 11, 2025 17:05:46.039 [22781425703736] Debug — Completed: [127.0.0.1:47420] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=63&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (7 live) #681 GZIP 0ms 195 bytes (pipelined: 5) Oct 11, 2025 17:05:46.040 [22781388442424] Debug — Request: [127.0.0.1:47420 (Loopback)] GET /library/changestamp (7 live) #686 GZIP Token () Oct 11, 2025 17:05:46.041 [22781427813176] Debug — Completed: [127.0.0.1:47420] 200 GET /library/changestamp (7 live) #686 GZIP 0ms 417 bytes (pipelined: 6) Oct 11, 2025 17:05:46.042 [22781388442424] Debug — Request: [127.0.0.1:47420 (Loopback)] GET /library/changestamp (7 live) #68d GZIP Token () Oct 11, 2025 17:05:46.042 [22781427813176] Debug — Completed: [127.0.0.1:47420] 200 GET /library/changestamp (7 live) #68d GZIP 0ms 417 bytes (pipelined: 7) Oct 11, 2025 17:05:46.045 [22781433453368] Debug — Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 801 is 0 (success) Oct 11, 2025 17:05:46.045 [22781382019896] Debug — Killing job. Oct 11, 2025 17:05:46.045 [22781382019896] Debug — Signalling job ID 801 with 9 Oct 11, 2025 17:05:46.045 [22781382019896] Debug — Media Analyzer: Background analysis completed in 1.4 seconds, removing 1 IDs Oct 11, 2025 17:05:46.045 [22781390551864] Debug — MetadataAgentPostProcessor: adding id 63 to refresh queue (0 items in queue) Oct 11, 2025 17:05:46.045 [22781390551864] Debug — Native Scanner: Executed Media Analysis stage in 1.37 sec. Oct 11, 2025 17:05:46.045 [22781382019896] Debug — Media Analyzer: Performing on-the-fly analysis on 1 item. Oct 11, 2025 17:05:46.045 [22781382019896] Debug — [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/46f74ab-560174306fe167a5978a79dd-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Media Scanner" --analyze --log-file-suffix " Analysis" --item 64 Oct 11, 2025 17:05:46.045 [22781382019896] Debug — [JobRunner] Jobs: Starting child process with pid 807 Oct 11, 2025 17:05:46.045 [22781394848568] Debug — Native Scanner: Executed Local Metadata stage in 0.00 sec. Oct 11, 2025 17:05:46.046 [22781384129336] Debug — Skipping hash check, no size match for 28797380956 bytes. Oct 11, 2025 17:05:46.046 [22781384129336] Debug — No match for hash. Oct 11, 2025 17:05:46.047 [22781372742456] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:05:46.047 [22781372742456] Error — MetadataAgentPostProcessor: failed to find agent for identifier tv.plex.agents.movie Oct 11, 2025 17:05:46.047 [22781384129336] Debug — Added new metadata item (American Hustle) with ID 65 Oct 11, 2025 17:05:46.047 [22781386312504] Debug — [LibraryTimeline] Scanner activity on section 1: 65 added, 0 deleted Oct 11, 2025 17:05:46.048 [22781384129336] Debug — Native Scanner: Executed Add to Database stage in 0.00 sec. Oct 11, 2025 17:05:46.074 [22781390551864] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:05:46.074 [22781390551864] Warning — Scanner [Plex Movie]: unable to find agent match for item file '/data/Movies/American Made (2017) - UHD.mkv' Oct 11, 2025 17:05:46.074 [22781390551864] Debug — Native Scanner: Executed Agent Match stage in 0.03 sec. Oct 11, 2025 17:05:46.108 [22781388442424] Debug — Request: [127.0.0.1:47426 (Loopback)] GET /library/changestamp (7 live) #665 GZIP Token () Oct 11, 2025 17:05:46.109 [22781425703736] Debug — Completed: [127.0.0.1:47426] 200 GET /library/changestamp (7 live) #665 GZIP 0ms 417 bytes (pipelined: 1) Oct 11, 2025 17:05:46.300 [22781388442424] Debug — Request: [127.0.0.1:47426 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=64&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (7 live) #6c5 GZIP Token () Oct 11, 2025 17:05:46.300 [22781425703736] Debug — Completed: [127.0.0.1:47426] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=64&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (7 live) #6c5 GZIP 0ms 195 bytes (pipelined: 2) Oct 11, 2025 17:05:46.301 [22781388442424] Debug — Request: [127.0.0.1:47426 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=64&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (7 live) #6c9 GZIP Token () Oct 11, 2025 17:05:46.301 [22781425703736] Debug — Completed: [127.0.0.1:47426] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=64&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (7 live) #6c9 GZIP 0ms 195 bytes (pipelined: 3) Oct 11, 2025 17:05:47.734 [22781388442424] Debug — Request: [127.0.0.1:47426 (Loopback)] GET /library/changestamp (7 live) #6cf GZIP Token () Oct 11, 2025 17:05:47.735 [22781425703736] Debug — Completed: [127.0.0.1:47426] 200 GET /library/changestamp (7 live) #6cf GZIP 0ms 417 bytes (pipelined: 4) Oct 11, 2025 17:05:47.735 [22781388442424] Debug — Request: [127.0.0.1:47426 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=64&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (7 live) #6d8 GZIP Token () Oct 11, 2025 17:05:47.736 [22781425703736] Debug — Completed: [127.0.0.1:47426] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=64&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (7 live) #6d8 GZIP 0ms 195 bytes (pipelined: 5) Oct 11, 2025 17:05:47.737 [22781388442424] Debug — Request: [127.0.0.1:47426 (Loopback)] GET /library/changestamp (7 live) #6dd GZIP Token () Oct 11, 2025 17:05:47.737 [22781425703736] Debug — Completed: [127.0.0.1:47426] 200 GET /library/changestamp (7 live) #6dd GZIP 0ms 417 bytes (pipelined: 6) Oct 11, 2025 17:05:47.738 [22781388442424] Debug — Request: [127.0.0.1:47426 (Loopback)] GET /library/changestamp (7 live) #6e4 GZIP Token () Oct 11, 2025 17:05:47.738 [22781427813176] Debug — Completed: [127.0.0.1:47426] 200 GET /library/changestamp (7 live) #6e4 GZIP 0ms 417 bytes (pipelined: 7) Oct 11, 2025 17:05:47.741 [22781433453368] Debug — Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 807 is 0 (success) Oct 11, 2025 17:05:47.741 [22781382019896] Debug — Killing job. Oct 11, 2025 17:05:47.741 [22781382019896] Debug — Signalling job ID 807 with 9 Oct 11, 2025 17:05:47.741 [22781382019896] Debug — Media Analyzer: Background analysis completed in 1.7 seconds, removing 1 IDs Oct 11, 2025 17:05:47.741 [22781397056312] Debug — MetadataAgentPostProcessor: adding id 64 to refresh queue (0 items in queue) Oct 11, 2025 17:05:47.741 [22781397056312] Debug — Native Scanner: Executed Media Analysis stage in 1.70 sec. Oct 11, 2025 17:05:47.741 [22781382019896] Debug — Media Analyzer: Performing on-the-fly analysis on 1 item. Oct 11, 2025 17:05:47.741 [22781382019896] Debug — [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/46f74ab-560174306fe167a5978a79dd-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Media Scanner" --analyze --log-file-suffix " Analysis" --item 65 Oct 11, 2025 17:05:47.741 [22781382019896] Debug — [JobRunner] Jobs: Starting child process with pid 813 Oct 11, 2025 17:05:47.741 [22781394848568] Debug — Native Scanner: Executed Local Metadata stage in 0.00 sec. Oct 11, 2025 17:05:47.742 [22781397056312] Debug — Skipping hash check, no size match for 59958518740 bytes. Oct 11, 2025 17:05:47.742 [22781397056312] Debug — No match for hash. Oct 11, 2025 17:05:47.743 [22781397056312] Debug — Added new metadata item (American Made) with ID 66 Oct 11, 2025 17:05:47.743 [22781386312504] Debug — [LibraryTimeline] Scanner activity on section 1: 66 added, 0 deleted Oct 11, 2025 17:05:47.743 [22781372742456] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:05:47.743 [22781372742456] Error — MetadataAgentPostProcessor: failed to find agent for identifier tv.plex.agents.movie Oct 11, 2025 17:05:47.744 [22781397056312] Debug — Native Scanner: Executed Add to Database stage in 0.00 sec. Oct 11, 2025 17:05:47.761 [22781390551864] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:05:47.761 [22781390551864] Warning — Scanner [Plex Movie]: unable to find agent match for item file '/data/Movies/American Made (2017).mkv' Oct 11, 2025 17:05:47.761 [22781390551864] Debug — Native Scanner: Executed Agent Match stage in 0.02 sec. Oct 11, 2025 17:05:47.803 [22781388442424] Debug — Request: [127.0.0.1:47442 (Loopback)] GET /library/changestamp (7 live) #6c2 GZIP Token () Oct 11, 2025 17:05:47.804 [22781425703736] Debug — Completed: [127.0.0.1:47442] 200 GET /library/changestamp (7 live) #6c2 GZIP 0ms 417 bytes (pipelined: 1) Oct 11, 2025 17:05:47.997 [22781388442424] Debug — Request: [127.0.0.1:47442 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=65&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (7 live) #71c GZIP Token () Oct 11, 2025 17:05:47.997 [22781427813176] Debug — Completed: [127.0.0.1:47442] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=65&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (7 live) #71c GZIP 0ms 195 bytes (pipelined: 2) Oct 11, 2025 17:05:47.998 [22781388442424] Debug — Request: [127.0.0.1:47442 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=65&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (7 live) #722 GZIP Token () Oct 11, 2025 17:05:47.998 [22781427813176] Debug — Completed: [127.0.0.1:47442] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=65&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (7 live) #722 GZIP 0ms 195 bytes (pipelined: 3) Oct 11, 2025 17:05:49.603 [22781388442424] Debug — Request: [127.0.0.1:47442 (Loopback)] GET /library/changestamp (4 live) #726 GZIP Token () Oct 11, 2025 17:05:49.603 [22781427813176] Debug — Completed: [127.0.0.1:47442] 200 GET /library/changestamp (4 live) #726 GZIP 0ms 417 bytes (pipelined: 4) Oct 11, 2025 17:05:49.604 [22781388442424] Debug — Request: [127.0.0.1:47442 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=65&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #732 GZIP Token () Oct 11, 2025 17:05:49.604 [22781425703736] Debug — Completed: [127.0.0.1:47442] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=65&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #732 GZIP 0ms 195 bytes (pipelined: 5) Oct 11, 2025 17:05:49.605 [22781388442424] Debug — Request: [127.0.0.1:47442 (Loopback)] GET /library/changestamp (4 live) #735 GZIP Token () Oct 11, 2025 17:05:49.605 [22781427813176] Debug — Completed: [127.0.0.1:47442] 200 GET /library/changestamp (4 live) #735 GZIP 0ms 417 bytes (pipelined: 6) Oct 11, 2025 17:05:49.606 [22781388442424] Debug — Request: [127.0.0.1:47442 (Loopback)] GET /library/changestamp (4 live) #73e GZIP Token () Oct 11, 2025 17:05:49.607 [22781425703736] Debug — Completed: [127.0.0.1:47442] 200 GET /library/changestamp (4 live) #73e GZIP 0ms 417 bytes (pipelined: 7) Oct 11, 2025 17:05:49.609 [22781433453368] Debug — Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 813 is 0 (success) Oct 11, 2025 17:05:49.609 [22781382019896] Debug — Killing job. Oct 11, 2025 17:05:49.609 [22781382019896] Debug — Signalling job ID 813 with 9 Oct 11, 2025 17:05:49.609 [22781382019896] Debug — Media Analyzer: Background analysis completed in 1.9 seconds, removing 1 IDs Oct 11, 2025 17:05:49.609 [22781384129336] Debug — MetadataAgentPostProcessor: adding id 65 to refresh queue (0 items in queue) Oct 11, 2025 17:05:49.609 [22781384129336] Debug — Native Scanner: Executed Media Analysis stage in 1.87 sec. Oct 11, 2025 17:05:49.609 [22781382019896] Debug — Media Analyzer: Performing on-the-fly analysis on 1 item. Oct 11, 2025 17:05:49.609 [22781382019896] Debug — [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/46f74ab-560174306fe167a5978a79dd-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Media Scanner" --analyze --log-file-suffix " Analysis" --item 66 Oct 11, 2025 17:05:49.609 [22781384129336] Debug — Native Scanner: Executed Local Metadata stage in 0.00 sec. Oct 11, 2025 17:05:49.609 [22781382019896] Debug — [JobRunner] Jobs: Starting child process with pid 819 Oct 11, 2025 17:05:49.610 [22781390551864] Debug — Skipping hash check, no size match for 32564914388 bytes. Oct 11, 2025 17:05:49.610 [22781390551864] Debug — No match for hash. Oct 11, 2025 17:05:49.611 [22781372742456] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:05:49.611 [22781372742456] Error — MetadataAgentPostProcessor: failed to find agent for identifier tv.plex.agents.movie Oct 11, 2025 17:05:49.611 [22781390551864] Debug — Added new metadata item (American Made) with ID 67 Oct 11, 2025 17:05:49.611 [22781386312504] Debug — [LibraryTimeline] Scanner activity on section 1: 67 added, 0 deleted Oct 11, 2025 17:05:49.643 [22781394848568] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:05:49.643 [22781394848568] Warning — Scanner [Plex Movie]: unable to find agent match for item file '/data/Movies/American Psycho (2000) - (Unrated) UHD.mkv' Oct 11, 2025 17:05:49.643 [22781394848568] Debug — Native Scanner: Executed Agent Match stage in 0.03 sec. Oct 11, 2025 17:05:49.646 [22781390551864] Debug — Native Scanner: Executed Add to Database stage in 0.04 sec. Oct 11, 2025 17:05:49.670 [22781388442424] Debug — Request: [127.0.0.1:47454 (Loopback)] GET /library/changestamp (4 live) #719 GZIP Token () Oct 11, 2025 17:05:49.670 [22781425703736] Debug — Completed: [127.0.0.1:47454] 200 GET /library/changestamp (4 live) #719 GZIP 0ms 417 bytes (pipelined: 1) Oct 11, 2025 17:05:49.807 [22781388442424] Debug — Request: [127.0.0.1:47454 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=66&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #776 GZIP Token () Oct 11, 2025 17:05:49.807 [22781427813176] Debug — Completed: [127.0.0.1:47454] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=66&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #776 GZIP 0ms 195 bytes (pipelined: 2) Oct 11, 2025 17:05:49.808 [22781388442424] Debug — Request: [127.0.0.1:47454 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=66&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #779 GZIP Token () Oct 11, 2025 17:05:49.808 [22781425703736] Debug — Completed: [127.0.0.1:47454] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=66&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #779 GZIP 0ms 195 bytes (pipelined: 3) Oct 11, 2025 17:05:52.112 [22781388442424] Debug — Request: [127.0.0.1:47454 (Loopback)] GET /library/changestamp (4 live) #782 GZIP Token () Oct 11, 2025 17:05:52.113 [22781425703736] Debug — Completed: [127.0.0.1:47454] 200 GET /library/changestamp (4 live) #782 GZIP 0ms 417 bytes (pipelined: 4) Oct 11, 2025 17:05:52.114 [22781388442424] Debug — Request: [127.0.0.1:47454 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=66&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #789 GZIP Token () Oct 11, 2025 17:05:52.114 [22781425703736] Debug — Completed: [127.0.0.1:47454] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=66&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #789 GZIP 0ms 195 bytes (pipelined: 5) Oct 11, 2025 17:05:52.115 [22781388442424] Debug — Request: [127.0.0.1:47454 (Loopback)] GET /library/changestamp (4 live) #78c GZIP Token () Oct 11, 2025 17:05:52.115 [22781425703736] Debug — Completed: [127.0.0.1:47454] 200 GET /library/changestamp (4 live) #78c GZIP 0ms 417 bytes (pipelined: 6) Oct 11, 2025 17:05:52.116 [22781388442424] Debug — Request: [127.0.0.1:47454 (Loopback)] GET /library/changestamp (4 live) #795 GZIP Token () Oct 11, 2025 17:05:52.117 [22781427813176] Debug — Completed: [127.0.0.1:47454] 200 GET /library/changestamp (4 live) #795 GZIP 0ms 417 bytes (pipelined: 7) Oct 11, 2025 17:05:52.119 [22781433453368] Debug — Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 819 is 0 (success) Oct 11, 2025 17:05:52.119 [22781382019896] Debug — Killing job. Oct 11, 2025 17:05:52.119 [22781382019896] Debug — Signalling job ID 819 with 9 Oct 11, 2025 17:05:52.119 [22781382019896] Debug — Media Analyzer: Background analysis completed in 2.5 seconds, removing 1 IDs Oct 11, 2025 17:05:52.119 [22781397056312] Debug — MetadataAgentPostProcessor: adding id 66 to refresh queue (0 items in queue) Oct 11, 2025 17:05:52.119 [22781397056312] Debug — Native Scanner: Executed Media Analysis stage in 2.51 sec. Oct 11, 2025 17:05:52.119 [22781382019896] Debug — Media Analyzer: Performing on-the-fly analysis on 1 item. Oct 11, 2025 17:05:52.119 [22781382019896] Debug — [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/46f74ab-560174306fe167a5978a79dd-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Media Scanner" --analyze --log-file-suffix " Analysis" --item 67 Oct 11, 2025 17:05:52.119 [22781384129336] Debug — Native Scanner: Executed Local Metadata stage in 0.00 sec. Oct 11, 2025 17:05:52.120 [22781382019896] Debug — [JobRunner] Jobs: Starting child process with pid 825 Oct 11, 2025 17:05:52.120 [22781394848568] Debug — Skipping hash check, no size match for 60822936810 bytes. Oct 11, 2025 17:05:52.120 [22781394848568] Debug — No match for hash. Oct 11, 2025 17:05:52.121 [22781394848568] Debug — Added new metadata item (American Psycho) with ID 68 Oct 11, 2025 17:05:52.121 [22781386312504] Debug — [LibraryTimeline] Scanner activity on section 1: 68 added, 0 deleted Oct 11, 2025 17:05:52.121 [22781372742456] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:05:52.121 [22781372742456] Error — MetadataAgentPostProcessor: failed to find agent for identifier tv.plex.agents.movie Oct 11, 2025 17:05:52.122 [22781394848568] Debug — Native Scanner: Executed Add to Database stage in 0.00 sec. Oct 11, 2025 17:05:52.149 [22781397056312] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:05:52.149 [22781397056312] Warning — Scanner [Plex Movie]: unable to find agent match for item file '/data/Movies/American Psycho (2000) - UHD (Unrated).mkv' Oct 11, 2025 17:05:52.149 [22781397056312] Debug — Native Scanner: Executed Agent Match stage in 0.03 sec. Oct 11, 2025 17:05:52.175 [22781388442424] Debug — Request: [127.0.0.1:47464 (Loopback)] GET /library/changestamp (4 live) #773 GZIP Token () Oct 11, 2025 17:05:52.175 [22781425703736] Debug — Completed: [127.0.0.1:47464] 200 GET /library/changestamp (4 live) #773 GZIP 0ms 417 bytes (pipelined: 1) Oct 11, 2025 17:05:52.304 [22781388442424] Debug — Request: [127.0.0.1:47464 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=67&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #7cd GZIP Token () Oct 11, 2025 17:05:52.304 [22781427813176] Debug — Completed: [127.0.0.1:47464] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=67&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #7cd GZIP 0ms 195 bytes (pipelined: 2) Oct 11, 2025 17:05:52.304 [22781388442424] Debug — Request: [127.0.0.1:47464 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=67&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #7d3 GZIP Token () Oct 11, 2025 17:05:52.304 [22781425703736] Debug — Completed: [127.0.0.1:47464] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=67&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #7d3 GZIP 0ms 195 bytes (pipelined: 3) Oct 11, 2025 17:05:52.949 [22781388442424] Debug — Request: [127.0.0.1:47464 (Loopback)] GET /library/changestamp (4 live) #7d9 GZIP Token () Oct 11, 2025 17:05:52.949 [22781425703736] Debug — Completed: [127.0.0.1:47464] 200 GET /library/changestamp (4 live) #7d9 GZIP 0ms 417 bytes (pipelined: 4) Oct 11, 2025 17:05:52.950 [22781388442424] Debug — Request: [127.0.0.1:47464 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=67&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #7e0 GZIP Token () Oct 11, 2025 17:05:52.951 [22781425703736] Debug — Completed: [127.0.0.1:47464] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=67&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #7e0 GZIP 0ms 195 bytes (pipelined: 5) Oct 11, 2025 17:05:52.951 [22781388442424] Debug — Request: [127.0.0.1:47464 (Loopback)] GET /library/changestamp (4 live) #7e5 GZIP Token () Oct 11, 2025 17:05:52.952 [22781425703736] Debug — Completed: [127.0.0.1:47464] 200 GET /library/changestamp (4 live) #7e5 GZIP 0ms 417 bytes (pipelined: 6) Oct 11, 2025 17:05:52.953 [22781388442424] Debug — Request: [127.0.0.1:47464 (Loopback)] GET /library/changestamp (4 live) #7ec GZIP Token () Oct 11, 2025 17:05:52.953 [22781427813176] Debug — Completed: [127.0.0.1:47464] 200 GET /library/changestamp (4 live) #7ec GZIP 0ms 417 bytes (pipelined: 7) Oct 11, 2025 17:05:52.956 [22781433453368] Debug — Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 825 is 0 (success) Oct 11, 2025 17:05:52.956 [22781382019896] Debug — Killing job. Oct 11, 2025 17:05:52.956 [22781382019896] Debug — Signalling job ID 825 with 9 Oct 11, 2025 17:05:52.956 [22781382019896] Debug — Media Analyzer: Background analysis completed in 0.8 seconds, removing 1 IDs Oct 11, 2025 17:05:52.956 [22781390551864] Debug — MetadataAgentPostProcessor: adding id 67 to refresh queue (0 items in queue) Oct 11, 2025 17:05:52.956 [22781390551864] Debug — Native Scanner: Executed Media Analysis stage in 0.84 sec. Oct 11, 2025 17:05:52.956 [22781382019896] Debug — Media Analyzer: Performing on-the-fly analysis on 1 item. Oct 11, 2025 17:05:52.956 [22781390551864] Debug — Native Scanner: Executed Local Metadata stage in 0.00 sec. Oct 11, 2025 17:05:52.956 [22781382019896] Debug — [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/46f74ab-560174306fe167a5978a79dd-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Media Scanner" --analyze --log-file-suffix " Analysis" --item 68 Oct 11, 2025 17:05:52.956 [22781382019896] Debug — [JobRunner] Jobs: Starting child process with pid 831 Oct 11, 2025 17:05:52.957 [22781397056312] Debug — Skipping hash check, no size match for 60822479061 bytes. Oct 11, 2025 17:05:52.957 [22781397056312] Debug — No match for hash. Oct 11, 2025 17:05:52.958 [22781372742456] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:05:52.958 [22781372742456] Error — MetadataAgentPostProcessor: failed to find agent for identifier tv.plex.agents.movie Oct 11, 2025 17:05:52.958 [22781397056312] Debug — Added new metadata item (American Psycho) with ID 69 Oct 11, 2025 17:05:52.958 [22781386312504] Debug — [LibraryTimeline] Scanner activity on section 1: 69 added, 0 deleted Oct 11, 2025 17:05:52.959 [22781397056312] Debug — Native Scanner: Executed Add to Database stage in 0.00 sec. Oct 11, 2025 17:05:52.991 [22781384129336] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:05:52.991 [22781384129336] Warning — Scanner [Plex Movie]: unable to find agent match for item file '/data/Movies/American Psycho (2000).mkv' Oct 11, 2025 17:05:52.991 [22781384129336] Debug — Native Scanner: Executed Agent Match stage in 0.03 sec. Oct 11, 2025 17:05:53.011 [22781388442424] Debug — Request: [127.0.0.1:50966 (Loopback)] GET /library/changestamp (4 live) #7ca GZIP Token () Oct 11, 2025 17:05:53.012 [22781427813176] Debug — Completed: [127.0.0.1:50966] 200 GET /library/changestamp (4 live) #7ca GZIP 0ms 417 bytes (pipelined: 1) Oct 11, 2025 17:05:53.142 [22781388442424] Debug — Request: [127.0.0.1:50966 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=68&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #824 GZIP Token () Oct 11, 2025 17:05:53.142 [22781427813176] Debug — Completed: [127.0.0.1:50966] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=68&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #824 GZIP 0ms 195 bytes (pipelined: 2) Oct 11, 2025 17:05:53.142 [22781388442424] Debug — Request: [127.0.0.1:50966 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=68&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #828 GZIP Token () Oct 11, 2025 17:05:53.142 [22781425703736] Debug — Completed: [127.0.0.1:50966] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=68&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #828 GZIP 0ms 195 bytes (pipelined: 3) Oct 11, 2025 17:05:58.699 [22781388442424] Debug — Request: [127.0.0.1:50966 (Loopback)] GET /library/changestamp (4 live) #82f GZIP Token () Oct 11, 2025 17:05:58.699 [22781427813176] Debug — Completed: [127.0.0.1:50966] 200 GET /library/changestamp (4 live) #82f GZIP 0ms 417 bytes (pipelined: 4) Oct 11, 2025 17:05:58.700 [22781388442424] Debug — Request: [127.0.0.1:50966 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=68&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #837 GZIP Token () Oct 11, 2025 17:05:58.700 [22781427813176] Debug — Completed: [127.0.0.1:50966] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=68&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #837 GZIP 0ms 195 bytes (pipelined: 5) Oct 11, 2025 17:05:58.701 [22781388442424] Debug — Request: [127.0.0.1:50966 (Loopback)] GET /library/changestamp (4 live) #83c GZIP Token () Oct 11, 2025 17:05:58.701 [22781425703736] Debug — Completed: [127.0.0.1:50966] 200 GET /library/changestamp (4 live) #83c GZIP 0ms 417 bytes (pipelined: 6) Oct 11, 2025 17:05:58.702 [22781388442424] Debug — Request: [127.0.0.1:50966 (Loopback)] GET /library/changestamp (4 live) #843 GZIP Token () Oct 11, 2025 17:05:58.702 [22781425703736] Debug — Completed: [127.0.0.1:50966] 200 GET /library/changestamp (4 live) #843 GZIP 0ms 417 bytes (pipelined: 7) Oct 11, 2025 17:05:58.705 [22781433453368] Debug — Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 831 is 0 (success) Oct 11, 2025 17:05:58.705 [22781382019896] Debug — Killing job. Oct 11, 2025 17:05:58.705 [22781382019896] Debug — Signalling job ID 831 with 9 Oct 11, 2025 17:05:58.705 [22781382019896] Debug — Media Analyzer: Background analysis completed in 5.7 seconds, removing 1 IDs Oct 11, 2025 17:05:58.705 [22781394848568] Debug — MetadataAgentPostProcessor: adding id 68 to refresh queue (0 items in queue) Oct 11, 2025 17:05:58.705 [22781394848568] Debug — Native Scanner: Executed Media Analysis stage in 5.75 sec. Oct 11, 2025 17:05:58.705 [22781382019896] Debug — Media Analyzer: Performing on-the-fly analysis on 1 item. Oct 11, 2025 17:05:58.705 [22781382019896] Debug — [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/46f74ab-560174306fe167a5978a79dd-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Media Scanner" --analyze --log-file-suffix " Analysis" --item 69 Oct 11, 2025 17:05:58.705 [22781394848568] Debug — Native Scanner: Executed Local Metadata stage in 0.00 sec. Oct 11, 2025 17:05:58.705 [22781382019896] Debug — [JobRunner] Jobs: Starting child process with pid 837 Oct 11, 2025 17:05:58.705 [22781384129336] Debug — Skipping hash check, no size match for 17118823866 bytes. Oct 11, 2025 17:05:58.705 [22781384129336] Debug — No match for hash. Oct 11, 2025 17:05:58.706 [22781384129336] Debug — Added new metadata item (American Psycho) with ID 70 Oct 11, 2025 17:05:58.706 [22781386312504] Debug — [LibraryTimeline] Scanner activity on section 1: 70 added, 0 deleted Oct 11, 2025 17:05:58.706 [22781372742456] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:05:58.706 [22781372742456] Error — MetadataAgentPostProcessor: failed to find agent for identifier tv.plex.agents.movie Oct 11, 2025 17:05:58.707 [22781384129336] Debug — Native Scanner: Executed Add to Database stage in 0.00 sec. Oct 11, 2025 17:05:58.743 [22781390551864] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:05:58.743 [22781390551864] Warning — Scanner [Plex Movie]: unable to find agent match for item file '/data/Movies/American Sniper (2014).mkv' Oct 11, 2025 17:05:58.743 [22781390551864] Debug — Native Scanner: Executed Agent Match stage in 0.04 sec. Oct 11, 2025 17:05:58.761 [22781388442424] Debug — Request: [127.0.0.1:50970 (Loopback)] GET /library/changestamp (4 live) #821 GZIP Token () Oct 11, 2025 17:05:58.761 [22781425703736] Debug — Completed: [127.0.0.1:50970] 200 GET /library/changestamp (4 live) #821 GZIP 0ms 417 bytes (pipelined: 1) Oct 11, 2025 17:05:58.875 [22781388442424] Debug — Request: [127.0.0.1:50970 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=69&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #87b GZIP Token () Oct 11, 2025 17:05:58.875 [22781425703736] Debug — Completed: [127.0.0.1:50970] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=69&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #87b GZIP 0ms 195 bytes (pipelined: 2) Oct 11, 2025 17:05:58.875 [22781388442424] Debug — Request: [127.0.0.1:50970 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=69&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #880 GZIP Token () Oct 11, 2025 17:05:58.875 [22781425703736] Debug — Completed: [127.0.0.1:50970] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=69&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #880 GZIP 0ms 195 bytes (pipelined: 3) Oct 11, 2025 17:06:04.396 [22781388442424] Debug — Request: [127.0.0.1:50970 (Loopback)] GET /library/changestamp (4 live) #886 GZIP Token () Oct 11, 2025 17:06:04.397 [22781425703736] Debug — Completed: [127.0.0.1:50970] 200 GET /library/changestamp (4 live) #886 GZIP 0ms 417 bytes (pipelined: 4) Oct 11, 2025 17:06:04.398 [22781388442424] Debug — Request: [127.0.0.1:50970 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=69&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #88e GZIP Token () Oct 11, 2025 17:06:04.398 [22781427813176] Debug — Completed: [127.0.0.1:50970] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=69&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #88e GZIP 0ms 195 bytes (pipelined: 5) Oct 11, 2025 17:06:04.399 [22781388442424] Debug — Request: [127.0.0.1:50970 (Loopback)] GET /library/changestamp (4 live) #891 GZIP Token () Oct 11, 2025 17:06:04.399 [22781425703736] Debug — Completed: [127.0.0.1:50970] 200 GET /library/changestamp (4 live) #891 GZIP 0ms 417 bytes (pipelined: 6) Oct 11, 2025 17:06:04.400 [22781388442424] Debug — Request: [127.0.0.1:50970 (Loopback)] GET /library/changestamp (4 live) #89a GZIP Token () Oct 11, 2025 17:06:04.400 [22781425703736] Debug — Completed: [127.0.0.1:50970] 200 GET /library/changestamp (4 live) #89a GZIP 0ms 417 bytes (pipelined: 7) Oct 11, 2025 17:06:04.403 [22781433453368] Debug — Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 837 is 0 (success) Oct 11, 2025 17:06:04.403 [22781382019896] Debug — Killing job. Oct 11, 2025 17:06:04.403 [22781382019896] Debug — Signalling job ID 837 with 9 Oct 11, 2025 17:06:04.403 [22781382019896] Debug — Media Analyzer: Background analysis completed in 5.7 seconds, removing 1 IDs Oct 11, 2025 17:06:04.403 [22781397056312] Debug — MetadataAgentPostProcessor: adding id 69 to refresh queue (0 items in queue) Oct 11, 2025 17:06:04.403 [22781397056312] Debug — Native Scanner: Executed Media Analysis stage in 5.70 sec. Oct 11, 2025 17:06:04.403 [22781382019896] Debug — Media Analyzer: Performing on-the-fly analysis on 1 item. Oct 11, 2025 17:06:04.403 [22781382019896] Debug — [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/46f74ab-560174306fe167a5978a79dd-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Media Scanner" --analyze --log-file-suffix " Analysis" --item 70 Oct 11, 2025 17:06:04.403 [22781382019896] Debug — [JobRunner] Jobs: Starting child process with pid 843 Oct 11, 2025 17:06:04.403 [22781397056312] Debug — Native Scanner: Executed Local Metadata stage in 0.00 sec. Oct 11, 2025 17:06:04.404 [22781390551864] Debug — Skipping hash check, no size match for 29346593651 bytes. Oct 11, 2025 17:06:04.404 [22781390551864] Debug — No match for hash. Oct 11, 2025 17:06:04.404 [22781390551864] Debug — Added new metadata item (American Sniper) with ID 71 Oct 11, 2025 17:06:04.404 [22781386312504] Debug — [LibraryTimeline] Scanner activity on section 1: 71 added, 0 deleted Oct 11, 2025 17:06:04.405 [22781372742456] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:06:04.405 [22781372742456] Error — MetadataAgentPostProcessor: failed to find agent for identifier tv.plex.agents.movie Oct 11, 2025 17:06:04.405 [22781390551864] Debug — Native Scanner: Executed Add to Database stage in 0.00 sec. Oct 11, 2025 17:06:04.447 [22781394848568] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:06:04.447 [22781394848568] Warning — Scanner [Plex Movie]: unable to find agent match for item file '/data/Movies/Analyze That (2002).mkv' Oct 11, 2025 17:06:04.447 [22781394848568] Debug — Native Scanner: Executed Agent Match stage in 0.04 sec. Oct 11, 2025 17:06:04.466 [22781388442424] Debug — Request: [127.0.0.1:40534 (Loopback)] GET /library/changestamp (4 live) #878 GZIP Token () Oct 11, 2025 17:06:04.467 [22781425703736] Debug — Completed: [127.0.0.1:40534] 200 GET /library/changestamp (4 live) #878 GZIP 0ms 417 bytes (pipelined: 1) Oct 11, 2025 17:06:04.586 [22781388442424] Debug — Request: [127.0.0.1:40534 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=70&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #8d2 GZIP Token () Oct 11, 2025 17:06:04.586 [22781427813176] Debug — Completed: [127.0.0.1:40534] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=70&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #8d2 GZIP 0ms 195 bytes (pipelined: 2) Oct 11, 2025 17:06:04.586 [22781388442424] Debug — Request: [127.0.0.1:40534 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=70&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #8d7 GZIP Token () Oct 11, 2025 17:06:04.586 [22781427813176] Debug — Completed: [127.0.0.1:40534] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=70&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #8d7 GZIP 0ms 195 bytes (pipelined: 3) Oct 11, 2025 17:06:05.853 [22781388442424] Debug — Request: [127.0.0.1:40534 (Loopback)] GET /library/changestamp (4 live) #8dc GZIP Token () Oct 11, 2025 17:06:05.854 [22781425703736] Debug — Completed: [127.0.0.1:40534] 200 GET /library/changestamp (4 live) #8dc GZIP 0ms 417 bytes (pipelined: 4) Oct 11, 2025 17:06:05.854 [22781388442424] Debug — Request: [127.0.0.1:40534 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=70&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #8e5 GZIP Token () Oct 11, 2025 17:06:05.855 [22781427813176] Debug — Completed: [127.0.0.1:40534] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=70&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #8e5 GZIP 0ms 195 bytes (pipelined: 5) Oct 11, 2025 17:06:05.856 [22781388442424] Debug — Request: [127.0.0.1:40534 (Loopback)] GET /library/changestamp (4 live) #8e9 GZIP Token () Oct 11, 2025 17:06:05.856 [22781427813176] Debug — Completed: [127.0.0.1:40534] 200 GET /library/changestamp (4 live) #8e9 GZIP 0ms 417 bytes (pipelined: 6) Oct 11, 2025 17:06:05.857 [22781388442424] Debug — Request: [127.0.0.1:40534 (Loopback)] GET /library/changestamp (4 live) #8f1 GZIP Token () Oct 11, 2025 17:06:05.857 [22781427813176] Debug — Completed: [127.0.0.1:40534] 200 GET /library/changestamp (4 live) #8f1 GZIP 0ms 417 bytes (pipelined: 7) Oct 11, 2025 17:06:05.859 [22781433453368] Debug — Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 843 is 0 (success) Oct 11, 2025 17:06:05.859 [22781382019896] Debug — Killing job. Oct 11, 2025 17:06:05.859 [22781382019896] Debug — Signalling job ID 843 with 9 Oct 11, 2025 17:06:05.859 [22781382019896] Debug — Media Analyzer: Background analysis completed in 1.5 seconds, removing 1 IDs Oct 11, 2025 17:06:05.859 [22781384129336] Debug — MetadataAgentPostProcessor: adding id 70 to refresh queue (0 items in queue) Oct 11, 2025 17:06:05.859 [22781384129336] Debug — Native Scanner: Executed Media Analysis stage in 1.46 sec. Oct 11, 2025 17:06:05.859 [22781382019896] Debug — Media Analyzer: Performing on-the-fly analysis on 1 item. Oct 11, 2025 17:06:05.859 [22781382019896] Debug — [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/46f74ab-560174306fe167a5978a79dd-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Media Scanner" --analyze --log-file-suffix " Analysis" --item 71 Oct 11, 2025 17:06:05.860 [22781382019896] Debug — [JobRunner] Jobs: Starting child process with pid 849 Oct 11, 2025 17:06:05.860 [22781397056312] Debug — Native Scanner: Executed Local Metadata stage in 0.00 sec. Oct 11, 2025 17:06:05.860 [22781384129336] Debug — Skipping hash check, no size match for 15056300060 bytes. Oct 11, 2025 17:06:05.860 [22781384129336] Debug — No match for hash. Oct 11, 2025 17:06:05.861 [22781372742456] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:06:05.861 [22781372742456] Error — MetadataAgentPostProcessor: failed to find agent for identifier tv.plex.agents.movie Oct 11, 2025 17:06:05.861 [22781384129336] Debug — Added new metadata item (Analyze That) with ID 72 Oct 11, 2025 17:06:05.861 [22781386312504] Debug — [LibraryTimeline] Scanner activity on section 1: 72 added, 0 deleted Oct 11, 2025 17:06:05.885 [22781394848568] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:06:05.885 [22781394848568] Warning — Scanner [Plex Movie]: unable to find agent match for item file '/data/Movies/Analyze This (1999).mkv' Oct 11, 2025 17:06:05.885 [22781394848568] Debug — Native Scanner: Executed Agent Match stage in 0.03 sec. Oct 11, 2025 17:06:05.894 [22781384129336] Debug — Native Scanner: Executed Add to Database stage in 0.03 sec. Oct 11, 2025 17:06:05.914 [22781388442424] Debug — Request: [127.0.0.1:40536 (Loopback)] GET /library/changestamp (4 live) #8cf GZIP Token () Oct 11, 2025 17:06:05.914 [22781427813176] Debug — Completed: [127.0.0.1:40536] 200 GET /library/changestamp (4 live) #8cf GZIP 0ms 417 bytes (pipelined: 1) Oct 11, 2025 17:06:05.931 [22781388442424] Debug — Request: [127.0.0.1:40536 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=71&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #929 GZIP Token () Oct 11, 2025 17:06:05.931 [22781427813176] Debug — Completed: [127.0.0.1:40536] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=71&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #929 GZIP 0ms 195 bytes (pipelined: 2) Oct 11, 2025 17:06:05.932 [22781388442424] Debug — Request: [127.0.0.1:40536 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=71&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #92e GZIP Token () Oct 11, 2025 17:06:05.932 [22781427813176] Debug — Completed: [127.0.0.1:40536] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=71&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #92e GZIP 0ms 195 bytes (pipelined: 3) Oct 11, 2025 17:06:07.370 [22781388442424] Debug — Request: [127.0.0.1:40536 (Loopback)] GET /library/changestamp (4 live) #934 GZIP Token () Oct 11, 2025 17:06:07.371 [22781427813176] Debug — Completed: [127.0.0.1:40536] 200 GET /library/changestamp (4 live) #934 GZIP 0ms 417 bytes (pipelined: 4) Oct 11, 2025 17:06:07.372 [22781388442424] Debug — Request: [127.0.0.1:40536 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=71&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #93c GZIP Token () Oct 11, 2025 17:06:07.372 [22781425703736] Debug — Completed: [127.0.0.1:40536] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=71&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #93c GZIP 0ms 195 bytes (pipelined: 5) Oct 11, 2025 17:06:07.373 [22781388442424] Debug — Request: [127.0.0.1:40536 (Loopback)] GET /library/changestamp (4 live) #93f GZIP Token () Oct 11, 2025 17:06:07.373 [22781427813176] Debug — Completed: [127.0.0.1:40536] 200 GET /library/changestamp (4 live) #93f GZIP 0ms 417 bytes (pipelined: 6) Oct 11, 2025 17:06:07.374 [22781388442424] Debug — Request: [127.0.0.1:40536 (Loopback)] GET /library/changestamp (4 live) #948 GZIP Token () Oct 11, 2025 17:06:07.374 [22781425703736] Debug — Completed: [127.0.0.1:40536] 200 GET /library/changestamp (4 live) #948 GZIP 0ms 417 bytes (pipelined: 7) Oct 11, 2025 17:06:07.376 [22781433453368] Debug — Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 849 is 0 (success) Oct 11, 2025 17:06:07.376 [22781382019896] Debug — Killing job. Oct 11, 2025 17:06:07.376 [22781382019896] Debug — Signalling job ID 849 with 9 Oct 11, 2025 17:06:07.376 [22781382019896] Debug — Media Analyzer: Background analysis completed in 1.5 seconds, removing 1 IDs Oct 11, 2025 17:06:07.376 [22781390551864] Debug — MetadataAgentPostProcessor: adding id 71 to refresh queue (0 items in queue) Oct 11, 2025 17:06:07.376 [22781390551864] Debug — Native Scanner: Executed Media Analysis stage in 1.52 sec. Oct 11, 2025 17:06:07.376 [22781382019896] Debug — Media Analyzer: Performing on-the-fly analysis on 1 item. Oct 11, 2025 17:06:07.376 [22781382019896] Debug — [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/46f74ab-560174306fe167a5978a79dd-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Media Scanner" --analyze --log-file-suffix " Analysis" --item 72 Oct 11, 2025 17:06:07.377 [22781382019896] Debug — [JobRunner] Jobs: Starting child process with pid 855 Oct 11, 2025 17:06:07.377 [22781397056312] Debug — Native Scanner: Executed Local Metadata stage in 0.00 sec. Oct 11, 2025 17:06:07.377 [22781394848568] Debug — Skipping hash check, no size match for 15790748230 bytes. Oct 11, 2025 17:06:07.377 [22781394848568] Debug — No match for hash. Oct 11, 2025 17:06:07.378 [22781394848568] Debug — Added new metadata item (Analyze This) with ID 73 Oct 11, 2025 17:06:07.378 [22781372742456] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:06:07.378 [22781372742456] Error — MetadataAgentPostProcessor: failed to find agent for identifier tv.plex.agents.movie Oct 11, 2025 17:06:07.378 [22781386312504] Debug — [LibraryTimeline] Scanner activity on section 1: 73 added, 0 deleted Oct 11, 2025 17:06:07.379 [22781394848568] Debug — Native Scanner: Executed Add to Database stage in 0.00 sec. Oct 11, 2025 17:06:07.433 [22781388442424] Debug — Request: [127.0.0.1:40550 (Loopback)] GET /library/changestamp (4 live) #926 GZIP Token () Oct 11, 2025 17:06:07.433 [22781427813176] Debug — Completed: [127.0.0.1:40550] 200 GET /library/changestamp (4 live) #926 GZIP 0ms 417 bytes (pipelined: 1) Oct 11, 2025 17:06:07.448 [22781390551864] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:06:07.448 [22781390551864] Warning — Scanner [Plex Movie]: unable to find agent match for item file '/data/Movies/Anchorman The Legend of Ron Burgundy (2004).mkv' Oct 11, 2025 17:06:07.448 [22781390551864] Debug — Native Scanner: Executed Agent Match stage in 0.07 sec. Oct 11, 2025 17:06:07.553 [22781388442424] Debug — Request: [127.0.0.1:40550 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=72&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #97a GZIP Token () Oct 11, 2025 17:06:07.553 [22781425703736] Debug — Completed: [127.0.0.1:40550] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=72&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #97a GZIP 0ms 195 bytes (pipelined: 2) Oct 11, 2025 17:06:07.554 [22781388442424] Debug — Request: [127.0.0.1:40550 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=72&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #984 GZIP Token () Oct 11, 2025 17:06:07.554 [22781425703736] Debug — Completed: [127.0.0.1:40550] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=72&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #984 GZIP 0ms 195 bytes (pipelined: 3) Oct 11, 2025 17:06:08.455 [22781388442424] Debug — Request: [127.0.0.1:40550 (Loopback)] GET /library/changestamp (4 live) #98a GZIP Token () Oct 11, 2025 17:06:08.456 [22781425703736] Debug — Completed: [127.0.0.1:40550] 200 GET /library/changestamp (4 live) #98a GZIP 0ms 417 bytes (pipelined: 4) Oct 11, 2025 17:06:08.457 [22781388442424] Debug — Request: [127.0.0.1:40550 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=72&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #993 GZIP Token () Oct 11, 2025 17:06:08.457 [22781425703736] Debug — Completed: [127.0.0.1:40550] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=72&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #993 GZIP 0ms 195 bytes (pipelined: 5) Oct 11, 2025 17:06:08.458 [22781388442424] Debug — Request: [127.0.0.1:40550 (Loopback)] GET /library/changestamp (4 live) #998 GZIP Token () Oct 11, 2025 17:06:08.458 [22781427813176] Debug — Completed: [127.0.0.1:40550] 200 GET /library/changestamp (4 live) #998 GZIP 0ms 417 bytes (pipelined: 6) Oct 11, 2025 17:06:08.460 [22781388442424] Debug — Request: [127.0.0.1:40550 (Loopback)] GET /library/changestamp (4 live) #99f GZIP Token () Oct 11, 2025 17:06:08.460 [22781425703736] Debug — Completed: [127.0.0.1:40550] 200 GET /library/changestamp (4 live) #99f GZIP 0ms 417 bytes (pipelined: 7) Oct 11, 2025 17:06:08.462 [22781433453368] Debug — Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 855 is 0 (success) Oct 11, 2025 17:06:08.462 [22781382019896] Debug — Killing job. Oct 11, 2025 17:06:08.462 [22781382019896] Debug — Signalling job ID 855 with 9 Oct 11, 2025 17:06:08.462 [22781382019896] Debug — Media Analyzer: Background analysis completed in 1.1 seconds, removing 1 IDs Oct 11, 2025 17:06:08.462 [22781384129336] Debug — MetadataAgentPostProcessor: adding id 72 to refresh queue (0 items in queue) Oct 11, 2025 17:06:08.462 [22781384129336] Debug — Native Scanner: Executed Media Analysis stage in 1.09 sec. Oct 11, 2025 17:06:08.462 [22781382019896] Debug — Media Analyzer: Performing on-the-fly analysis on 1 item. Oct 11, 2025 17:06:08.462 [22781382019896] Debug — [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/46f74ab-560174306fe167a5978a79dd-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Media Scanner" --analyze --log-file-suffix " Analysis" --item 73 Oct 11, 2025 17:06:08.463 [22781382019896] Debug — [JobRunner] Jobs: Starting child process with pid 861 Oct 11, 2025 17:06:08.463 [22781397056312] Debug — Native Scanner: Executed Local Metadata stage in 0.00 sec. Oct 11, 2025 17:06:08.463 [22781394848568] Debug — Skipping hash check, no size match for 28881909899 bytes. Oct 11, 2025 17:06:08.463 [22781394848568] Debug — No match for hash. Oct 11, 2025 17:06:08.464 [22781394848568] Debug — Added new metadata item (Anchorman the Legend of Ron Burgundy) with ID 74 Oct 11, 2025 17:06:08.464 [22781386312504] Debug — [LibraryTimeline] Scanner activity on section 1: 74 added, 0 deleted Oct 11, 2025 17:06:08.464 [22781372742456] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:06:08.464 [22781372742456] Error — MetadataAgentPostProcessor: failed to find agent for identifier tv.plex.agents.movie Oct 11, 2025 17:06:08.465 [22781394848568] Debug — Native Scanner: Executed Add to Database stage in 0.00 sec. Oct 11, 2025 17:06:08.514 [22781390551864] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:06:08.514 [22781390551864] Warning — Scanner [Plex Movie]: unable to find agent match for item file '/data/Movies/Angels & Demons (2009) - Extended Edition.mkv' Oct 11, 2025 17:06:08.515 [22781390551864] Debug — Native Scanner: Executed Agent Match stage in 0.05 sec. Oct 11, 2025 17:06:08.521 [22781388442424] Debug — Request: [127.0.0.1:40558 (Loopback)] GET /library/changestamp (4 live) #977 GZIP Token () Oct 11, 2025 17:06:08.521 [22781425703736] Debug — Completed: [127.0.0.1:40558] 200 GET /library/changestamp (4 live) #977 GZIP 0ms 417 bytes (pipelined: 1) Oct 11, 2025 17:06:08.650 [22781388442424] Debug — Request: [127.0.0.1:40558 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=73&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #9d7 GZIP Token () Oct 11, 2025 17:06:08.650 [22781427813176] Debug — Completed: [127.0.0.1:40558] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=73&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #9d7 GZIP 0ms 195 bytes (pipelined: 2) Oct 11, 2025 17:06:08.650 [22781388442424] Debug — Request: [127.0.0.1:40558 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=73&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #9dc GZIP Token () Oct 11, 2025 17:06:08.650 [22781425703736] Debug — Completed: [127.0.0.1:40558] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=73&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #9dc GZIP 0ms 195 bytes (pipelined: 3) Oct 11, 2025 17:06:09.115 [22781388442424] Debug — Request: [127.0.0.1:40558 (Loopback)] GET /library/changestamp (4 live) #9e3 GZIP Token () Oct 11, 2025 17:06:09.115 [22781425703736] Debug — Completed: [127.0.0.1:40558] 200 GET /library/changestamp (4 live) #9e3 GZIP 0ms 417 bytes (pipelined: 4) Oct 11, 2025 17:06:09.116 [22781388442424] Debug — Request: [127.0.0.1:40558 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=73&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #9ea GZIP Token () Oct 11, 2025 17:06:09.116 [22781425703736] Debug — Completed: [127.0.0.1:40558] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=73&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #9ea GZIP 0ms 195 bytes (pipelined: 5) Oct 11, 2025 17:06:09.117 [22781388442424] Debug — Request: [127.0.0.1:40558 (Loopback)] GET /library/changestamp (4 live) #9ed GZIP Token () Oct 11, 2025 17:06:09.117 [22781427813176] Debug — Completed: [127.0.0.1:40558] 200 GET /library/changestamp (4 live) #9ed GZIP 0ms 417 bytes (pipelined: 6) Oct 11, 2025 17:06:09.118 [22781388442424] Debug — Request: [127.0.0.1:40558 (Loopback)] GET /library/changestamp (4 live) #9f6 GZIP Token () Oct 11, 2025 17:06:09.119 [22781427813176] Debug — Completed: [127.0.0.1:40558] 200 GET /library/changestamp (4 live) #9f6 GZIP 0ms 417 bytes (pipelined: 7) Oct 11, 2025 17:06:09.121 [22781433453368] Debug — Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 861 is 0 (success) Oct 11, 2025 17:06:09.121 [22781382019896] Debug — Killing job. Oct 11, 2025 17:06:09.121 [22781382019896] Debug — Signalling job ID 861 with 9 Oct 11, 2025 17:06:09.121 [22781382019896] Debug — Media Analyzer: Background analysis completed in 0.7 seconds, removing 1 IDs Oct 11, 2025 17:06:09.121 [22781384129336] Debug — MetadataAgentPostProcessor: adding id 73 to refresh queue (0 items in queue) Oct 11, 2025 17:06:09.121 [22781384129336] Debug — Native Scanner: Executed Media Analysis stage in 0.66 sec. Oct 11, 2025 17:06:09.121 [22781382019896] Debug — Media Analyzer: Performing on-the-fly analysis on 1 item. Oct 11, 2025 17:06:09.121 [22781382019896] Debug — [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/46f74ab-560174306fe167a5978a79dd-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Media Scanner" --analyze --log-file-suffix " Analysis" --item 74 Oct 11, 2025 17:06:09.121 [22781382019896] Debug — [JobRunner] Jobs: Starting child process with pid 867 Oct 11, 2025 17:06:09.121 [22781394848568] Debug — Native Scanner: Executed Local Metadata stage in 0.00 sec. Oct 11, 2025 17:06:09.122 [22781390551864] Debug — Skipping hash check, no size match for 30683839282 bytes. Oct 11, 2025 17:06:09.122 [22781390551864] Debug — No match for hash. Oct 11, 2025 17:06:09.122 [22781390551864] Debug — Added new metadata item (Angels & Demons) with ID 75 Oct 11, 2025 17:06:09.123 [22781386312504] Debug — [LibraryTimeline] Scanner activity on section 1: 75 added, 0 deleted Oct 11, 2025 17:06:09.123 [22781372742456] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:06:09.123 [22781372742456] Error — MetadataAgentPostProcessor: failed to find agent for identifier tv.plex.agents.movie Oct 11, 2025 17:06:09.124 [22781390551864] Debug — Native Scanner: Executed Add to Database stage in 0.00 sec. Oct 11, 2025 17:06:09.146 [22781397056312] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:06:09.146 [22781397056312] Warning — Scanner [Plex Movie]: unable to find agent match for item file '/data/Movies/Animusic 2 (Video 2005).mkv' Oct 11, 2025 17:06:09.146 [22781397056312] Debug — Native Scanner: Executed Agent Match stage in 0.02 sec. Oct 11, 2025 17:06:09.176 [22781388442424] Debug — Request: [127.0.0.1:40562 (Loopback)] GET /library/changestamp (4 live) #9d4 GZIP Token () Oct 11, 2025 17:06:09.176 [22781425703736] Debug — Completed: [127.0.0.1:40562] 200 GET /library/changestamp (4 live) #9d4 GZIP 0ms 417 bytes (pipelined: 1) Oct 11, 2025 17:06:09.208 [22781388442424] Debug — Request: [127.0.0.1:40562 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=74&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #a2e GZIP Token () Oct 11, 2025 17:06:09.208 [22781425703736] Debug — Completed: [127.0.0.1:40562] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=74&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #a2e GZIP 0ms 195 bytes (pipelined: 2) Oct 11, 2025 17:06:09.208 [22781388442424] Debug — Request: [127.0.0.1:40562 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=74&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #a33 GZIP Token () Oct 11, 2025 17:06:09.208 [22781425703736] Debug — Completed: [127.0.0.1:40562] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=74&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #a33 GZIP 0ms 195 bytes (pipelined: 3) Oct 11, 2025 17:06:09.840 [22781388442424] Debug — Request: [127.0.0.1:40562 (Loopback)] GET /library/changestamp (4 live) #a38 GZIP Token () Oct 11, 2025 17:06:09.840 [22781425703736] Debug — Completed: [127.0.0.1:40562] 200 GET /library/changestamp (4 live) #a38 GZIP 0ms 417 bytes (pipelined: 4) Oct 11, 2025 17:06:09.841 [22781388442424] Debug — Request: [127.0.0.1:40562 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=74&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #a41 GZIP Token () Oct 11, 2025 17:06:09.841 [22781425703736] Debug — Completed: [127.0.0.1:40562] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=74&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #a41 GZIP 0ms 195 bytes (pipelined: 5) Oct 11, 2025 17:06:09.842 [22781388442424] Debug — Request: [127.0.0.1:40562 (Loopback)] GET /library/changestamp (4 live) #a46 GZIP Token () Oct 11, 2025 17:06:09.842 [22781427813176] Debug — Completed: [127.0.0.1:40562] 200 GET /library/changestamp (4 live) #a46 GZIP 0ms 417 bytes (pipelined: 6) Oct 11, 2025 17:06:09.845 [22781388442424] Debug — Request: [127.0.0.1:40562 (Loopback)] GET /library/changestamp (4 live) #a4d GZIP Token () Oct 11, 2025 17:06:09.845 [22781425703736] Debug — Completed: [127.0.0.1:40562] 200 GET /library/changestamp (4 live) #a4d GZIP 0ms 417 bytes (pipelined: 7) Oct 11, 2025 17:06:09.847 [22781433453368] Debug — Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 867 is 0 (success) Oct 11, 2025 17:06:09.847 [22781382019896] Debug — Killing job. Oct 11, 2025 17:06:09.847 [22781382019896] Debug — Signalling job ID 867 with 9 Oct 11, 2025 17:06:09.847 [22781382019896] Debug — Media Analyzer: Background analysis completed in 0.7 seconds, removing 1 IDs Oct 11, 2025 17:06:09.847 [22781384129336] Debug — MetadataAgentPostProcessor: adding id 74 to refresh queue (0 items in queue) Oct 11, 2025 17:06:09.847 [22781384129336] Debug — Native Scanner: Executed Media Analysis stage in 0.73 sec. Oct 11, 2025 17:06:09.847 [22781382019896] Debug — Media Analyzer: Performing on-the-fly analysis on 1 item. Oct 11, 2025 17:06:09.847 [22781382019896] Debug — [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/46f74ab-560174306fe167a5978a79dd-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Media Scanner" --analyze --log-file-suffix " Analysis" --item 75 Oct 11, 2025 17:06:09.847 [22781382019896] Debug — [JobRunner] Jobs: Starting child process with pid 873 Oct 11, 2025 17:06:09.847 [22781390551864] Debug — Native Scanner: Executed Local Metadata stage in 0.00 sec. Oct 11, 2025 17:06:09.848 [22781397056312] Debug — Skipping hash check, no size match for 2840102512 bytes. Oct 11, 2025 17:06:09.848 [22781397056312] Debug — No match for hash. Oct 11, 2025 17:06:09.849 [22781397056312] Debug — Added new metadata item (Animusic 2 Video 2005) with ID 76 Oct 11, 2025 17:06:09.849 [22781372742456] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:06:09.849 [22781372742456] Error — MetadataAgentPostProcessor: failed to find agent for identifier tv.plex.agents.movie Oct 11, 2025 17:06:09.849 [22781386312504] Debug — [LibraryTimeline] Scanner activity on section 1: 76 added, 0 deleted Oct 11, 2025 17:06:09.850 [22781397056312] Debug — Native Scanner: Executed Add to Database stage in 0.00 sec. Oct 11, 2025 17:06:09.906 [22781394848568] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:06:09.906 [22781394848568] Warning — Scanner [Plex Movie]: unable to find agent match for item file '/data/Movies/Annabelle (2014).mkv' Oct 11, 2025 17:06:09.907 [22781394848568] Debug — Native Scanner: Executed Agent Match stage in 0.06 sec. Oct 11, 2025 17:06:09.909 [22781388442424] Debug — Request: [127.0.0.1:40572 (Loopback)] GET /library/changestamp (4 live) #a2b GZIP Token () Oct 11, 2025 17:06:09.909 [22781425703736] Debug — Completed: [127.0.0.1:40572] 200 GET /library/changestamp (4 live) #a2b GZIP 0ms 417 bytes (pipelined: 1) Oct 11, 2025 17:06:10.053 [22781388442424] Debug — Request: [127.0.0.1:40572 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=75&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #a85 GZIP Token () Oct 11, 2025 17:06:10.053 [22781427813176] Debug — Completed: [127.0.0.1:40572] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=75&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #a85 GZIP 0ms 195 bytes (pipelined: 2) Oct 11, 2025 17:06:10.054 [22781388442424] Debug — Request: [127.0.0.1:40572 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=75&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #a89 GZIP Token () Oct 11, 2025 17:06:10.054 [22781425703736] Debug — Completed: [127.0.0.1:40572] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=75&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #a89 GZIP 0ms 195 bytes (pipelined: 3) Oct 11, 2025 17:06:11.546 [22781388442424] Debug — Request: [127.0.0.1:40572 (Loopback)] GET /library/changestamp (4 live) #a8f GZIP Token () Oct 11, 2025 17:06:11.546 [22781425703736] Debug — Completed: [127.0.0.1:40572] 200 GET /library/changestamp (4 live) #a8f GZIP 0ms 417 bytes (pipelined: 4) Oct 11, 2025 17:06:11.547 [22781388442424] Debug — Request: [127.0.0.1:40572 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=75&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #a98 GZIP Token () Oct 11, 2025 17:06:11.547 [22781425703736] Debug — Completed: [127.0.0.1:40572] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=75&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #a98 GZIP 0ms 195 bytes (pipelined: 5) Oct 11, 2025 17:06:11.548 [22781388442424] Debug — Request: [127.0.0.1:40572 (Loopback)] GET /library/changestamp (4 live) #a9d GZIP Token () Oct 11, 2025 17:06:11.548 [22781425703736] Debug — Completed: [127.0.0.1:40572] 200 GET /library/changestamp (4 live) #a9d GZIP 0ms 417 bytes (pipelined: 6) Oct 11, 2025 17:06:11.549 [22781388442424] Debug — Request: [127.0.0.1:40572 (Loopback)] GET /library/changestamp (4 live) #aa4 GZIP Token () Oct 11, 2025 17:06:11.550 [22781425703736] Debug — Completed: [127.0.0.1:40572] 200 GET /library/changestamp (4 live) #aa4 GZIP 0ms 417 bytes (pipelined: 7) Oct 11, 2025 17:06:11.552 [22781433453368] Debug — Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 873 is 0 (success) Oct 11, 2025 17:06:11.552 [22781382019896] Debug — Killing job. Oct 11, 2025 17:06:11.552 [22781382019896] Debug — Signalling job ID 873 with 9 Oct 11, 2025 17:06:11.552 [22781382019896] Debug — Media Analyzer: Background analysis completed in 1.7 seconds, removing 1 IDs Oct 11, 2025 17:06:11.552 [22781384129336] Debug — MetadataAgentPostProcessor: adding id 75 to refresh queue (0 items in queue) Oct 11, 2025 17:06:11.552 [22781384129336] Debug — Native Scanner: Executed Media Analysis stage in 1.71 sec. Oct 11, 2025 17:06:11.552 [22781382019896] Debug — Media Analyzer: Performing on-the-fly analysis on 1 item. Oct 11, 2025 17:06:11.552 [22781382019896] Debug — [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/46f74ab-560174306fe167a5978a79dd-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Media Scanner" --analyze --log-file-suffix " Analysis" --item 76 Oct 11, 2025 17:06:11.552 [22781390551864] Debug — Native Scanner: Executed Local Metadata stage in 0.00 sec. Oct 11, 2025 17:06:11.552 [22781382019896] Debug — [JobRunner] Jobs: Starting child process with pid 879 Oct 11, 2025 17:06:11.553 [22781384129336] Debug — Skipping hash check, no size match for 21225644366 bytes. Oct 11, 2025 17:06:11.553 [22781384129336] Debug — No match for hash. Oct 11, 2025 17:06:11.553 [22781384129336] Debug — Added new metadata item (Annabelle) with ID 77 Oct 11, 2025 17:06:11.553 [22781386312504] Debug — [LibraryTimeline] Scanner activity on section 1: 77 added, 0 deleted Oct 11, 2025 17:06:11.554 [22781372742456] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:06:11.554 [22781372742456] Error — MetadataAgentPostProcessor: failed to find agent for identifier tv.plex.agents.movie Oct 11, 2025 17:06:11.557 [22781384129336] Debug — Native Scanner: Executed Add to Database stage in 0.01 sec. Oct 11, 2025 17:06:11.586 [22781394848568] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:06:11.586 [22781394848568] Warning — Scanner [Plex Movie]: unable to find agent match for item file '/data/Movies/Ant-Man (2015).mkv' Oct 11, 2025 17:06:11.586 [22781394848568] Debug — Native Scanner: Executed Agent Match stage in 0.03 sec. Oct 11, 2025 17:06:11.607 [22781388442424] Debug — Request: [127.0.0.1:40588 (Loopback)] GET /library/changestamp (4 live) #a82 GZIP Token () Oct 11, 2025 17:06:11.607 [22781425703736] Debug — Completed: [127.0.0.1:40588] 200 GET /library/changestamp (4 live) #a82 GZIP 0ms 417 bytes (pipelined: 1) Oct 11, 2025 17:06:11.615 [22781388442424] Debug — Request: [127.0.0.1:40588 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=76&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #adc GZIP Token () Oct 11, 2025 17:06:11.615 [22781427813176] Debug — Completed: [127.0.0.1:40588] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=76&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #adc GZIP 0ms 195 bytes (pipelined: 2) Oct 11, 2025 17:06:11.615 [22781388442424] Debug — Request: [127.0.0.1:40588 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=76&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #ae1 GZIP Token () Oct 11, 2025 17:06:11.615 [22781425703736] Debug — Completed: [127.0.0.1:40588] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=76&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #ae1 GZIP 0ms 195 bytes (pipelined: 3) Oct 11, 2025 17:06:11.821 [22781388442424] Debug — Request: [127.0.0.1:40588 (Loopback)] GET /library/changestamp (4 live) #ae6 GZIP Token () Oct 11, 2025 17:06:11.821 [22781427813176] Debug — Completed: [127.0.0.1:40588] 200 GET /library/changestamp (4 live) #ae6 GZIP 0ms 417 bytes (pipelined: 4) Oct 11, 2025 17:06:11.822 [22781388442424] Debug — Request: [127.0.0.1:40588 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=76&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #aef GZIP Token () Oct 11, 2025 17:06:11.822 [22781427813176] Debug — Completed: [127.0.0.1:40588] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=76&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #aef GZIP 0ms 195 bytes (pipelined: 5) Oct 11, 2025 17:06:11.823 [22781388442424] Debug — Request: [127.0.0.1:40588 (Loopback)] GET /library/changestamp (4 live) #af2 GZIP Token () Oct 11, 2025 17:06:11.823 [22781427813176] Debug — Completed: [127.0.0.1:40588] 200 GET /library/changestamp (4 live) #af2 GZIP 0ms 417 bytes (pipelined: 6) Oct 11, 2025 17:06:11.825 [22781388442424] Debug — Request: [127.0.0.1:40588 (Loopback)] GET /library/changestamp (4 live) #afb GZIP Token () Oct 11, 2025 17:06:11.825 [22781425703736] Debug — Completed: [127.0.0.1:40588] 200 GET /library/changestamp (4 live) #afb GZIP 0ms 417 bytes (pipelined: 7) Oct 11, 2025 17:06:11.827 [22781433453368] Debug — Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 879 is 0 (success) Oct 11, 2025 17:06:11.827 [22781382019896] Debug — Killing job. Oct 11, 2025 17:06:11.827 [22781382019896] Debug — Signalling job ID 879 with 9 Oct 11, 2025 17:06:11.827 [22781382019896] Debug — Media Analyzer: Background analysis completed in 0.3 seconds, removing 1 IDs Oct 11, 2025 17:06:11.827 [22781397056312] Debug — MetadataAgentPostProcessor: adding id 76 to refresh queue (0 items in queue) Oct 11, 2025 17:06:11.827 [22781397056312] Debug — Native Scanner: Executed Media Analysis stage in 0.28 sec. Oct 11, 2025 17:06:11.827 [22781382019896] Debug — Media Analyzer: Performing on-the-fly analysis on 1 item. Oct 11, 2025 17:06:11.827 [22781382019896] Debug — [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/46f74ab-560174306fe167a5978a79dd-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Media Scanner" --analyze --log-file-suffix " Analysis" --item 77 Oct 11, 2025 17:06:11.828 [22781382019896] Debug — [JobRunner] Jobs: Starting child process with pid 885 Oct 11, 2025 17:06:11.828 [22781390551864] Debug — Native Scanner: Executed Local Metadata stage in 0.00 sec. Oct 11, 2025 17:06:11.828 [22781397056312] Debug — Skipping hash check, no size match for 29831185793 bytes. Oct 11, 2025 17:06:11.828 [22781397056312] Debug — No match for hash. Oct 11, 2025 17:06:11.829 [22781372742456] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:06:11.829 [22781372742456] Error — MetadataAgentPostProcessor: failed to find agent for identifier tv.plex.agents.movie Oct 11, 2025 17:06:11.829 [22781397056312] Debug — Added new metadata item (Ant Man) with ID 78 Oct 11, 2025 17:06:11.829 [22781386312504] Debug — [LibraryTimeline] Scanner activity on section 1: 78 added, 0 deleted Oct 11, 2025 17:06:11.830 [22781397056312] Debug — Native Scanner: Executed Add to Database stage in 0.00 sec. Oct 11, 2025 17:06:11.867 [22781394848568] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:06:11.867 [22781394848568] Warning — Scanner [Plex Movie]: unable to find agent match for item file '/data/Movies/Apocalypse Now (1979) - Final Cut 2.mkv' Oct 11, 2025 17:06:11.867 [22781394848568] Debug — Native Scanner: Executed Agent Match stage in 0.04 sec. Oct 11, 2025 17:06:11.890 [22781388442424] Debug — Request: [127.0.0.1:40600 (Loopback)] GET /library/changestamp (4 live) #ad9 GZIP Token () Oct 11, 2025 17:06:11.890 [22781425703736] Debug — Completed: [127.0.0.1:40600] 200 GET /library/changestamp (4 live) #ad9 GZIP 0ms 417 bytes (pipelined: 1) Oct 11, 2025 17:06:12.128 [22781388442424] Debug — Request: [127.0.0.1:40600 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=77&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #b33 GZIP Token () Oct 11, 2025 17:06:12.128 [22781425703736] Debug — Completed: [127.0.0.1:40600] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=77&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #b33 GZIP 0ms 195 bytes (pipelined: 2) Oct 11, 2025 17:06:12.129 [22781388442424] Debug — Request: [127.0.0.1:40600 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=77&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #b38 GZIP Token () Oct 11, 2025 17:06:12.129 [22781427813176] Debug — Completed: [127.0.0.1:40600] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=77&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #b38 GZIP 0ms 195 bytes (pipelined: 3) Oct 11, 2025 17:06:13.653 [22781388442424] Debug — Request: [127.0.0.1:40600 (Loopback)] GET /library/changestamp (4 live) #b3f GZIP Token () Oct 11, 2025 17:06:13.653 [22781425703736] Debug — Completed: [127.0.0.1:40600] 200 GET /library/changestamp (4 live) #b3f GZIP 0ms 417 bytes (pipelined: 4) Oct 11, 2025 17:06:13.654 [22781388442424] Debug — Request: [127.0.0.1:40600 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=77&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #b46 GZIP Token () Oct 11, 2025 17:06:13.654 [22781425703736] Debug — Completed: [127.0.0.1:40600] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=77&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #b46 GZIP 0ms 195 bytes (pipelined: 5) Oct 11, 2025 17:06:13.655 [22781388442424] Debug — Request: [127.0.0.1:40600 (Loopback)] GET /library/changestamp (4 live) #b4b GZIP Token () Oct 11, 2025 17:06:13.655 [22781425703736] Debug — Completed: [127.0.0.1:40600] 200 GET /library/changestamp (4 live) #b4b GZIP 0ms 417 bytes (pipelined: 6) Oct 11, 2025 17:06:13.657 [22781388442424] Debug — Request: [127.0.0.1:40600 (Loopback)] GET /library/changestamp (4 live) #b52 GZIP Token () Oct 11, 2025 17:06:13.657 [22781427813176] Debug — Completed: [127.0.0.1:40600] 200 GET /library/changestamp (4 live) #b52 GZIP 0ms 417 bytes (pipelined: 7) Oct 11, 2025 17:06:13.659 [22781433453368] Debug — Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 885 is 0 (success) Oct 11, 2025 17:06:13.659 [22781382019896] Debug — Killing job. Oct 11, 2025 17:06:13.659 [22781382019896] Debug — Signalling job ID 885 with 9 Oct 11, 2025 17:06:13.659 [22781382019896] Debug — Media Analyzer: Background analysis completed in 1.8 seconds, removing 1 IDs Oct 11, 2025 17:06:13.659 [22781384129336] Debug — MetadataAgentPostProcessor: adding id 77 to refresh queue (0 items in queue) Oct 11, 2025 17:06:13.659 [22781384129336] Debug — Native Scanner: Executed Media Analysis stage in 1.83 sec. Oct 11, 2025 17:06:13.659 [22781382019896] Debug — Media Analyzer: Performing on-the-fly analysis on 1 item. Oct 11, 2025 17:06:13.659 [22781382019896] Debug — [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/46f74ab-560174306fe167a5978a79dd-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Media Scanner" --analyze --log-file-suffix " Analysis" --item 78 Oct 11, 2025 17:06:13.659 [22781382019896] Debug — [JobRunner] Jobs: Starting child process with pid 891 Oct 11, 2025 17:06:13.659 [22781390551864] Debug — Native Scanner: Executed Local Metadata stage in 0.00 sec. Oct 11, 2025 17:06:13.660 [22781384129336] Debug — Skipping hash check, no size match for 43591445954 bytes. Oct 11, 2025 17:06:13.660 [22781384129336] Debug — No match for hash. Oct 11, 2025 17:06:13.660 [22781384129336] Debug — Added new metadata item (Apocalypse Now) with ID 79 Oct 11, 2025 17:06:13.660 [22781386312504] Debug — [LibraryTimeline] Scanner activity on section 1: 79 added, 0 deleted Oct 11, 2025 17:06:13.660 [22781372742456] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:06:13.660 [22781372742456] Error — MetadataAgentPostProcessor: failed to find agent for identifier tv.plex.agents.movie Oct 11, 2025 17:06:13.661 [22781384129336] Debug — Native Scanner: Executed Add to Database stage in 0.00 sec. Oct 11, 2025 17:06:13.677 [22781394848568] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:06:13.677 [22781394848568] Warning — Scanner [Plex Movie]: unable to find agent match for item file '/data/Movies/Apocalypse Now (1979) - Final Cut.mkv' Oct 11, 2025 17:06:13.677 [22781394848568] Debug — Native Scanner: Executed Agent Match stage in 0.02 sec. Oct 11, 2025 17:06:13.718 [22781388442424] Debug — Request: [127.0.0.1:46512 (Loopback)] GET /library/changestamp (4 live) #b30 GZIP Token () Oct 11, 2025 17:06:13.718 [22781427813176] Debug — Completed: [127.0.0.1:46512] 200 GET /library/changestamp (4 live) #b30 GZIP 0ms 417 bytes (pipelined: 1) Oct 11, 2025 17:06:13.747 [22781388442424] Debug — Request: [127.0.0.1:46512 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #b8a GZIP Token () Oct 11, 2025 17:06:13.747 [22781427813176] Debug — Completed: [127.0.0.1:46512] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #b8a GZIP 0ms 195 bytes (pipelined: 2) Oct 11, 2025 17:06:13.747 [22781388442424] Debug — Request: [127.0.0.1:46512 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #b8f GZIP Token () Oct 11, 2025 17:06:13.747 [22781425703736] Debug — Completed: [127.0.0.1:46512] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #b8f GZIP 0ms 195 bytes (pipelined: 3) Oct 11, 2025 17:06:14.340 [22781388442424] Debug — Request: [127.0.0.1:46512 (Loopback)] GET /library/changestamp (4 live) #b95 GZIP Token () Oct 11, 2025 17:06:14.341 [22781427813176] Debug — Completed: [127.0.0.1:46512] 200 GET /library/changestamp (4 live) #b95 GZIP 0ms 417 bytes (pipelined: 4) Oct 11, 2025 17:06:14.342 [22781388442424] Debug — Request: [127.0.0.1:46512 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #b9d GZIP Token () Oct 11, 2025 17:06:14.342 [22781425703736] Debug — Completed: [127.0.0.1:46512] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=78&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=idle (4 live) #b9d GZIP 0ms 195 bytes (pipelined: 5) Oct 11, 2025 17:06:14.343 [22781388442424] Debug — Request: [127.0.0.1:46512 (Loopback)] GET /library/changestamp (4 live) #ba2 GZIP Token () Oct 11, 2025 17:06:14.343 [22781427813176] Debug — Completed: [127.0.0.1:46512] 200 GET /library/changestamp (4 live) #ba2 GZIP 0ms 417 bytes (pipelined: 6) Oct 11, 2025 17:06:14.345 [22781388442424] Debug — Request: [127.0.0.1:46512 (Loopback)] GET /library/changestamp (4 live) #ba9 GZIP Token () Oct 11, 2025 17:06:14.345 [22781425703736] Debug — Completed: [127.0.0.1:46512] 200 GET /library/changestamp (4 live) #ba9 GZIP 0ms 417 bytes (pipelined: 7) Oct 11, 2025 17:06:14.347 [22781433453368] Debug — Jobs: '/usr/lib/plexmediaserver/Plex Media Scanner' exit code for process 891 is 0 (success) Oct 11, 2025 17:06:14.347 [22781382019896] Debug — Killing job. Oct 11, 2025 17:06:14.347 [22781382019896] Debug — Signalling job ID 891 with 9 Oct 11, 2025 17:06:14.347 [22781382019896] Debug — Media Analyzer: Background analysis completed in 0.7 seconds, removing 1 IDs Oct 11, 2025 17:06:14.347 [22781397056312] Debug — MetadataAgentPostProcessor: adding id 78 to refresh queue (0 items in queue) Oct 11, 2025 17:06:14.347 [22781397056312] Debug — Native Scanner: Executed Media Analysis stage in 0.69 sec. Oct 11, 2025 17:06:14.347 [22781382019896] Debug — Media Analyzer: Performing on-the-fly analysis on 1 item. Oct 11, 2025 17:06:14.347 [22781382019896] Debug — [JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/46f74ab-560174306fe167a5978a79dd-linux-x86_64/' X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxxx "/usr/lib/plexmediaserver/Plex Media Scanner" --analyze --log-file-suffix " Analysis" --item 79 Oct 11, 2025 17:06:14.347 [22781382019896] Debug — [JobRunner] Jobs: Starting child process with pid 897 Oct 11, 2025 17:06:14.347 [22781390551864] Debug — Native Scanner: Executed Local Metadata stage in 0.00 sec. Oct 11, 2025 17:06:14.348 [22781394848568] Debug — Skipping hash check, no size match for 84270870421 bytes. Oct 11, 2025 17:06:14.348 [22781394848568] Debug — No match for hash. Oct 11, 2025 17:06:14.348 [22781394848568] Debug — Added new metadata item (Apocalypse Now) with ID 80 Oct 11, 2025 17:06:14.349 [22781386312504] Debug — [LibraryTimeline] Scanner activity on section 1: 80 added, 0 deleted Oct 11, 2025 17:06:14.349 [22781372742456] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:06:14.349 [22781372742456] Error — MetadataAgentPostProcessor: failed to find agent for identifier tv.plex.agents.movie Oct 11, 2025 17:06:14.349 [22781394848568] Debug — Native Scanner: Executed Add to Database stage in 0.00 sec. Oct 11, 2025 17:06:14.362 [22781397056312] Error — [MetadataAgentManager/getAgent] Unable to find or create media provider for agent 'tv.plex.agents.movie' Oct 11, 2025 17:06:14.362 [22781397056312] Warning — Scanner [Plex Movie]: unable to find agent match for item file '/data/Movies/Apocalypse Now (1979) - Original - UHD.mkv' Oct 11, 2025 17:06:14.362 [22781397056312] Debug — Native Scanner: Executed Agent Match stage in 0.01 sec. Oct 11, 2025 17:06:14.410 [22781388442424] Debug — Request: [127.0.0.1:46522 (Loopback)] GET /library/changestamp (4 live) #b87 GZIP Token () Oct 11, 2025 17:06:14.410 [22781425703736] Debug — Completed: [127.0.0.1:46522] 200 GET /library/changestamp (4 live) #b87 GZIP 0ms 417 bytes (pipelined: 1) Oct 11, 2025 17:06:14.556 [22781388442424] Debug — Request: [127.0.0.1:46522 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=79&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #be1 GZIP Token () Oct 11, 2025 17:06:14.557 [22781427813176] Debug — Completed: [127.0.0.1:46522] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=79&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=analyzing (4 live) #be1 GZIP 0ms 195 bytes (pipelined: 2) Oct 11, 2025 17:06:14.557 [22781388442424] Debug — Request: [127.0.0.1:46522 (Loopback)] GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=79&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #be5 GZIP Token () Oct 11, 2025 17:06:14.557 [22781427813176] Debug — Completed: [127.0.0.1:46522] 200 GET /:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=79&metadataType=1&state=-1&parentID=-1&rootID=-1&mediaState=thumbnailing (4 live) #be5 GZIP 0ms 195 bytes (pipelined: 3)

  9. Plex Container Crash-Loop on Fresh Install - 500 Error on Claim (Exhaustive Troubleshooting Inside)

    Bullhead28 replied to Bullhead28's topic in General Support
    Stop start seems to have worked. It still gave me those two errors after adding the server friendly name. It's currently adding media. Should I wait to go to General and Claim?

  10. Plex Container Crash-Loop on Fresh Install - 500 Error on Claim (Exhaustive Troubleshooting Inside)

    Bullhead28 replied to Bullhead28's topic in General Support
    Did the /data path too.

  11. Plex Container Crash-Loop on Fresh Install - 500 Error on Claim (Exhaustive Troubleshooting Inside)

    Bullhead28 replied to Bullhead28's topic in General Support
    I mean... the log looks good, no? I successfully did the chmod permission thing. The only snag is that going to http://[IP]:32400/web/, the server setup doesn't show.

  12. Plex Container Crash-Loop on Fresh Install - 500 Error on Claim (Exhaustive Troubleshooting Inside)

    Bullhead28 replied to Bullhead28's topic in General Support
    First, thank you SO MUCH for taking the time to help me! I got as far as the docker safe permission tools. I was a bit confused so I paused. Before I commit, is this right? (images attached)

  13. Plex Container Crash-Loop on Fresh Install - 500 Error on Claim (Exhaustive Troubleshooting Inside)

    Bullhead28 replied to Bullhead28's topic in General Support
    I should probably have mentioned that I tried the plexinc and the linuxserver as well. In all cases, I have completely deleted the folder in appdata, which would include all files and Preferences.xml. Is that what you were referring to?

  14. Plex Container Crash-Loop on Fresh Install - 500 Error on Claim (Exhaustive Troubleshooting Inside)

    Bullhead28 posted a topic in General Support
    I'm hoping someone can help me with a very stubborn issue. I've been trying to set up a binhex-plexpass Docker container on a fresh Unraid server, and I'm stuck in a loop that I can't seem to break. I've spent days on this and have ruled out what feels like everything. TL;DR: A brand new binhex-plexpass container immediately enters a crash-loop on first start. Any attempt to claim the server (either via the web setup or a PLEX_CLAIM token) fails because the main Plex process is not running correctly. The container log ultimately shows the claim script receiving a 500 Internal Server Error. This happens on a completely pristine system, even immediately after recreating the Docker vDisk and wiping appdata. A 22-hour Memtest has passed with zero errors. Diagnostics file is attached. ## The Problem in DetailWhen I start a brand new binhex-plexpass container with a fresh, empty appdata folder, the Plex server process seems to crash instantly. If I try to claim it manually via http://[SERVER_IP]:32400, the setup wizard will start, but after I name the server, I get the red error bars: "There was a problem claiming the server" and "There was a problem signing in to Plex." If I use a PLEX_CLAIM token, the container log shows the start-script failing and the claim-script eventually receiving a 500 Internal Server Error, indicating it can't communicate with the crashed local server process. This happens before I have even added a single media library. The core server process is failing at the most basic level. ## My SystemUnraid OS: 6.12.24-Unraid Docker Image: binhex/arch-plexpass Hardware: RAM: Passed a 22-hour Memtest86+ run with zero errors. appdata share is currently running on the main array (to rule out a faulty cache drive). ## What I Have Already Tried (Troubleshooting Steps)I have systematically ruled out every common cause I can think of. Network TroubleshootingInternet Connectivity: Confirmed the server has full outbound internet access using curl -v https://plex.tv. The connection is successful. DNS: Ruled out local DNS issues by setting the server to use 8.8.8.8 and 1.1.1.1. Double NAT: Confirmed there is no Double NAT (router's WAN IP matches public IP). Port Forwarding / ISP Blocking: Successfully enabled remote access on a custom port (32500), confirming the ISP is not blocking traffic and that port forwarding works (when the server is stable enough to test it). Plex & Docker Application TroubleshootingMultiple appdata Wipes: I have completely deleted the /appdata/binhex-plexpass folder more than five times to start fresh. Claiming Methods: The failure occurs with both the manual web setup and the PLEX_CLAIM token method. Plex Account: Removed all old/duplicate server entries from "Authorized Devices" on my plex.tv account. Docker Image Refresh: Forced a complete re-download of the binhex-plexpass image. Folder Structure: Corrected a nested media folder structure issue (this was a separate problem, but has been fixed). Unraid System & Hardware TroubleshootingDocker vDisk Re-creation: This was the "nuclear option." I stopped the Docker service, deleted the docker.img vDisk file, and had Unraid create a brand new, 20GB vDisk from scratch. The 500 Internal Server Error returned on the very first container I installed. RAM Test: Ran a full 22-hour Memtest86+ which passed with zero errors. Cache Drive Test: To rule out a faulty cache drive, I moved the entire appdata share to the main array. The crash-loop and 500 error still occur even when Plex is running entirely from the array. ## Current Status & The AskAfter all of this, the problem remains identical. The log from a brand new container (on a new Docker vDisk, with a new appdata folder) shows the start-script exiting and the claim failing with a 500 error. Here's a key snippet from the latest log: Plex Media Server is already running. Will not start... exited: start-script (exit status 1; not expected) ... [info] Claim response received: <html><head><title>Internal Server Error</title></head><body><h1>500 Internal Server Error</h1></body></html> I have exhausted every possible software, configuration, and common hardware fix. The issue appears to be at a very low level, specific to my server, that is causing the Plex process to crash instantly. Could someone please take a look at the attached diagnostics file? I'm hoping to find a kernel error, a persistent filesystem issue, or a hardware conflict that could explain this behavior. Thank you in advance for your time and expertise. assphaceserver-diagnostics-20251008-2210.zip

  15. Download of appfeed failed

    Bullhead28 replied to Bullhead28's topic in General Support
    Anyone? Bueller?

  16. Download of appfeed failed

    Bullhead28 posted a topic in General Support
    I set my DNS to 208.67.222.222 and 208.67.220.220 in settings > network and restarted my router but still can't get appfeed to work. butthole-diagnostics-20230711-2230.zip

  17. Mover having issues /mnt/cache/ ._.DS_Store No space left on device

    Bullhead28 replied to Bullhead28's topic in General Support
    Thank you. I will reduce the minimum file size and see if that helps as a temporary solution. I've been waiting on a decent sale to get a new drive to add since I just replaced a failing parity drive earlier this month.

  18. Mover having issues /mnt/cache/ ._.DS_Store No space left on device

    Bullhead28 replied to Bullhead28's topic in General Support
    Great, thank you! butthole-diagnostics-20221217-2026.zip

  19. Mover having issues /mnt/cache/ ._.DS_Store No space left on device

    Bullhead28 posted a topic in General Support
    OK, I did my best to read as many forum posts as possible to try and resolve my issue but can't seem to figure it out with my Plex server. The mover used to work fine. I don't know what changed. I have the log file but can't seem to find a way to upload it. I have 494GB free on my array and 392GB in cache SSD waiting to transfer over but I'm getting these: Dec 17 15:46:32 Butthole kernel: ICMPv6: RA: ndisc_router_discovery failed to add default route Dec 17 15:47:54 Butthole emhttpd: shcmd (397): /usr/local/sbin/mover |& logger & Dec 17 15:47:54 Butthole root: mover: started Dec 17 15:47:54 Butthole move: move: file /mnt/cache/Movies/Movies/The Martian (2015) - UHD.mkv Dec 17 15:47:54 Butthole move: move_object: /mnt/cache/Movies/Movies/The Martian (2015) - UHD.mkv No space left on device Dec 17 15:47:54 Butthole move: move: file /mnt/cache/Movies/Movies/The Score (2001).mkv Dec 17 15:47:54 Butthole move: move_object: /mnt/cache/Movies/Movies/The Score (2001).mkv No space left on device Dec 17 15:47:54 Butthole move: move: file /mnt/cache/Movies/Movies/Hell or High Water (2016) - UHD.mkv Dec 17 15:47:54 Butthole move: move_object: /mnt/cache/Movies/Movies/Hell or High Water (2016) - UHD.mkv No space left on device Dec 17 15:47:54 Butthole move: move: file /mnt/cache/Movies/Movies/Brawl in Cell Block 99 (2017).mkv Dec 17 15:47:54 Butthole move: move_object: /mnt/cache/Movies/Movies/Brawl in Cell Block 99 (2017).mkv No space left on device Dec 17 15:47:54 Butthole move: move: file /mnt/cache/Movies/Movies/Brawl in Cell Block 99 (2017) - UHD.mkv Dec 17 15:47:54 Butthole move: move_object: /mnt/cache/Movies/Movies/Brawl in Cell Block 99 (2017) - UHD.mkv No space left on device Dec 17 15:47:54 Butthole move: move: file /mnt/cache/Movies/Movies/The Homesman (2014).mkv Dec 17 15:47:54 Butthole move: move_object: /mnt/cache/Movies/Movies/The Homesman (2014).mkv No space left on device Dec 17 15:47:54 Butthole move: move: file /mnt/cache/Movies/Christmas Movies/The Polar Express (2004) - UHD.mkv Dec 17 15:47:54 Butthole move: move_object: /mnt/cache/Movies/Christmas Movies/The Polar Express (2004) - UHD.mkv No space left on device Dec 17 15:47:54 Butthole move: move: file /mnt/cache/Movies/Christmas Movies/Home Alone (1990) - UHD.mkv Dec 17 15:47:54 Butthole move: move_object: /mnt/cache/Movies/Christmas Movies/Home Alone (1990) - UHD.mkv No space left on device Dec 17 15:47:54 Butthole move: move: file /mnt/cache/Movies/Family Videos/.DS_Store Dec 17 15:47:54 Butthole move: move_object: /mnt/cache/Movies/Family Videos/.DS_Store No space left on device Dec 17 15:47:54 Butthole move: move: file /mnt/cache/Movies/Family Videos/._.DS_Store Dec 17 15:47:54 Butthole move: move_object: /mnt/cache/Movies/Family Videos/._.DS_Store No space left on device Dec 17 15:47:54 Butthole move: move: file /mnt/cache/Movies/Bonus Features/Planes Trains Bonus/PLANES, TRAINS AND AUTOMOBILES - Bonus_t00.mkv Dec 17 15:47:54 Butthole move: move: create_parent: /mnt/cache/Movies/Bonus Features/Planes Trains Bonus error: No space left on device Dec 17 15:47:54 Butthole move: move_object: /mnt/cache/Movies/Bonus Features/Planes Trains Bonus: No space left on device Dec 17 15:47:54 Butthole move: move: file /mnt/cache/Movies/Bonus Features/._Planes Trains Bonus Dec 17 15:47:54 Butthole move: move_object: /mnt/cache/Movies/Bonus Features/._Planes Trains Bonus No space left on device Dec 17 15:47:54 Butthole move: move: file /mnt/cache/TV Shows/Police Squad! (TV Series 1982)/.DS_Store Dec 17 15:47:54 Butthole move: move_object: /mnt/cache/TV Shows/Police Squad! (TV Series 1982)/.DS_Store No space left on device Dec 17 15:47:54 Butthole move: move: file /mnt/cache/TV Shows/Police Squad! (TV Series 1982)/._.DS_Store Dec 17 15:47:54 Butthole move: move_object: /mnt/cache/TV Shows/Police Squad! (TV Series 1982)/._.DS_Store No space left on device Dec 17 15:47:54 Butthole root: mover: finished Dec 17 15:49:34 Butthole kernel: ICMPv6: RA: ndisc_router_discovery failed to add default route Dec 17 15:50:03 Butthole emhttpd: shcmd (402): /usr/local/sbin/mover |& logger & Dec 17 15:50:03 Butthole root: mover: started Dec 17 15:50:03 Butthole move: move: file /mnt/cache/Movies/Movies/The Martian (2015) - UHD.mkv Dec 17 15:50:03 Butthole move: move_object: /mnt/cache/Movies/Movies/The Martian (2015) - UHD.mkv No space left on device Dec 17 15:50:03 Butthole move: move: file /mnt/cache/Movies/Movies/The Score (2001).mkv Dec 17 15:50:03 Butthole move: move_object: /mnt/cache/Movies/Movies/The Score (2001).mkv No space left on device Dec 17 15:50:03 Butthole move: move: file /mnt/cache/Movies/Movies/Hell or High Water (2016) - UHD.mkv Dec 17 15:50:03 Butthole move: move_object: /mnt/cache/Movies/Movies/Hell or High Water (2016) - UHD.mkv No space left on device Dec 17 15:50:03 Butthole move: move: file /mnt/cache/Movies/Movies/Brawl in Cell Block 99 (2017).mkv Dec 17 15:50:03 Butthole move: move_object: /mnt/cache/Movies/Movies/Brawl in Cell Block 99 (2017).mkv No space left on device Dec 17 15:50:03 Butthole move: move: file /mnt/cache/Movies/Movies/Brawl in Cell Block 99 (2017) - UHD.mkv Dec 17 15:50:03 Butthole move: move_object: /mnt/cache/Movies/Movies/Brawl in Cell Block 99 (2017) - UHD.mkv No space left on device Dec 17 15:50:03 Butthole move: move: file /mnt/cache/Movies/Movies/The Homesman (2014).mkv Dec 17 15:50:03 Butthole move: move_object: /mnt/cache/Movies/Movies/The Homesman (2014).mkv No space left on device Dec 17 15:50:04 Butthole move: move: file /mnt/cache/Movies/Christmas Movies/The Polar Express (2004) - UHD.mkv Dec 17 15:50:04 Butthole move: move_object: /mnt/cache/Movies/Christmas Movies/The Polar Express (2004) - UHD.mkv No space left on device Dec 17 15:50:04 Butthole move: move: file /mnt/cache/Movies/Christmas Movies/Home Alone (1990) - UHD.mkv Dec 17 15:50:04 Butthole move: move_object: /mnt/cache/Movies/Christmas Movies/Home Alone (1990) - UHD.mkv No space left on device Dec 17 15:50:04 Butthole move: move: file /mnt/cache/Movies/Family Videos/.DS_Store Dec 17 15:50:04 Butthole move: move_object: /mnt/cache/Movies/Family Videos/.DS_Store No space left on device Dec 17 15:50:04 Butthole move: move: file /mnt/cache/Movies/Family Videos/._.DS_Store Dec 17 15:50:04 Butthole move: move_object: /mnt/cache/Movies/Family Videos/._.DS_Store No space left on device Dec 17 15:50:04 Butthole move: move: file /mnt/cache/TV Shows/Police Squad! (TV Series 1982)/.DS_Store Dec 17 15:50:04 Butthole move: move_object: /mnt/cache/TV Shows/Police Squad! (TV Series 1982)/.DS_Store No space left on device Dec 17 15:50:04 Butthole move: move: file /mnt/cache/TV Shows/Police Squad! (TV Series 1982)/._.DS_Store Dec 17 15:50:04 Butthole move: move_object: /mnt/cache/TV Shows/Police Squad! (TV Series 1982)/._.DS_Store No space left on device Dec 17 15:50:04 Butthole root: mover: finished Dec 17 15:53:06 Butthole kernel: ICMPv6: RA: ndisc_router_discovery failed to add default route Dec 17 15:57:10 Butthole kernel: ICMPv6: RA: ndisc_router_discovery failed to add default route Dec 17 15:58:15 Butthole emhttpd: shcmd (419): /usr/local/sbin/mover |& logger & Dec 17 15:58:15 Butthole root: mover: started Dec 17 15:58:15 Butthole move: move: file /mnt/cache/Movies/Movies/The Martian (2015) - UHD.mkv Dec 17 15:58:15 Butthole move: move_object: /mnt/cache/Movies/Movies/The Martian (2015) - UHD.mkv No space left on device Dec 17 15:58:15 Butthole move: move: file /mnt/cache/Movies/Movies/The Score (2001).mkv Dec 17 15:58:15 Butthole move: move_object: /mnt/cache/Movies/Movies/The Score (2001).mkv No space left on device Dec 17 15:58:16 Butthole move: move: file /mnt/cache/Movies/Movies/Hell or High Water (2016) - UHD.mkv Dec 17 15:58:16 Butthole move: move_object: /mnt/cache/Movies/Movies/Hell or High Water (2016) - UHD.mkv No space left on device Dec 17 15:58:16 Butthole move: move: file /mnt/cache/Movies/Movies/Brawl in Cell Block 99 (2017).mkv Dec 17 15:58:16 Butthole move: move_object: /mnt/cache/Movies/Movies/Brawl in Cell Block 99 (2017).mkv No space left on device Dec 17 15:58:16 Butthole move: move: file /mnt/cache/Movies/Movies/Brawl in Cell Block 99 (2017) - UHD.mkv Dec 17 15:58:16 Butthole move: move_object: /mnt/cache/Movies/Movies/Brawl in Cell Block 99 (2017) - UHD.mkv No space left on device Dec 17 15:58:16 Butthole move: move: file /mnt/cache/Movies/Movies/The Homesman (2014).mkv Dec 17 15:58:16 Butthole move: move_object: /mnt/cache/Movies/Movies/The Homesman (2014).mkv No space left on device Dec 17 15:58:16 Butthole move: move: file /mnt/cache/Movies/Christmas Movies/The Polar Express (2004) - UHD.mkv Dec 17 15:58:16 Butthole move: move_object: /mnt/cache/Movies/Christmas Movies/The Polar Express (2004) - UHD.mkv No space left on device Dec 17 15:58:16 Butthole move: move: file /mnt/cache/Movies/Christmas Movies/Home Alone (1990) - UHD.mkv Dec 17 15:58:16 Butthole move: move_object: /mnt/cache/Movies/Christmas Movies/Home Alone (1990) - UHD.mkv No space left on device Dec 17 15:58:16 Butthole move: move: file /mnt/cache/Movies/Family Videos/.DS_Store Dec 17 15:58:16 Butthole move: move_object: /mnt/cache/Movies/Family Videos/.DS_Store No space left on device Dec 17 15:58:16 Butthole move: move: file /mnt/cache/Movies/Family Videos/._.DS_Store Dec 17 15:58:16 Butthole move: move_object: /mnt/cache/Movies/Family Videos/._.DS_Store No space left on device Dec 17 15:58:16 Butthole move: move: file /mnt/cache/TV Shows/Police Squad! (TV Series 1982)/.DS_Store Dec 17 15:58:16 Butthole move: move_object: /mnt/cache/TV Shows/Police Squad! (TV Series 1982)/.DS_Store No space left on device Dec 17 15:58:16 Butthole move: move: file /mnt/cache/TV Shows/Police Squad! (TV Series 1982)/._.DS_Store Dec 17 15:58:16 Butthole move: move_object: /mnt/cache/TV Shows/Police Squad! (TV Series 1982)/._.DS_Store No space left on device Dec 17 15:58:16 Butthole root: mover: finished

Account

Navigation

Search

Search

Configure browser push notifications
Chrome (Android)
  1. Tap the lock icon next to the address bar.
  2. Tap Permissions → Notifications.
  3. Adjust your preference.
Chrome (Desktop)
  1. Click the padlock icon in the address bar.
  2. Select Site settings.
  3. Find Notifications and adjust your preference.