PMS for Windows crashes during launch every time (after password change)

KJAB · September 11, 2025, 10:54am

Server Version#: 1.42.1.10060-4e8b05daf
Player Version#: n/a

OS: Microsoft Windows Server 2019 Datacenter, Version 10.0.17763 Build 17763

I have been running PMS on a Windows Server box for many years now. It has generally worked very well, surviving hardware migrations and OS upgrades with few issues. I don’t use Docker or anything similar, a pretty basic Windows setup.

Now I reset my account password because of the recent advisory and logged out of all instances. I have 2FA since a long time. Account seems to work fine after password reset but the server crashes soon after launch every time. I have not even been able to experience the issues with reclaiming my server that I read others posting about. Nothing else has changed on the machine, software or hardware. During the time between launch and crash, if I visit http://localhost:32400/web I can see this:

<Response code="503" title="Maintenance" status="Plex Media Server is currently running startup maintenance tasks." data-google-analytics-opt-out=""/>

I have tried some things:

rebooting the OS
moving back to latest, and latest before that, versions of com.plexapp.plugins.library.db and com.plexapp.plugins.library.blobs.db
updating to latest beta PMS version (and then moving back to the last stable again, since it did not help)
removing the account information keys from the registry at HKEY_CURRENT_USER\Software\Plex, Inc.\Plex Media Server
checked that no drive is running out of space
looking at the logs/dumps myself and trying to find out what the problem is
found that event viewer shows an Event ID 1000 Application Error when PMS crashes.

Nothing of the above improved the situation.

How should I proceed? I am quite desperate for help at this time.

The crashes generate these log/dump files which I have, but I’m not sure what is safe to post publicly:

Plex Media Server\Logs - Plex Media Server.log and Plex Tuner Service.log (I don’t use the tuner service AFAIK so perhaps this is some clue?!)
Plex Media Server\Crash Reports\1.42.1.10060-4e8b05daf\PLEX MEDIA SERVER - .log (a version of the log file from above, but cut off a bit earlier) and .dmp (≈250KB) files
AppData\Local\CrashDumps - Plex Media Server.exe.???.dmp (≈16GB)

Errors in the latest log files:

Sep 11, 2025 11:42:30.850 [17668] ERROR - Grabber: timeout waiting for Plex Tuner Service to finish starting.
Sep 11, 2025 11:43:30.868 [17308] ERROR - BufferingLineReader: failed to read line (error: -2)
Sep 11, 2025 11:43:30.868 [17308] ERROR - Timeout or error reading status line from plug-in pipe [com.plexapp.system], we’re killing it.

Sep 11, 2025 11:43:30.869 [17308] ERROR - Error received reading configuration for com.plexapp.system

Sep 11, 2025 11:43:31.132 [17176] ERROR - MyPlex: Error 401 requesting JSON from: https://plex.tv/api/v2/server/users/features

End of log file:

Sep 11, 2025 11:43:30.911 [1452] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Sep 11, 2025 11:43:31.017 [17684] WARN - Crash: Crash reporting disabled
Sep 11, 2025 11:43:31.024 [16116] WARN - [DatabaseFixups/class DatabaseFixupsSyncCollections] Held transaction for too long (D:\gha\plex-media-server\plex-media-server\Library\MetadataCollection.cpp:522): 0.187500 seconds
Sep 11, 2025 11:43:31.028 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 6 in 131ms.
Sep 11, 2025 11:43:31.040 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 6 in 12ms.
Sep 11, 2025 11:43:31.042 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 1 collections tags in section 7.
Sep 11, 2025 11:43:31.042 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 1 collections in the library already.
Sep 11, 2025 11:43:31.042 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 7 in 1ms.
Sep 11, 2025 11:43:31.043 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 7.
Sep 11, 2025 11:43:31.043 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 11, 2025 11:43:31.043 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 7 in 2ms.
Sep 11, 2025 11:43:31.044 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 7.
Sep 11, 2025 11:43:31.044 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 11, 2025 11:43:31.044 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 7 in 3ms.
Sep 11, 2025 11:43:31.049 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 7 in 5ms.
Sep 11, 2025 11:43:31.052 [17440] DEBUG - [HttpClient/HCl#7] HTTP/1.1 (0.2s) 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=
Sep 11, 2025 11:43:31.053 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 7 in 3ms.
Sep 11, 2025 11:43:31.054 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 8.
Sep 11, 2025 11:43:31.054 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 1 collections in the library already.
Sep 11, 2025 11:43:31.054 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 8 in 1ms.
Sep 11, 2025 11:43:31.055 [17440] DEBUG - [HttpClient/HCl#6] HTTP/1.1 (0.2s) 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=
Sep 11, 2025 11:43:31.057 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 8 in 2ms.
Sep 11, 2025 11:43:31.060 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 8 in 3ms.
Sep 11, 2025 11:43:31.061 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 9.
Sep 11, 2025 11:43:31.061 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 11, 2025 11:43:31.061 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 9 in 1ms.
Sep 11, 2025 11:43:31.062 [17440] DEBUG - [HttpClient/HCl#8] HTTP/1.1 (0.2s) 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=
Sep 11, 2025 11:43:31.064 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 9 in 2ms.
Sep 11, 2025 11:43:31.065 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 9 in 1ms.
Sep 11, 2025 11:43:31.066 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 10.
Sep 11, 2025 11:43:31.066 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 11, 2025 11:43:31.066 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 10 in 1ms.
Sep 11, 2025 11:43:31.069 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 10 in 2ms.
Sep 11, 2025 11:43:31.071 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 10 in 1ms.
Sep 11, 2025 11:43:31.071 [17440] DEBUG - [HttpClient/HCl#5] HTTP/1.1 (0.2s) 201 response from POST https://plex.tv/api/v2/pins?strong=true
Sep 11, 2025 11:43:31.072 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 11.
Sep 11, 2025 11:43:31.072 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 11, 2025 11:43:31.072 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 11 in 1ms.
Sep 11, 2025 11:43:31.075 [3092] DEBUG - [HCl#9] HTTP requesting GET https://plex.tv/api/v2/server/users/features
Sep 11, 2025 11:43:31.075 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 11 in 2ms.
Sep 11, 2025 11:43:31.077 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 11 in 1ms.
Sep 11, 2025 11:43:31.078 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 12.
Sep 11, 2025 11:43:31.078 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 11, 2025 11:43:31.078 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 12 in 1ms.
Sep 11, 2025 11:43:31.081 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 12 in 2ms.
Sep 11, 2025 11:43:31.081 [17440] DEBUG - [HttpClient/HCl#4] HTTP/1.1 (0.2s) 201 response from POST https://plex.tv/api/v2/pins?strong=true
Sep 11, 2025 11:43:31.082 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 12 in 1ms.
Sep 11, 2025 11:43:31.083 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 13.
Sep 11, 2025 11:43:31.084 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 11, 2025 11:43:31.084 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 13 in 1ms.
Sep 11, 2025 11:43:31.086 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 13 in 2ms.
Sep 11, 2025 11:43:31.087 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 13 in 1ms.
Sep 11, 2025 11:43:31.087 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Fixup completed
Sep 11, 2025 11:43:31.087 [16116] DEBUG - [DatabaseFixups/class DatabaseFixupsCleanupActivities] Executing fixup
Sep 11, 2025 11:43:31.092 [17440] DEBUG - [HttpClient/HCl#3] HTTP/1.1 (0.2s) 200 response from GET https://v4.plex.tv/pms/:/ip
Sep 11, 2025 11:43:31.127 [17440] DEBUG - [HttpClient/HCl#9] HTTP/1.1 (0.1s) 401 response from GET https://plex.tv/api/v2/server/users/features (reused)
Sep 11, 2025 11:43:31.128 [17668] DEBUG - PublicAddressManager: Got public IP from v4.plex.tv: [external IP address]
Sep 11, 2025 11:43:31.132 [17176] ERROR - MyPlex: Error 401 requesting JSON from: https://plex.tv/api/v2/server/users/features
Sep 11, 2025 11:43:31.132 [3092] WARN - FeatureManager: Couldn't get features. Trying again soon.
Sep 11, 2025 11:43:33.946 [1452] DEBUG - [MediaProviderManager] Refreshing media providers
Sep 11, 2025 11:43:33.986 [1452] DEBUG - [MediaProviderManager] we had 1 cloud providers online, we now have 1
Sep 11, 2025 11:43:33.986 [1452] DEBUG - [MediaProviderManager] cloud provider (Metadata) is online and available
Sep 11, 2025 11:43:33.986 [1452] DEBUG - [MediaProviderManager] Media provider refresh complete

(The line with “D:\gha\plex-media-server\plex-media-server\Library\MetadataCollection.cpp” seems weird since this is absolutely not a valid path on my system, but I guess it is just some remnant of how PMS was built.)

Event Viewer data:

- <Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
- <System>
  <Provider Name="Application Error" /> 
  <EventID Qualifiers="0">1000</EventID> 
  <Level>2</Level> 
  <Task>100</Task> 
  <Keywords>0x80000000000000</Keywords> 
  <TimeCreated SystemTime="2025-09-11T09:29:24.576819000Z" /> 
  <EventRecordID>301311</EventRecordID> 
  <Channel>Application</Channel> 
  <Computer>[hidden]</Computer> 
  <Security /> 
  </System>
- <EventData>
  <Data>Plex Media Server.exe</Data> 
  <Data>1.42.1.10060</Data> 
  <Data>6899ad57</Data> 
  <Data>Plex Media Server.exe</Data> 
  <Data>1.42.1.10060</Data> 
  <Data>6899ad57</Data> 
  <Data>c0000005</Data> 
  <Data>0000000000af68da</Data> 
  <Data>40a0</Data> 
  <Data>01dc22fe241aedc2</Data> 
  <Data>C:\Program Files\Plex\Plex Media Server\Plex Media Server.exe</Data> 
  <Data>C:\Program Files\Plex\Plex Media Server\Plex Media Server.exe</Data> 
  <Data>8b111710-a4dc-4a10-90e2-4610a2b02cf0</Data> 
  <Data /> 
  <Data /> 
  </EventData>
  </Event>

KJAB · September 14, 2025, 6:53pm

So I have tried some more things, including trying an even earlier version of PMS (1.42.1.10054-f333bdaa8), even earlier db backups, updating GPU drivers, disabling everything non-OS else going on at the server.

Please, if you can help me get further in pinning down the issue. The errors that are in the logs, are they meaningful at all or am I wasting my time on them? I have googled them obviously, but it didn’t give any really useful results.

OttoKerner · September 15, 2025, 9:31am

The first log file from above was probably generated while your account was blocked on plex.tv, due to a too high number of account-related actions within a short time.

verify that drive C: has plenty of free space. It should provide at least 3x the free space as the size of your plex database files together
How large is your com.plexapp.plugins.library.db at the moment?
have you tried this batch file? DBRepair/Windows at master · ChuckPa/DBRepair · GitHub

KJAB · September 15, 2025, 4:30pm

Thanks for your response and help!

I was not aware that there could be such a block at plex.tv. What was the clue in the log to tell you that? I have now made a new attempt to launch PMS, it was 21 hours since last attempt, see the log below. Errors seem to be the same as before.

Drive C has 26.9 GB free. LocalAppDataPath is on this drive. ButlerDatabaseBackupPath and TranscoderTempDirectory are on two other separate drives, 1.41 TB and 70.5 GB free respectively.
com.plexapp.plugins.library.db is 208 073 KB, com.plexapp.plugins.library.blobs.db is 287 976 KB, previous backups (from before any issues) have very similar sizes.
I have used the DB repair tool before, but not (yet) this time, since I couldn’t see any sign of errors with the DBs. Downloaded the latest version and ran it now, see log results below. It finished without any errors or indiciations of repairs. Attempting to start PMS afterwards failed as before.

Output from DBRepair-1.11.09 “auto” command:

Enter command # -or- command name (4 char min): 2
Automatic Check,Repair,Index started.
Exporting Main DB
Exporting Blobs DB
Successfully exported the main and blobs databases. Proceeding to import into new database.
Importing Main DB.
Importing Blobs DB.
Successfully imported databases.
Verifying databases integrity after importing.
Main DB verification check is: ok
Verification complete. PMS main database is OK.
Blobs DB verification check is: ok
Verification complete. PMS blobs database is OK.
Reindexing Main DB
Reindexing Blobs DB
Reindexing complete.
Moving current DBs to DBTMP and making new databases active
Automatic Check,Repair,Index succeeded.

Complete log from new PMS launch attempt:

Sep 15, 2025 17:53:31.646 [13988] INFO - Plex Media Server v1.42.1.10060-4e8b05daf - Intel(R) Client Systems NUC11TNHi5 x64-x64 - build: windows-x86_64 windows - GMT 02:00
Sep 15, 2025 17:53:31.646 [13988] INFO - Windows version: 10.0 (Build 17763), language en-US
Sep 15, 2025 17:53:31.646 [13988] INFO - 8 2419 MHz processor(s): Architecture=9, Level=6, Revision=35841 Processor Identifier=Intel64 Family 6 Model 140 Stepping 1, GenuineIntel
Sep 15, 2025 17:53:31.646 [13988] INFO - Compiler is - MSVC 1929
Sep 15, 2025 17:53:31.646 [13988] DEBUG - "C:\Program Files\Plex\Plex Media Server\Plex Media Server.exe" 
Sep 15, 2025 17:53:31.667 [18164] DEBUG - Plex for Windows Store application is not installed
Sep 15, 2025 17:53:31.668 [18164] DEBUG - BPQ: [Idle] -> [Starting]
Sep 15, 2025 17:53:31.679 [18164] DEBUG - FeatureManager: Using cached data for features list
Sep 15, 2025 17:53:31.689 [18164] DEBUG - MyPlex: mapping state set to 'Unknown'.
Sep 15, 2025 17:53:31.702 [18164] DEBUG - Relay: read 25 cached entries from hosts file
Sep 15, 2025 17:53:31.703 [18164] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.39.4, threadsafe=1
Sep 15, 2025 17:53:31.719 [18164] INFO - SQLITE3:0x2, 283, recovered 4 frames from WAL file C:\PlexMeta\Plex Media Server\Plug-in Support\Databases\com.plexapp.plugins.library.db-wal
Sep 15, 2025 17:53:31.759 [18164] DEBUG - MyPlex: using cached data for request for https://plex.tv/api/v2/server/users/features
Sep 15, 2025 17:53:31.914 [18164] DEBUG - HttpServer: Listening on IPv6 as well as IPv4.
Sep 15, 2025 17:53:31.915 [18164] DEBUG - HttpServer: Listening on port 32400.
Sep 15, 2025 17:53:31.915 [18164] DEBUG - HttpServer: Listening on port 32401.
Sep 15, 2025 17:53:31.915 [18164] DEBUG - Running server...
Sep 15, 2025 17:53:31.915 [9980] DEBUG - HttpServer: Set up a thread pool with 2 threads.
Sep 15, 2025 17:53:31.916 [18164] INFO - Running migrations. (EPG 0)
Sep 15, 2025 17:53:31.916 [18164] DEBUG - Captured session 0.
Sep 15, 2025 17:53:31.916 [18164] DEBUG - Captured session 1.
Sep 15, 2025 17:53:31.916 [18164] DEBUG - Captured session 2.
Sep 15, 2025 17:53:31.916 [18164] DEBUG - Captured session 3.
Sep 15, 2025 17:53:31.916 [18164] DEBUG - Captured session 4.
Sep 15, 2025 17:53:31.916 [18164] DEBUG - Captured session 5.
Sep 15, 2025 17:53:31.916 [18164] DEBUG - Captured session 6.
Sep 15, 2025 17:53:31.916 [18164] DEBUG - Captured session 7.
Sep 15, 2025 17:53:31.916 [18164] DEBUG - Captured session 8.
Sep 15, 2025 17:53:31.916 [18164] DEBUG - Captured session 9.
Sep 15, 2025 17:53:31.916 [18164] DEBUG - Captured session 10.
Sep 15, 2025 17:53:31.916 [18164] DEBUG - Captured session 11.
Sep 15, 2025 17:53:31.916 [18164] DEBUG - Captured session 12.
Sep 15, 2025 17:53:31.916 [18164] DEBUG - Captured session 13.
Sep 15, 2025 17:53:31.916 [18164] DEBUG - Captured session 14.
Sep 15, 2025 17:53:31.916 [18164] DEBUG - Captured session 15.
Sep 15, 2025 17:53:31.916 [18164] DEBUG - Captured session 16.
Sep 15, 2025 17:53:31.916 [18164] DEBUG - Captured session 17.
Sep 15, 2025 17:53:31.916 [18164] DEBUG - Captured session 18.
Sep 15, 2025 17:53:31.916 [18164] DEBUG - Captured session 19.
Sep 15, 2025 17:53:31.961 [18164] DEBUG - ChangestampAllocator: initialized to 3734102
Sep 15, 2025 17:53:31.998 [18164] DEBUG - Opening 2 database sessions to library (com.plexapp.plugins.library.blobs), SQLite 3.39.4, threadsafe=1
Sep 15, 2025 17:53:32.010 [18164] INFO - Running migrations. (EPG 0)
Sep 15, 2025 17:53:32.010 [18164] DEBUG - Captured session 0.
Sep 15, 2025 17:53:32.010 [18164] DEBUG - Captured session 1.
Sep 15, 2025 17:53:32.677 [17836] DEBUG - [GPU] Got device: Intel(R) Iris(R) Xe Graphics, intel@unknown, default true, best true, ID 8086:9a49:8086:3002, DevID [8086:9a49:8086:3002], flags 0xbaf6
Sep 15, 2025 17:53:32.678 [17836] DEBUG - [GPU] Cuda library was not found on the system
Sep 15, 2025 17:53:33.047 [17836] DEBUG - Grabber: Cleaning up orphaned grabs.
Sep 15, 2025 17:53:33.047 [18164] DEBUG - Media Provider: Registering provider com.plexapp.plugins.library
Sep 15, 2025 17:53:33.047 [18164] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Sep 15, 2025 17:53:33.048 [1440] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Sep 15, 2025 17:53:33.048 [18164] DEBUG - Opening 1 database sessions to library (), SQLite 3.39.4, threadsafe=1
Sep 15, 2025 17:53:33.048 [17836] DEBUG - Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
Sep 15, 2025 17:53:33.049 [16212] DEBUG - File "C:\Program Files\Plex\Plex Media Server\Resources\Plug-ins-4e8b05daf" changed: -1 => 2025-09-15 17:53:04 (1757951584).
Sep 15, 2025 17:53:33.049 [16212] DEBUG - File "C:\PlexMeta\Plex Media Server\Plug-ins" changed: -1 => 2022-03-28 20:07:24 (1648490844).
Sep 15, 2025 17:53:33.049 [16212] DEBUG - Scanning for plug-ins in "C:\Program Files\Plex\Plex Media Server\Resources\Plug-ins-4e8b05daf"
Sep 15, 2025 17:53:33.199 [16212] DEBUG - Scanning for plug-ins in "C:\PlexMeta\Plex Media Server\Plug-ins"
Sep 15, 2025 17:53:33.199 [16212] DEBUG - PluginRepository::setStartState: 1, startingSystem
Sep 15, 2025 17:53:33.199 [16212] DEBUG - Starting plug-in C:\Program Files\Plex\Plex Media Server\Resources\Plug-ins-4e8b05daf\System.bundle.
Sep 15, 2025 17:53:33.199 [16212] DEBUG - [com.plexapp.system] Setting plug-in to always running (daemon mode).
Sep 15, 2025 17:53:33.200 [16212] DEBUG - Starting file watcher for com.plexapp.system
Sep 15, 2025 17:53:33.200 [16212] DEBUG - Plugin: setting environment variable: 'PYTHONHOME=C:\Program Files\Plex\Plex Media Server'
Sep 15, 2025 17:53:33.200 [16212] DEBUG - Plugin: setting environment variable: 'PYTHONPATH=C:\Program Files\Plex\Plex Media Server\Resources\Python\python27.zip;C:\Program Files\Plex\Plex Media Server\Resources\Python\lib\python2.7\site-packages'
Sep 15, 2025 17:53:33.200 [16212] DEBUG - Plugin::createProcess: Creating plugin process: "C:\Program Files\Plex\Plex Media Server\PlexScriptHost.exe" "C:\Program Files\Plex\Plex Media Server\Resources\Plug-ins-4e8b05daf\Framework.bundle\Contents\Resources\Versions\2\Python\bootstrap.py" "C:\Program Files\Plex\Plex Media Server\Resources\Plug-ins-4e8b05daf\System.bundle"
Sep 15, 2025 17:53:33.230 [16212] DEBUG - Spawned plug-in com.plexapp.system with PID 17980
Sep 15, 2025 17:53:33.230 [16212] DEBUG - [com.plexapp.system] Sending command: GET /:/prefixes

Sep 15, 2025 17:53:33.644 [17836] WARN - Crash: Crash reporting disabled
Sep 15, 2025 17:53:36.048 [17836] DEBUG - [MediaProviderManager] Refreshing media providers
Sep 15, 2025 17:53:36.325 [17836] DEBUG - [MediaProviderManager] we had 0 cloud providers online, we now have 1
Sep 15, 2025 17:53:36.325 [17836] DEBUG - [MediaProviderManager] cloud provider (Metadata) is online and available
Sep 15, 2025 17:53:36.325 [17836] DEBUG - [MediaProviderManager/MetadataAgentManager] [tv.plex.agents.movie] provider added to map
Sep 15, 2025 17:53:36.325 [17836] DEBUG - [MediaProviderManager/MetadataAgentManager] [tv.plex.agents.series] provider added to map
Sep 15, 2025 17:53:36.325 [17836] DEBUG - [MediaProviderManager/MetadataAgentManager] [tv.plex.agents.music] provider added to map
Sep 15, 2025 17:53:36.325 [17836] DEBUG - [MediaProviderManager/MetadataAgentManager] [org.musicbrainz.agents.music] provider added to map
Sep 15, 2025 17:53:36.325 [17836] DEBUG - [MediaProviderManager] Media provider refresh complete
Sep 15, 2025 17:53:43.059 [17836] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 17:53:53.073 [17836] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 17:54:03.081 [12660] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 17:54:13.093 [12660] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 17:54:23.105 [12660] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 17:54:33.116 [12660] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 17:54:43.116 [12660] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 17:54:53.124 [12660] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 17:55:03.137 [12660] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 17:55:13.148 [12660] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 17:55:23.159 [12660] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 17:55:31.684 [6532] DEBUG - BPQ: [Starting] -> [Processing]
Sep 15, 2025 17:55:31.685 [6532] DEBUG - BPQ: generating queue items from 2 generator(s)
Sep 15, 2025 17:55:31.687 [6532] DEBUG - BPQ: generated 1 item(s) for queue
Sep 15, 2025 17:55:31.687 [6532] DEBUG - PlayQueue: Added 1 items in 0.0 sec.
Sep 15, 2025 17:55:31.687 [6532] DEBUG - PlayQueue: total generated: -1, before cursor: 0, after cursor: 0
Sep 15, 2025 17:55:31.687 [6532] DEBUG - PlayQueue: Inserting 0 new items, starting at 0.000000.
Sep 15, 2025 17:55:31.687 [6532] DEBUG - BPQ: [Processing] -> [Paused]
Sep 15, 2025 17:55:33.171 [12660] ERROR - Grabber: timeout waiting for Plex Tuner Service to finish starting.
Sep 15, 2025 17:56:33.232 [16212] ERROR - BufferingLineReader: failed to read line (error: -2)
Sep 15, 2025 17:56:33.232 [16212] ERROR - Timeout or error reading status line from plug-in pipe [com.plexapp.system], we're killing it.
Sep 15, 2025 17:56:33.232 [16212] DEBUG - Stopping [com.plexapp.system].
Sep 15, 2025 17:56:33.232 [16212] DEBUG - [com.plexapp.system] Killing.
Sep 15, 2025 17:56:33.232 [16212] ERROR - Error received reading configuration for com.plexapp.system
Sep 15, 2025 17:56:33.232 [16212] DEBUG - Stopping [com.plexapp.system].
Sep 15, 2025 17:56:33.232 [16212] DEBUG - Done with 1 synchronous plug-in starts, starting the rest in parallel.
Sep 15, 2025 17:56:33.232 [16212] DEBUG - PluginRepository::setStartState: 2, startingPlugins
Sep 15, 2025 17:56:33.232 [16212] DEBUG - Updating the list of agents known by the system.
Sep 15, 2025 17:56:33.232 [16212] DEBUG - Starting file watcher for com.plexapp.system
Sep 15, 2025 17:56:33.232 [16212] DEBUG - Plugin: setting environment variable: 'PYTHONHOME=C:\Program Files\Plex\Plex Media Server'
Sep 15, 2025 17:56:33.232 [16212] DEBUG - Plugin: setting environment variable: 'PYTHONPATH=C:\Program Files\Plex\Plex Media Server\Resources\Python\python27.zip;C:\Program Files\Plex\Plex Media Server\Resources\Python\lib\python2.7\site-packages'
Sep 15, 2025 17:56:33.232 [16212] DEBUG - Plugin::createProcess: Creating plugin process: "C:\Program Files\Plex\Plex Media Server\PlexScriptHost.exe" "C:\Program Files\Plex\Plex Media Server\Resources\Plug-ins-4e8b05daf\Framework.bundle\Contents\Resources\Versions\2\Python\bootstrap.py" "C:\Program Files\Plex\Plex Media Server\Resources\Plug-ins-4e8b05daf\System.bundle"
Sep 15, 2025 17:56:33.232 [18164] DEBUG - Image transcode cache directory: "C:\PlexMeta\Plex Media Server\Cache\PhotoTranscoder"
Sep 15, 2025 17:56:33.233 [18164] DEBUG - Transcoder: Cleaning old transcode directories.
Sep 15, 2025 17:56:33.234 [18164] DEBUG - MyPlex: start public ip check and mapping - current mapped state: 'Unknown'.
Sep 15, 2025 17:56:33.234 [18164] DEBUG - MyPlex: mapping state set to 'Not Mapped'.
Sep 15, 2025 17:56:33.234 [18164] DEBUG - PublicAddressManager: Starting.
Sep 15, 2025 17:56:33.234 [18164] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Sep 15, 2025 17:56:33.234 [12660] DEBUG - PublicAddressManager: Obtaining public IP.
Sep 15, 2025 17:56:33.234 [12660] DEBUG - [HCl#3] HTTP requesting GET https://v4.plex.tv/pms/:/ip
Sep 15, 2025 17:56:33.234 [16212] DEBUG - Spawned plug-in com.plexapp.system with PID 13444
Sep 15, 2025 17:56:33.234 [16212] DEBUG - [com.plexapp.system] Sending command: GET /:/prefixes

Sep 15, 2025 17:56:33.234 [18960] DEBUG - [HCl#4] HTTP requesting POST https://plex.tv/api/v2/pins?strong=true
Sep 15, 2025 17:56:33.234 [4840] DEBUG - [HCl#5] HTTP requesting POST https://plex.tv/api/v2/pins?strong=true
Sep 15, 2025 17:56:33.269 [18164] DEBUG - NetworkInterface: Notified of network changed (force=0)
Sep 15, 2025 17:56:33.272 [18164] DEBUG - NetworkInterface: ignoring adapter 'Loopback Pseudo-Interface 1' (::1,127.0.0.1) because it has no gateway
Sep 15, 2025 17:56:33.273 [18164] DEBUG - Detected primary interface: 192.168.1.202
Sep 15, 2025 17:56:33.273 [18164] DEBUG - Network interfaces:
Sep 15, 2025 17:56:33.273 [18164] DEBUG -  * 15 Ethernet {D8EF3362-2741-47D4-B632-290C26773BF7} (192.168.1.202) (48-21-0B-26-19-51) (loopback: 0)
Sep 15, 2025 17:56:33.273 [18164] DEBUG - Creating NetworkServices singleton.
Sep 15, 2025 17:56:33.273 [18164] DEBUG - NetworkServices: Initializing...
Sep 15, 2025 17:56:33.273 [18164] DEBUG - NetworkService: Creating new service.
Sep 15, 2025 17:56:33.273 [18164] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Sep 15, 2025 17:56:33.273 [18164] DEBUG - NetworkService: Quick dispatch of network change.
Sep 15, 2025 17:56:33.273 [18164] DEBUG - NetworkService: Creating new service.
Sep 15, 2025 17:56:33.273 [18164] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Sep 15, 2025 17:56:33.273 [18164] DEBUG - NetworkService: Quick dispatch of network change.
Sep 15, 2025 17:56:33.273 [18384] DEBUG - Network change for advertiser.
Sep 15, 2025 17:56:33.273 [18164] DEBUG - NetworkService: Creating new service.
Sep 15, 2025 17:56:33.273 [18164] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Sep 15, 2025 17:56:33.273 [18164] DEBUG - NetworkService: Quick dispatch of network change.
Sep 15, 2025 17:56:33.273 [18164] DEBUG - NetworkService: Creating new service.
Sep 15, 2025 17:56:33.273 [18164] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Sep 15, 2025 17:56:33.273 [18164] DEBUG - NetworkService: Quick dispatch of network change.
Sep 15, 2025 17:56:33.273 [18164] DEBUG - NetworkService: Creating new service.
Sep 15, 2025 17:56:33.273 [18384] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32414
Sep 15, 2025 17:56:33.273 [18164] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Sep 15, 2025 17:56:33.273 [18164] DEBUG - NetworkService: Quick dispatch of network change.
Sep 15, 2025 17:56:33.273 [18164] DEBUG - NetworkService: Creating new service.
Sep 15, 2025 17:56:33.273 [18164] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Sep 15, 2025 17:56:33.273 [18164] DEBUG - NetworkService: Quick dispatch of network change.
Sep 15, 2025 17:56:33.274 [18384] DEBUG - Network change for advertiser.
Sep 15, 2025 17:56:33.274 [18384] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32410
Sep 15, 2025 17:56:33.274 [18384] DEBUG - Network change for advertiser.
Sep 15, 2025 17:56:33.274 [18384] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32412
Sep 15, 2025 17:56:33.274 [18384] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Sep 15, 2025 17:56:33.274 [18384] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32413
Sep 15, 2025 17:56:33.274 [18384] DEBUG - NetworkService: Browsing on interface 192.168.1.202 on broadcast address 192.168.1.255 (index: 0)
Sep 15, 2025 17:56:33.274 [18384] DEBUG - NetworkService: Network change for browser (polled=1), closing 0 browse sockets.
Sep 15, 2025 17:56:33.274 [18384] DEBUG - NetworkService: Browsing on interface 192.168.1.202 on broadcast address 192.168.1.255 (index: 0)
Sep 15, 2025 17:56:33.274 [18384] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Sep 15, 2025 17:56:33.275 [18384] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:1901
Sep 15, 2025 17:56:33.275 [18384] DEBUG - NetworkService: Browsing on interface 192.168.1.202 on broadcast address 239.255.255.250 (index: 0)
Sep 15, 2025 17:56:33.276 [8096] DEBUG - [ChildProcessMonitor] Plex Tuner Service.exe starting.
Sep 15, 2025 17:56:33.276 [10692] DEBUG - [DatabaseFixups] Running fix-ups.
Sep 15, 2025 17:56:33.276 [18164] DEBUG - Waiting for server condition to be signaled.
Sep 15, 2025 17:56:33.276 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsCleanupRetroGames] Executing fixup
Sep 15, 2025 17:56:33.276 [18676] DEBUG - [HCl#6] HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=
Sep 15, 2025 17:56:33.276 [12108] DEBUG - [HCl#7] HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=
Sep 15, 2025 17:56:33.276 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsCleanupRetroGames] Fixup completed
Sep 15, 2025 17:56:33.276 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsEnsureLibrarySectionUuids] Executing fixup
Sep 15, 2025 17:56:33.276 [8096] DEBUG - [ChildProcessMonitor/JobRunner] Job running: "C:\Program Files\Plex\Plex Media Server\Plex Tuner Service.exe" "C:\Program Files\Plex\Plex Media Server\Resources\Tuner\Private" "C:\Program Files\Plex\Plex Media Server\Resources\Tuner\Shared" 1.42.1.10060-4e8b05daf 32600
Sep 15, 2025 17:56:33.276 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsEnsureLibrarySectionUuids] Fixup completed
Sep 15, 2025 17:56:33.276 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsEnsureMetadataItemHashes] Executing fixup
Sep 15, 2025 17:56:33.276 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsEnsureMetadataItemHashes] Fixup completed
Sep 15, 2025 17:56:33.276 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSetDefaultAccountLanguage] Executing fixup
Sep 15, 2025 17:56:33.276 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSetDefaultAccountLanguage] Fixup completed
Sep 15, 2025 17:56:33.276 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsCreateDefaultMediaProcessingTargetTags] Executing fixup
Sep 15, 2025 17:56:33.276 [8312] DEBUG - [HCl#8] HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=
Sep 15, 2025 17:56:33.276 [18984] DEBUG - DVR: Starting up.
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale af
Sep 15, 2025 17:56:33.277 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsCreateDefaultMediaProcessingTargetTags] Fixup completed
Sep 15, 2025 17:56:33.277 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsUpdateAutomaticLibraryFolders] Executing fixup
Sep 15, 2025 17:56:33.277 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsUpdateAutomaticLibraryFolders] Fixup completed
Sep 15, 2025 17:56:33.277 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Executing fixup
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale ar
Sep 15, 2025 17:56:33.277 [18984] DEBUG - Activity: registered new activity fa523f72-8b87-485b-83f7-8047f732b7cf - "Processing subscriptions"
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale cs_CZ
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale da
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale de
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale en
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale es
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale et_EE
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale fi
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale fr
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale he
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale hr_HR
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale hu
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale hu_HU
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale it
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale ja_JP
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale ko
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale lt
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale nl
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale nl_BE
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale no
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale pl
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale pt
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale pt_BR
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale ro
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale ru
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale sk
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale sl
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale sr
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale sv
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale uk
Sep 15, 2025 17:56:33.277 [12776] DEBUG - Translator: Found locale zh_CN
Sep 15, 2025 17:56:33.285 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 131 collections tags in section 6.
Sep 15, 2025 17:56:33.287 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 137 collections in the library already.
Sep 15, 2025 17:56:33.287 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 6 in 9ms.
Sep 15, 2025 17:56:33.301 [12776] DEBUG - Found session GUID of 4fbd0588-7ddf-4bc0-980f-3580fc8ae6f2 in session start.
Sep 15, 2025 17:56:33.382 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 6 in 94ms.
Sep 15, 2025 17:56:33.392 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 6 in 10ms.
Sep 15, 2025 17:56:33.393 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 1 collections tags in section 7.
Sep 15, 2025 17:56:33.393 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 1 collections in the library already.
Sep 15, 2025 17:56:33.393 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 7 in 0ms.
Sep 15, 2025 17:56:33.393 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 7.
Sep 15, 2025 17:56:33.393 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 15, 2025 17:56:33.393 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 7 in 1ms.
Sep 15, 2025 17:56:33.394 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 7.
Sep 15, 2025 17:56:33.394 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 15, 2025 17:56:33.394 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 7 in 1ms.
Sep 15, 2025 17:56:33.397 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 7 in 3ms.
Sep 15, 2025 17:56:33.399 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 7 in 1ms.
Sep 15, 2025 17:56:33.399 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 8.
Sep 15, 2025 17:56:33.400 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 1 collections in the library already.
Sep 15, 2025 17:56:33.400 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 8 in 0ms.
Sep 15, 2025 17:56:33.401 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 8 in 1ms.
Sep 15, 2025 17:56:33.403 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 8 in 1ms.
Sep 15, 2025 17:56:33.403 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 9.
Sep 15, 2025 17:56:33.403 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 15, 2025 17:56:33.403 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 9 in 0ms.
Sep 15, 2025 17:56:33.404 [4652] DEBUG - [HttpClient/HCl#5] HTTP/1.1 (0.2s) 201 response from POST https://plex.tv/api/v2/pins?strong=true
Sep 15, 2025 17:56:33.406 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 9 in 2ms.
Sep 15, 2025 17:56:33.408 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 9 in 2ms.
Sep 15, 2025 17:56:33.409 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 10.
Sep 15, 2025 17:56:33.409 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 15, 2025 17:56:33.409 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 10 in 1ms.
Sep 15, 2025 17:56:33.412 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 10 in 2ms.
Sep 15, 2025 17:56:33.413 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 10 in 1ms.
Sep 15, 2025 17:56:33.414 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 11.
Sep 15, 2025 17:56:33.415 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 15, 2025 17:56:33.415 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 11 in 1ms.
Sep 15, 2025 17:56:33.418 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 11 in 3ms.
Sep 15, 2025 17:56:33.420 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 11 in 1ms.
Sep 15, 2025 17:56:33.420 [4652] DEBUG - [HttpClient/HCl#4] HTTP/1.1 (0.2s) 201 response from POST https://plex.tv/api/v2/pins?strong=true
Sep 15, 2025 17:56:33.421 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 12.
Sep 15, 2025 17:56:33.421 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 15, 2025 17:56:33.421 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 12 in 1ms.
Sep 15, 2025 17:56:33.424 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 12 in 2ms.
Sep 15, 2025 17:56:33.425 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 12 in 1ms.
Sep 15, 2025 17:56:33.426 [13196] WARN - Crash: Crash reporting disabled
Sep 15, 2025 17:56:33.427 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 13.
Sep 15, 2025 17:56:33.427 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 15, 2025 17:56:33.427 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 13 in 1ms.
Sep 15, 2025 17:56:33.428 [8096] DEBUG - [ChildProcessMonitor/JobRunner] Jobs: Starting child process with pid 10784
Sep 15, 2025 17:56:33.430 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 13 in 2ms.
Sep 15, 2025 17:56:33.430 [4652] DEBUG - [HttpClient/HCl#6] HTTP/1.1 (0.2s) 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=
Sep 15, 2025 17:56:33.433 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 13 in 3ms.
Sep 15, 2025 17:56:33.433 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Fixup completed
Sep 15, 2025 17:56:33.433 [10692] DEBUG - [DatabaseFixups/class DatabaseFixupsCleanupActivities] Executing fixup
Sep 15, 2025 17:56:33.435 [4652] DEBUG - [HttpClient/HCl#7] HTTP/1.1 (0.2s) 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=
Sep 15, 2025 17:56:33.437 [4652] DEBUG - [HttpClient/HCl#8] HTTP/1.1 (0.2s) 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=
Sep 15, 2025 17:56:33.441 [4652] DEBUG - [HttpClient/HCl#3] HTTP/1.1 (0.2s) 200 response from GET https://v4.plex.tv/pms/:/ip
Sep 15, 2025 17:56:33.443 [12660] DEBUG - PublicAddressManager: Got public IP from v4.plex.tv: [my public IP address]
Sep 15, 2025 17:56:33.446 [8312] DEBUG - [HCl#9] HTTP requesting GET https://plex.tv/api/v2/server/users/features
Sep 15, 2025 17:56:33.447 [1220] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Sep 15, 2025 17:56:33.501 [4652] DEBUG - [HttpClient/HCl#9] HTTP/1.1 (0.1s) 401 response from GET https://plex.tv/api/v2/server/users/features (reused)
Sep 15, 2025 17:56:33.501 [18060] ERROR - MyPlex: Error 401 requesting JSON from: https://plex.tv/api/v2/server/users/features
Sep 15, 2025 17:56:33.501 [8312] WARN - FeatureManager: Couldn't get features. Trying again soon.

Plex Media Server.exe process dies very shortly after writing this last line. Windows generates a crash dump like before. Plex Update Service.exe keeps running.

OttoKerner · September 15, 2025, 4:55pm

Maybe reactivate this temporarily, in order to alert people at Plex that there might be something up.

It’s weird that this is th last action in the log

Sep 15, 2025 17:56:33.501 [4652] DEBUG - [HttpClient/HCl#9] HTTP/1.1 (0.1s) 401 response from GET https://plex.tv/api/v2/server/users/features (reused)
Sep 15, 2025 17:56:33.501 [18060] ERROR - MyPlex: Error 401 requesting JSON from: https://plex.tv/api/v2/server/users/features
Sep 15, 2025 17:56:33.501 [8312] WARN - FeatureManager: Couldn't get features. Trying again soon.

KJAB · September 15, 2025, 7:37pm

I agree about weird.

I ran with verbose logging on, in case there is something useful in that:

Sep 15, 2025 21:20:55.414 [1260] INFO - Plex Media Server v1.42.1.10060-4e8b05daf - Intel(R) Client Systems NUC11TNHi5 x64-x64 - build: windows-x86_64 windows - GMT 02:00
Sep 15, 2025 21:20:55.414 [1260] INFO - Windows version: 10.0 (Build 17763), language en-US
Sep 15, 2025 21:20:55.414 [1260] INFO - 8 2419 MHz processor(s): Architecture=9, Level=6, Revision=35841 Processor Identifier=Intel64 Family 6 Model 140 Stepping 1, GenuineIntel
Sep 15, 2025 21:20:55.414 [1260] INFO - Compiler is - MSVC 1929
Sep 15, 2025 21:20:55.414 [1260] DEBUG - "C:\Program Files\Plex\Plex Media Server\Plex Media Server.exe" 
Sep 15, 2025 21:20:55.411 [10940] DEBUG - Plex for Windows Store application is not installed
Sep 15, 2025 21:20:55.413 [10940] DEBUG - BPQ: [Idle] -> [Starting]
Sep 15, 2025 21:20:55.413 [10940] VERBOSE - BPQ: delaying processing 120 second(s)
Sep 15, 2025 21:20:55.415 [10940] DEBUG - FeatureManager: Using cached data for features list
Sep 15, 2025 21:20:55.418 [10940] DEBUG - MyPlex: mapping state set to 'Unknown'.
Sep 15, 2025 21:20:55.419 [10940] DEBUG - Relay: read 25 cached entries from hosts file
Sep 15, 2025 21:20:55.420 [10940] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.39.4, threadsafe=1
Sep 15, 2025 21:20:55.442 [10940] INFO - SQLITE3:0x2, 283, recovered 116 frames from WAL file C:\PlexMeta\Plex Media Server\Plug-in Support\Databases\com.plexapp.plugins.library.db-wal
Sep 15, 2025 21:20:55.476 [10940] DEBUG - MyPlex: using cached data for request for https://plex.tv/api/v2/server/users/features
Sep 15, 2025 21:20:55.604 [10940] DEBUG - HttpServer: Listening on IPv6 as well as IPv4.
Sep 15, 2025 21:20:55.604 [10940] DEBUG - HttpServer: Listening on port 32400.
Sep 15, 2025 21:20:55.605 [10940] DEBUG - HttpServer: Listening on port 32401.
Sep 15, 2025 21:20:55.605 [10940] DEBUG - Running server...
Sep 15, 2025 21:20:55.605 [18680] DEBUG - HttpServer: Set up a thread pool with 2 threads.
Sep 15, 2025 21:20:55.605 [10940] INFO - Running migrations. (EPG 0)
Sep 15, 2025 21:20:55.606 [10940] DEBUG - Captured session 0.
Sep 15, 2025 21:20:55.606 [10940] DEBUG - Captured session 1.
Sep 15, 2025 21:20:55.606 [10940] DEBUG - Captured session 2.
Sep 15, 2025 21:20:55.606 [10940] DEBUG - Captured session 3.
Sep 15, 2025 21:20:55.606 [10940] DEBUG - Captured session 4.
Sep 15, 2025 21:20:55.606 [10940] DEBUG - Captured session 5.
Sep 15, 2025 21:20:55.606 [10940] DEBUG - Captured session 6.
Sep 15, 2025 21:20:55.606 [10940] DEBUG - Captured session 7.
Sep 15, 2025 21:20:55.606 [10940] DEBUG - Captured session 8.
Sep 15, 2025 21:20:55.606 [10940] DEBUG - Captured session 9.
Sep 15, 2025 21:20:55.606 [10940] DEBUG - Captured session 10.
Sep 15, 2025 21:20:55.606 [10940] DEBUG - Captured session 11.
Sep 15, 2025 21:20:55.606 [10940] DEBUG - Captured session 12.
Sep 15, 2025 21:20:55.606 [10940] DEBUG - Captured session 13.
Sep 15, 2025 21:20:55.606 [10940] DEBUG - Captured session 14.
Sep 15, 2025 21:20:55.606 [10940] DEBUG - Captured session 15.
Sep 15, 2025 21:20:55.606 [10940] DEBUG - Captured session 16.
Sep 15, 2025 21:20:55.606 [10940] DEBUG - Captured session 17.
Sep 15, 2025 21:20:55.606 [10940] DEBUG - Captured session 18.
Sep 15, 2025 21:20:55.606 [10940] DEBUG - Captured session 19.
Sep 15, 2025 21:20:55.637 [10940] DEBUG - ChangestampAllocator: initialized to 3734102
Sep 15, 2025 21:20:55.657 [10940] DEBUG - Opening 2 database sessions to library (com.plexapp.plugins.library.blobs), SQLite 3.39.4, threadsafe=1
Sep 15, 2025 21:20:55.669 [10940] INFO - Running migrations. (EPG 0)
Sep 15, 2025 21:20:55.670 [10940] DEBUG - Captured session 0.
Sep 15, 2025 21:20:55.670 [10940] DEBUG - Captured session 1.
Sep 15, 2025 21:20:55.670 [10940] VERBOSE - [FFMPEG] - Rescanning for external libs: '\\\\?\\C\:\\PlexMeta\\Plex\ Media\ Server\\Codecs\\46f74ab-560174306fe167a5978a79dd-windows-x86_64\\'
Sep 15, 2025 21:20:55.670 [10940] VERBOSE - [FFMPEG] - Loading external lib \\?\C:\PlexMeta\Plex Media Server\Codecs\46f74ab-560174306fe167a5978a79dd-windows-x86_64\aac_decoder.dll
Sep 15, 2025 21:20:55.670 [10940] VERBOSE - [FFMPEG] - Loading external lib \\?\C:\PlexMeta\Plex Media Server\Codecs\46f74ab-560174306fe167a5978a79dd-windows-x86_64\ac3_decoder.dll
Sep 15, 2025 21:20:55.671 [10940] VERBOSE - [FFMPEG] - Loading external lib \\?\C:\PlexMeta\Plex Media Server\Codecs\46f74ab-560174306fe167a5978a79dd-windows-x86_64\ac3_encoder.dll
Sep 15, 2025 21:20:55.671 [10940] VERBOSE - [FFMPEG] - Loading external lib \\?\C:\PlexMeta\Plex Media Server\Codecs\46f74ab-560174306fe167a5978a79dd-windows-x86_64\dca_decoder.dll
Sep 15, 2025 21:20:55.671 [10940] VERBOSE - [FFMPEG] - Loading external lib \\?\C:\PlexMeta\Plex Media Server\Codecs\46f74ab-560174306fe167a5978a79dd-windows-x86_64\h264_decoder.dll
Sep 15, 2025 21:20:55.671 [10940] VERBOSE - [FFMPEG] - Loading external lib \\?\C:\PlexMeta\Plex Media Server\Codecs\46f74ab-560174306fe167a5978a79dd-windows-x86_64\hevc_decoder.dll
Sep 15, 2025 21:20:55.672 [10940] VERBOSE - [FFMPEG] - Loading external lib \\?\C:\PlexMeta\Plex Media Server\Codecs\46f74ab-560174306fe167a5978a79dd-windows-x86_64\jpeg2000_decoder.dll
Sep 15, 2025 21:20:55.672 [10940] VERBOSE - [FFMPEG] - Loading external lib \\?\C:\PlexMeta\Plex Media Server\Codecs\46f74ab-560174306fe167a5978a79dd-windows-x86_64\libx264_encoder.dll
Sep 15, 2025 21:20:55.672 [10940] VERBOSE - [FFMPEG] - Loading external lib \\?\C:\PlexMeta\Plex Media Server\Codecs\46f74ab-560174306fe167a5978a79dd-windows-x86_64\mp2_decoder.dll
Sep 15, 2025 21:20:55.672 [10940] VERBOSE - [FFMPEG] - Loading external lib \\?\C:\PlexMeta\Plex Media Server\Codecs\46f74ab-560174306fe167a5978a79dd-windows-x86_64\mp3_decoder.dll
Sep 15, 2025 21:20:55.673 [10940] VERBOSE - [FFMPEG] - Loading external lib \\?\C:\PlexMeta\Plex Media Server\Codecs\46f74ab-560174306fe167a5978a79dd-windows-x86_64\mpeg1video_decoder.dll
Sep 15, 2025 21:20:55.673 [10940] VERBOSE - [FFMPEG] - Loading external lib \\?\C:\PlexMeta\Plex Media Server\Codecs\46f74ab-560174306fe167a5978a79dd-windows-x86_64\mpeg2video_decoder.dll
Sep 15, 2025 21:20:55.673 [10940] VERBOSE - [FFMPEG] - Loading external lib \\?\C:\PlexMeta\Plex Media Server\Codecs\46f74ab-560174306fe167a5978a79dd-windows-x86_64\mpeg4_decoder.dll
Sep 15, 2025 21:20:55.674 [10940] VERBOSE - [FFMPEG] - Loading external lib \\?\C:\PlexMeta\Plex Media Server\Codecs\46f74ab-560174306fe167a5978a79dd-windows-x86_64\msmpeg4v3_decoder.dll
Sep 15, 2025 21:20:55.674 [10940] VERBOSE - [FFMPEG] - Loading external lib \\?\C:\PlexMeta\Plex Media Server\Codecs\46f74ab-560174306fe167a5978a79dd-windows-x86_64\prores_decoder.dll
Sep 15, 2025 21:20:55.674 [10940] VERBOSE - [FFMPEG] - Loading external lib \\?\C:\PlexMeta\Plex Media Server\Codecs\46f74ab-560174306fe167a5978a79dd-windows-x86_64\svq3_decoder.dll
Sep 15, 2025 21:20:55.674 [10940] VERBOSE - [FFMPEG] - Loading external lib \\?\C:\PlexMeta\Plex Media Server\Codecs\46f74ab-560174306fe167a5978a79dd-windows-x86_64\vc1_decoder.dll
Sep 15, 2025 21:20:55.674 [10940] VERBOSE - [FFMPEG] - Loading external lib \\?\C:\PlexMeta\Plex Media Server\Codecs\46f74ab-560174306fe167a5978a79dd-windows-x86_64\vp6f_decoder.dll
Sep 15, 2025 21:20:55.675 [10940] VERBOSE - [FFMPEG] - Loading external lib \\?\C:\PlexMeta\Plex Media Server\Codecs\46f74ab-560174306fe167a5978a79dd-windows-x86_64\vp9_decoder.dll
Sep 15, 2025 21:20:55.677 [11588] DEBUG - [GPU] Got device: Intel(R) Iris(R) Xe Graphics, intel@unknown, default true, best true, ID 8086:9a49:8086:3002, DevID [8086:9a49:8086:3002], flags 0xbaf6
Sep 15, 2025 21:20:55.678 [11588] DEBUG - [GPU] Cuda library was not found on the system
Sep 15, 2025 21:20:55.734 [11588] DEBUG - Grabber: Cleaning up orphaned grabs.
Sep 15, 2025 21:20:55.734 [10940] DEBUG - Media Provider: Registering provider com.plexapp.plugins.library
Sep 15, 2025 21:20:55.734 [10940] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Sep 15, 2025 21:20:55.735 [6304] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Sep 15, 2025 21:20:55.735 [11588] DEBUG - Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
Sep 15, 2025 21:20:55.735 [10940] DEBUG - Opening 1 database sessions to library (), SQLite 3.39.4, threadsafe=1
Sep 15, 2025 21:20:55.737 [1128] DEBUG - File "C:\Program Files\Plex\Plex Media Server\Resources\Plug-ins-4e8b05daf" changed: -1 => 2025-09-15 17:53:04 (1757951584).
Sep 15, 2025 21:20:55.737 [1128] DEBUG - File "C:\PlexMeta\Plex Media Server\Plug-ins" changed: -1 => 2022-03-28 20:07:24 (1648490844).
Sep 15, 2025 21:20:55.737 [1128] DEBUG - Scanning for plug-ins in "C:\Program Files\Plex\Plex Media Server\Resources\Plug-ins-4e8b05daf"
Sep 15, 2025 21:20:55.739 [1128] DEBUG - Scanning for plug-ins in "C:\PlexMeta\Plex Media Server\Plug-ins"
Sep 15, 2025 21:20:55.739 [1128] DEBUG - PluginRepository::setStartState: 1, startingSystem
Sep 15, 2025 21:20:55.739 [1128] DEBUG - Starting plug-in C:\Program Files\Plex\Plex Media Server\Resources\Plug-ins-4e8b05daf\System.bundle.
Sep 15, 2025 21:20:55.739 [1128] DEBUG - [com.plexapp.system] Setting plug-in to always running (daemon mode).
Sep 15, 2025 21:20:55.740 [1128] DEBUG - Starting file watcher for com.plexapp.system
Sep 15, 2025 21:20:55.740 [1128] DEBUG - Plugin: setting environment variable: 'PYTHONHOME=C:\Program Files\Plex\Plex Media Server'
Sep 15, 2025 21:20:55.740 [1128] DEBUG - Plugin: setting environment variable: 'PYTHONPATH=C:\Program Files\Plex\Plex Media Server\Resources\Python\python27.zip;C:\Program Files\Plex\Plex Media Server\Resources\Python\lib\python2.7\site-packages'
Sep 15, 2025 21:20:55.740 [1128] DEBUG - Plugin::createProcess: Creating plugin process: "C:\Program Files\Plex\Plex Media Server\PlexScriptHost.exe" "C:\Program Files\Plex\Plex Media Server\Resources\Plug-ins-4e8b05daf\Framework.bundle\Contents\Resources\Versions\2\Python\bootstrap.py" "C:\Program Files\Plex\Plex Media Server\Resources\Plug-ins-4e8b05daf\System.bundle"
Sep 15, 2025 21:20:55.743 [1128] DEBUG - Spawned plug-in com.plexapp.system with PID 5836
Sep 15, 2025 21:20:55.743 [1128] DEBUG - [com.plexapp.system] Sending command: GET /:/prefixes

Sep 15, 2025 21:20:57.414 [11588] WARN - Crash: Crash reporting disabled
Sep 15, 2025 21:20:58.747 [11588] DEBUG - [MediaProviderManager] Refreshing media providers
Sep 15, 2025 21:20:59.028 [11588] DEBUG - [MediaProviderManager] we had 0 cloud providers online, we now have 1
Sep 15, 2025 21:20:59.028 [11588] DEBUG - [MediaProviderManager] cloud provider (Metadata) is online and available
Sep 15, 2025 21:20:59.028 [11588] DEBUG - [MediaProviderManager/MetadataAgentManager] [tv.plex.agents.movie] provider added to map
Sep 15, 2025 21:20:59.028 [11588] DEBUG - [MediaProviderManager/MetadataAgentManager] [tv.plex.agents.series] provider added to map
Sep 15, 2025 21:20:59.028 [11588] DEBUG - [MediaProviderManager/MetadataAgentManager] [tv.plex.agents.music] provider added to map
Sep 15, 2025 21:20:59.028 [11588] DEBUG - [MediaProviderManager/MetadataAgentManager] [org.musicbrainz.agents.music] provider added to map
Sep 15, 2025 21:20:59.028 [11588] DEBUG - [MediaProviderManager] Media provider refresh complete
Sep 15, 2025 21:21:05.738 [6304] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 21:21:15.750 [11588] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 21:21:16.323 [18568] VERBOSE - We didn't receive any data from 192.168.1.246:44410 in time, dropping connection.
Sep 15, 2025 21:21:25.750 [11588] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 21:21:35.756 [11588] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 21:21:35.927 [16696] VERBOSE - We didn't receive any data from 192.168.1.202:58081 in time, dropping connection.
Sep 15, 2025 21:21:35.927 [18568] VERBOSE - We didn't receive any data from 192.168.1.202:58083 in time, dropping connection.
Sep 15, 2025 21:21:35.927 [16696] VERBOSE - We didn't receive any data from 192.168.1.101:58082 in time, dropping connection.
Sep 15, 2025 21:21:35.942 [18568] VERBOSE - We didn't receive any data from 192.168.1.101:58084 in time, dropping connection.
Sep 15, 2025 21:21:45.769 [11588] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 21:21:55.779 [11588] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 21:22:05.780 [11588] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 21:22:15.788 [11588] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 21:22:25.800 [11588] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 21:22:35.812 [11588] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 21:22:36.604 [18568] VERBOSE - We didn't receive any data from 192.168.1.246:50892 in time, dropping connection.
Sep 15, 2025 21:22:45.824 [11588] DEBUG - Grabber: Plex Tuner Service not running; retrying in 10 seconds.
Sep 15, 2025 21:22:55.418 [11036] DEBUG - BPQ: [Starting] -> [Processing]
Sep 15, 2025 21:22:55.419 [11036] DEBUG - BPQ: generating queue items from 2 generator(s)
Sep 15, 2025 21:22:55.420 [11036] DEBUG - BPQ: generated 1 item(s) for queue
Sep 15, 2025 21:22:55.420 [11036] DEBUG - PlayQueue: Added 1 items in 0.0 sec.
Sep 15, 2025 21:22:55.421 [11036] DEBUG - PlayQueue: total generated: -1, before cursor: 0, after cursor: 0
Sep 15, 2025 21:22:55.421 [11036] DEBUG - PlayQueue: Inserting 0 new items, starting at 0.000000.
Sep 15, 2025 21:22:55.421 [11036] DEBUG - BPQ: [Processing] -> [Paused]
Sep 15, 2025 21:22:55.836 [11588] ERROR - Grabber: timeout waiting for Plex Tuner Service to finish starting.
Sep 15, 2025 21:23:35.928 [16696] VERBOSE - We didn't receive any data from 192.168.1.202:63958 in time, dropping connection.
Sep 15, 2025 21:23:35.928 [18568] VERBOSE - We didn't receive any data from 192.168.1.202:63956 in time, dropping connection.
Sep 15, 2025 21:23:35.928 [16696] VERBOSE - We didn't receive any data from 192.168.1.101:63957 in time, dropping connection.
Sep 15, 2025 21:23:35.928 [18568] VERBOSE - We didn't receive any data from 192.168.1.101:63960 in time, dropping connection.
Sep 15, 2025 21:23:55.752 [1128] ERROR - BufferingLineReader: failed to read line (error: -2)
Sep 15, 2025 21:23:55.752 [1128] ERROR - Timeout or error reading status line from plug-in pipe [com.plexapp.system], we're killing it.
Sep 15, 2025 21:23:55.752 [1128] DEBUG - Stopping [com.plexapp.system].
Sep 15, 2025 21:23:55.752 [1128] DEBUG - [com.plexapp.system] Killing.
Sep 15, 2025 21:23:55.752 [1128] ERROR - Error received reading configuration for com.plexapp.system
Sep 15, 2025 21:23:55.752 [1128] DEBUG - Stopping [com.plexapp.system].
Sep 15, 2025 21:23:55.752 [1128] DEBUG - Done with 1 synchronous plug-in starts, starting the rest in parallel.
Sep 15, 2025 21:23:55.752 [1128] DEBUG - PluginRepository::setStartState: 2, startingPlugins
Sep 15, 2025 21:23:55.752 [1128] DEBUG - Updating the list of agents known by the system.
Sep 15, 2025 21:23:55.752 [1128] DEBUG - Starting file watcher for com.plexapp.system
Sep 15, 2025 21:23:55.752 [1128] DEBUG - Plugin: setting environment variable: 'PYTHONHOME=C:\Program Files\Plex\Plex Media Server'
Sep 15, 2025 21:23:55.752 [1128] DEBUG - Plugin: setting environment variable: 'PYTHONPATH=C:\Program Files\Plex\Plex Media Server\Resources\Python\python27.zip;C:\Program Files\Plex\Plex Media Server\Resources\Python\lib\python2.7\site-packages'
Sep 15, 2025 21:23:55.752 [1128] DEBUG - Plugin::createProcess: Creating plugin process: "C:\Program Files\Plex\Plex Media Server\PlexScriptHost.exe" "C:\Program Files\Plex\Plex Media Server\Resources\Plug-ins-4e8b05daf\Framework.bundle\Contents\Resources\Versions\2\Python\bootstrap.py" "C:\Program Files\Plex\Plex Media Server\Resources\Plug-ins-4e8b05daf\System.bundle"
Sep 15, 2025 21:23:55.752 [10940] DEBUG - Image transcode cache directory: "C:\PlexMeta\Plex Media Server\Cache\PhotoTranscoder"
Sep 15, 2025 21:23:55.753 [10940] DEBUG - Transcoder: Cleaning old transcode directories.
Sep 15, 2025 21:23:55.753 [10940] DEBUG - MyPlex: start public ip check and mapping - current mapped state: 'Unknown'.
Sep 15, 2025 21:23:55.753 [10940] DEBUG - MyPlex: mapping state set to 'Not Mapped'.
Sep 15, 2025 21:23:55.753 [10940] DEBUG - PublicAddressManager: Starting.
Sep 15, 2025 21:23:55.753 [10940] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Sep 15, 2025 21:23:55.754 [11588] DEBUG - PublicAddressManager: Obtaining public IP.
Sep 15, 2025 21:23:55.754 [11588] DEBUG - [HCl#3] HTTP requesting GET https://v4.plex.tv/pms/:/ip
Sep 15, 2025 21:23:55.754 [8340] DEBUG - [HCl#4] HTTP requesting POST https://plex.tv/api/v2/pins?strong=true
Sep 15, 2025 21:23:55.754 [1992] DEBUG - [HCl#5] HTTP requesting POST https://plex.tv/api/v2/pins?strong=true
Sep 15, 2025 21:23:55.754 [1128] DEBUG - Spawned plug-in com.plexapp.system with PID 9224
Sep 15, 2025 21:23:55.754 [1128] DEBUG - [com.plexapp.system] Sending command: GET /:/prefixes

Sep 15, 2025 21:23:55.755 [10940] DEBUG - NetworkInterface: Notified of network changed (force=0)
Sep 15, 2025 21:23:55.759 [10940] DEBUG - NetworkInterface: ignoring adapter 'Loopback Pseudo-Interface 1' (::1,127.0.0.1) because it has no gateway
Sep 15, 2025 21:23:55.759 [10940] DEBUG - Detected primary interface: 192.168.1.202
Sep 15, 2025 21:23:55.759 [10940] DEBUG - Network interfaces:
Sep 15, 2025 21:23:55.759 [10940] DEBUG -  * 15 Ethernet {D8EF3362-2741-47D4-B632-290C26773BF7} (192.168.1.202) (48-21-0B-26-19-51) (loopback: 0)
Sep 15, 2025 21:23:55.760 [10940] DEBUG - Creating NetworkServices singleton.
Sep 15, 2025 21:23:55.760 [10940] DEBUG - NetworkServices: Initializing...
Sep 15, 2025 21:23:55.760 [10940] DEBUG - NetworkService: Creating new service.
Sep 15, 2025 21:23:55.760 [10940] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Sep 15, 2025 21:23:55.760 [10940] DEBUG - NetworkService: Quick dispatch of network change.
Sep 15, 2025 21:23:55.760 [10940] DEBUG - NetworkService: Creating new service.
Sep 15, 2025 21:23:55.760 [10940] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Sep 15, 2025 21:23:55.760 [10940] DEBUG - NetworkService: Quick dispatch of network change.
Sep 15, 2025 21:23:55.760 [10940] DEBUG - NetworkService: Creating new service.
Sep 15, 2025 21:23:55.760 [10940] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Sep 15, 2025 21:23:55.760 [10940] DEBUG - NetworkService: Quick dispatch of network change.
Sep 15, 2025 21:23:55.760 [14340] DEBUG - Network change for advertiser.
Sep 15, 2025 21:23:55.760 [10940] DEBUG - NetworkService: Creating new service.
Sep 15, 2025 21:23:55.760 [14340] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32414
Sep 15, 2025 21:23:55.760 [10940] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Sep 15, 2025 21:23:55.760 [10940] DEBUG - NetworkService: Quick dispatch of network change.
Sep 15, 2025 21:23:55.760 [10940] DEBUG - NetworkService: Creating new service.
Sep 15, 2025 21:23:55.760 [10940] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Sep 15, 2025 21:23:55.760 [10940] DEBUG - NetworkService: Quick dispatch of network change.
Sep 15, 2025 21:23:55.760 [10940] DEBUG - NetworkService: Creating new service.
Sep 15, 2025 21:23:55.761 [10940] DEBUG - NetworkService: Got notification of changed network (first change: 1)
Sep 15, 2025 21:23:55.761 [10940] DEBUG - NetworkService: Quick dispatch of network change.
Sep 15, 2025 21:23:55.761 [14340] DEBUG - Network change for advertiser.
Sep 15, 2025 21:23:55.761 [14340] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32410
Sep 15, 2025 21:23:55.761 [14340] DEBUG - Network change for advertiser.
Sep 15, 2025 21:23:55.761 [14340] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32412
Sep 15, 2025 21:23:55.761 [14340] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Sep 15, 2025 21:23:55.761 [14340] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:32413
Sep 15, 2025 21:23:55.761 [14340] DEBUG - NetworkService: Browsing on interface 192.168.1.202 on broadcast address 192.168.1.255 (index: 0)
Sep 15, 2025 21:23:55.761 [14340] DEBUG - NetworkService: Network change for browser (polled=1), closing 0 browse sockets.
Sep 15, 2025 21:23:55.761 [14340] DEBUG - NetworkService: Browsing on interface 192.168.1.202 on broadcast address 192.168.1.255 (index: 0)
Sep 15, 2025 21:23:55.761 [14340] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.
Sep 15, 2025 21:23:55.761 [14340] DEBUG - NetworkService: Setting up multicast listener on 0.0.0.0:1901
Sep 15, 2025 21:23:55.762 [14340] DEBUG - NetworkService: Browsing on interface 192.168.1.202 on broadcast address 239.255.255.250 (index: 0)
Sep 15, 2025 21:23:55.762 [1320] DEBUG - [DatabaseFixups] Running fix-ups.
Sep 15, 2025 21:23:55.762 [17520] DEBUG - [ChildProcessMonitor] Plex Tuner Service.exe starting.
Sep 15, 2025 21:23:55.762 [17520] DEBUG - [ChildProcessMonitor/JobRunner] Job running: "C:\Program Files\Plex\Plex Media Server\Plex Tuner Service.exe" "C:\Program Files\Plex\Plex Media Server\Resources\Tuner\Private" "C:\Program Files\Plex\Plex Media Server\Resources\Tuner\Shared" 1.42.1.10060-4e8b05daf 32600
Sep 15, 2025 21:23:55.762 [10940] DEBUG - Waiting for server condition to be signaled.
Sep 15, 2025 21:23:55.762 [16744] DEBUG - [HCl#6] HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=
Sep 15, 2025 21:23:55.762 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsCleanupRetroGames] Executing fixup
Sep 15, 2025 21:23:55.763 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsCleanupRetroGames] Fixup completed
Sep 15, 2025 21:23:55.763 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsEnsureLibrarySectionUuids] Executing fixup
Sep 15, 2025 21:23:55.763 [17344] DEBUG - [HCl#7] HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=
Sep 15, 2025 21:23:55.763 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsEnsureLibrarySectionUuids] Fixup completed
Sep 15, 2025 21:23:55.763 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsEnsureMetadataItemHashes] Executing fixup
Sep 15, 2025 21:23:55.763 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsEnsureMetadataItemHashes] Fixup completed
Sep 15, 2025 21:23:55.763 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSetDefaultAccountLanguage] Executing fixup
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale af
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale ar
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale cs_CZ
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale da
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale de
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale en
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale es
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale et_EE
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale fi
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale fr
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale he
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale hr_HR
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale hu
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale hu_HU
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale it
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale ja_JP
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale ko
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale lt
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale nl
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale nl_BE
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale no
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale pl
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale pt
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale pt_BR
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale ro
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale ru
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale sk
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale sl
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale sr
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale sv
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale uk
Sep 15, 2025 21:23:55.763 [8140] DEBUG - Translator: Found locale zh_CN
Sep 15, 2025 21:23:55.763 [16092] DEBUG - [HCl#8] HTTP requesting GET https://plex.tv/api/v2/features?X-Plex-Token=
Sep 15, 2025 21:23:55.763 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSetDefaultAccountLanguage] Fixup completed
Sep 15, 2025 21:23:55.763 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsCreateDefaultMediaProcessingTargetTags] Executing fixup
Sep 15, 2025 21:23:55.764 [18528] DEBUG - DVR: Starting up.
Sep 15, 2025 21:23:55.764 [18528] DEBUG - Activity: registered new activity 09257ca7-4e88-41c4-86e4-38c49979dd40 - "Processing subscriptions"
Sep 15, 2025 21:23:55.764 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsCreateDefaultMediaProcessingTargetTags] Fixup completed
Sep 15, 2025 21:23:55.764 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsUpdateAutomaticLibraryFolders] Executing fixup
Sep 15, 2025 21:23:55.764 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsUpdateAutomaticLibraryFolders] Fixup completed
Sep 15, 2025 21:23:55.764 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Executing fixup
Sep 15, 2025 21:23:55.766 [17520] DEBUG - [ChildProcessMonitor/JobRunner] Jobs: Starting child process with pid 18104
Sep 15, 2025 21:23:55.767 [18100] VERBOSE - NetworkInterface: ignoring adapter 'Loopback Pseudo-Interface 1' (::1,127.0.0.1) because it has no gateway
Sep 15, 2025 21:23:55.767 [18100] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Sep 15, 2025 21:23:55.773 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 131 collections tags in section 6.
Sep 15, 2025 21:23:55.775 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 137 collections in the library already.
Sep 15, 2025 21:23:55.775 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 6 in 10ms.
Sep 15, 2025 21:23:55.775 [8140] DEBUG - Found session GUID of 4fbd0588-7ddf-4bc0-980f-3580fc8ae6f2 in session start.
Sep 15, 2025 21:23:55.871 [7408] WARN - Crash: Crash reporting disabled
Sep 15, 2025 21:23:55.872 [1320] WARN - [DatabaseFixups/class DatabaseFixupsSyncCollections] Held transaction for too long (D:\gha\plex-media-server\plex-media-server\Library\MetadataCollection.cpp:522): 0.156250 seconds
Sep 15, 2025 21:23:55.875 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 6 in 100ms.
Sep 15, 2025 21:23:55.889 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 6 in 12ms.
Sep 15, 2025 21:23:55.890 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 1 collections tags in section 7.
Sep 15, 2025 21:23:55.890 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 1 collections in the library already.
Sep 15, 2025 21:23:55.890 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 7 in 1ms.
Sep 15, 2025 21:23:55.891 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 7.
Sep 15, 2025 21:23:55.891 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 15, 2025 21:23:55.891 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 7 in 2ms.
Sep 15, 2025 21:23:55.892 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 7.
Sep 15, 2025 21:23:55.892 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 15, 2025 21:23:55.892 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 7 in 3ms.
Sep 15, 2025 21:23:55.896 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 7 in 4ms.
Sep 15, 2025 21:23:55.899 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 7 in 3ms.
Sep 15, 2025 21:23:55.900 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 8.
Sep 15, 2025 21:23:55.900 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 1 collections in the library already.
Sep 15, 2025 21:23:55.900 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 8 in 0ms.
Sep 15, 2025 21:23:55.902 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 8 in 1ms.
Sep 15, 2025 21:23:55.904 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 8 in 1ms.
Sep 15, 2025 21:23:55.904 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 9.
Sep 15, 2025 21:23:55.904 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 15, 2025 21:23:55.904 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 9 in 0ms.
Sep 15, 2025 21:23:55.906 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 9 in 1ms.
Sep 15, 2025 21:23:55.906 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 9 in 0ms.
Sep 15, 2025 21:23:55.907 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 10.
Sep 15, 2025 21:23:55.907 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 15, 2025 21:23:55.907 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 10 in 0ms.
Sep 15, 2025 21:23:55.908 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 10 in 1ms.
Sep 15, 2025 21:23:55.909 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 10 in 0ms.
Sep 15, 2025 21:23:55.910 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 11.
Sep 15, 2025 21:23:55.910 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 15, 2025 21:23:55.910 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 11 in 0ms.
Sep 15, 2025 21:23:55.911 [3600] DEBUG - [HttpClient/HCl#6] HTTP/1.1 (0.1s) 200 response from GET https://plex.tv/api/v2/release_channels?X-Plex-Token=
Sep 15, 2025 21:23:55.911 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 11 in 1ms.
Sep 15, 2025 21:23:55.912 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 11 in 1ms.
Sep 15, 2025 21:23:55.923 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 12.
Sep 15, 2025 21:23:55.924 [3600] DEBUG - [HttpClient/HCl#8] HTTP/1.1 (0.2s) 200 response from GET https://plex.tv/api/v2/features?X-Plex-Token=
Sep 15, 2025 21:23:55.924 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 15, 2025 21:23:55.924 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 12 in 11ms.
Sep 15, 2025 21:23:55.927 [3600] DEBUG - [HttpClient/HCl#4] HTTP/1.1 (0.2s) 201 response from POST https://plex.tv/api/v2/pins?strong=true
Sep 15, 2025 21:23:55.927 [16092] DEBUG - [HCl#9] HTTP requesting GET https://plex.tv/api/v2/server/users/features
Sep 15, 2025 21:23:55.930 [3600] DEBUG - [HttpClient/HCl#7] HTTP/1.1 (0.2s) 200 response from GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=
Sep 15, 2025 21:23:55.932 [3600] DEBUG - [HttpClient/HCl#5] HTTP/1.1 (0.2s) 201 response from POST https://plex.tv/api/v2/pins?strong=true
Sep 15, 2025 21:23:55.933 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 12 in 8ms.
Sep 15, 2025 21:23:55.935 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 12 in 1ms.
Sep 15, 2025 21:23:55.936 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Found 0 collections tags in section 13.
Sep 15, 2025 21:23:55.936 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: We had 0 collections in the library already.
Sep 15, 2025 21:23:55.936 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Synced tags in section 13 in 1ms.
Sep 15, 2025 21:23:55.938 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collections: Refreshed attributes for 0 collections in section 13 in 2ms.
Sep 15, 2025 21:23:55.941 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Collection: Refreshed visibility in section 13 in 2ms.
Sep 15, 2025 21:23:55.941 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsSyncCollections] Fixup completed
Sep 15, 2025 21:23:55.941 [1320] DEBUG - [DatabaseFixups/class DatabaseFixupsCleanupActivities] Executing fixup
Sep 15, 2025 21:23:55.951 [3600] DEBUG - [HttpClient/HCl#3] HTTP/1.1 (0.2s) 200 response from GET https://v4.plex.tv/pms/:/ip
Sep 15, 2025 21:23:55.952 [11588] DEBUG - PublicAddressManager: Got public IP from v4.plex.tv: [my public IP address]
Sep 15, 2025 21:23:57.782 [18568] VERBOSE - We didn't receive any data from 192.168.1.246:49542 in time, dropping connection.
Sep 15, 2025 21:23:57.782 [3600] DEBUG - [HttpClient/HCl#9] HTTP/1.1 (1.9s) 401 response from GET https://plex.tv/api/v2/server/users/features (reused)
Sep 15, 2025 21:23:57.782 [2148] ERROR - MyPlex: Error 401 requesting JSON from: https://plex.tv/api/v2/server/users/features
Sep 15, 2025 21:23:57.782 [16092] WARN - FeatureManager: Couldn't get features. Trying again soon.

192.168.1.101 = router
192.168.1.202 = PMS host
192.168.1.246 = Home Assistant (with a Plex integration) host

Turned verbose off again and turned crash reports on as suggested.

OttoKerner · September 16, 2025, 8:29am

After the next crash, please post the whole log file zip archive.

KJAB · September 16, 2025, 3:27pm

I know how to do that through the GUI if I could get the server running, but now I have to collect them manually. What log files should I include? The only ones that get generated in Plex Media Server\Logs before the crash are Plex Media Server.log and Plex Tuner Service.log (ie no new instances of Update Service, Update Service Launcher, Transcoder Statistics, Media Scanner, Media Scanner Voice Activity or Media Scanner Matcher). Should I also include logs from any other places, like latest Plex Media Server\Crash Reports\1.42.1.10060-4e8b05daf\PLEX MEDIA SERVER .log and .dmp files (MEDIA SCANNER and TUNER SERVICE folders in Crash Reports are empty)? Nothing new is generated in Plex Media Server\Logs\PMS Plugin Logs or Plex Media Server\Logs\Installer Logs. Perhaps even the (16 GB!) Windows dump in AppData\Local\CrashDumps, probably not?

OttoKerner · September 16, 2025, 3:43pm

Leave out the installer logs and the crash dumps.
The latter should get uploaded automatically once you’ve re-enabled that.

A developer might ask later for the Windows crash dump in direct communication with you.

KJAB · September 16, 2025, 4:02pm

Zipped up the logs (and the .dmp) that were generated on the latest run (not the Windows CrashDump though). I notice that the logs look (and end) slightly different compared to before I turned on crash reporting for Plex.

logs.zip (44.4 KB)

OttoKerner · September 16, 2025, 4:03pm

That’s expected, because now you also get log lines about the automated crash uploading being performed.

OttoKerner · September 16, 2025, 4:04pm

Please include all the logs, except those I mentioned above to omit.

KJAB · September 16, 2025, 5:54pm

Ah, even the old ones that are not generated when trying to launch now? That is all of Plex Media Server\Logs except Installer Logs basically? I’ll do that!

KJAB · September 16, 2025, 6:13pm

Here they are. Password protected zip, password sent in PM.

logs.zip (557.9 KB)

OttoKerner · September 16, 2025, 6:16pm

Thank you, I have forwarded the logs.

Topic		Replies	Views
PMS keeps crashing on Windows since last update August 31 Desktops & Laptops server-windows	24	408	December 12, 2022
Server keeps crashing - 1.22.2.4264 Desktops & Laptops server-windows	154	4165	January 4, 2022
My Plex Media Server keeps crashing Desktops & Laptops server-windows	56	1111	March 17, 2022
PMS keeps crashing every few days (sometimes daily) Desktops & Laptops server-linux	18	720	November 21, 2019
Plex Server Crashing Randomly Desktops & Laptops server-windows	205	5611	January 17, 2023

PMS for Windows crashes during launch every time (after password change)

Related topics