Plex libraries went offline. Rebuilt the server from scratch. Now agents won't work

Server Version#: 1.25.9.5721
Player Version#: 4.79.4

Hi

Faithful Plex Pass user here.

I’ve been running Plex for years with no major issue. The server runs on my NVIDIA Shield & files are on my NAS (QNAP 453D) on the same network. Everything has been mainly working nicely ever since.

For some reason libraries, all went offline 2 days ago. I still have full internet connectivity & can access live TV or free plex movies. My NAS is running just fine. The NAS is mounted & accessible from the Shield.

After hours of troubleshooting, I gave up. I uninstalled it all on the Shield (cache/files/app) and rebuilt it all from scratch. just like windows, it’s supposed to be a good thing to do once every few years, right?

Unfortunately, things didn’t go as planned. I was able to rebuild my server on the shield. All libraries are mapping the various files on my NAS correctly… however, not a single file is matched anymore. I have to say, as a long-time user of Plex, I’ve enjoyed thetvdb naming convention… but none of my carefully named files are matching anymore.
Worst, when I try to manually match an item, it yields no result within a split second. As if it didn’t even try! I’m talking not even grossly mismatching results. I’m saying no result at all.

I know it sounds like a network issue, but I couldn’t find the start of an evidence.

Any idea?

===

See themoviedb.log:

2022-04-19 13:53:34,534 (26be6174f8) : INFO (core:349) - Starting framework core - Version: 2.6.3, Build: 965587f (Tue Apr 05 10:24:21 UTC 2022)
2022-04-19 13:53:34,535 (26be6174f8) : DEBUG (core:361) - Using the elevated policy
2022-04-19 13:53:34,535 (26be6174f8) : DEBUG (core:450) - Starting runtime component.
2022-04-19 13:53:34,541 (26be6174f8) : DEBUG (core:450) - Starting caching component.
2022-04-19 13:53:34,541 (26be6174f8) : DEBUG (core:450) - Starting data component.
2022-04-19 13:53:34,542 (26be6174f8) : DEBUG (core:450) - Starting networking component.
2022-04-19 13:53:34,543 (26be6174f8) : DEBUG (networking:265) - No cookie jar found
2022-04-19 13:53:34,544 (26be6174f8) : DEBUG (networking:428) - Setting the default network timeout to 20.0
2022-04-19 13:53:34,546 (26be6174f8) : DEBUG (core:450) - Starting localization component.
2022-04-19 13:53:34,547 (26be6174f8) : INFO (localization:409) - Setting the default locale to en-us
2022-04-19 13:53:34,547 (26be6174f8) : DEBUG (core:450) - Starting messaging component.
2022-04-19 13:53:34,548 (26be6174f8) : DEBUG (core:450) - Starting debugging component.
2022-04-19 13:53:34,549 (26be6174f8) : DEBUG (core:450) - Starting services component.
2022-04-19 13:53:34,549 (26be6174f8) : DEBUG (core:450) - Starting myplex component.
2022-04-19 13:53:34,550 (295b1e0d00) : DEBUG (networking:143) - Requesting ‘http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.themoviedb
2022-04-19 13:53:34,550 (26be6174f8) : DEBUG (core:450) - Starting notifications component.
2022-04-19 13:53:34,918 (26be6174f8) : DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.themoviedb in namespace ‘metadata’
2022-04-19 13:53:34,934 (26be6174f8) : DEBUG (networking:143) - Requesting ‘http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes
2022-04-19 13:53:34,948 (26be6174f8) : ERROR (networking:196) - Error opening URL ‘http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes
2022-04-19 13:53:34,951 (26be6174f8) : CRITICAL (runtime:1299) - Exception getting hosted resource hashes (most recent call last):
File “/data/data/com.plexapp.mediaserver.smb/Resources/Plug-ins-965587f64/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/runtime.py”, line 1293, in get_resource_hashes
json = self._core.networking.http_request(“http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes”, timeout=10).content
File “/data/data/com.plexapp.mediaserver.smb/Resources/Plug-ins-965587f64/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/networking.py”, line 242, in content
return self.str()
File “/data/data/com.plexapp.mediaserver.smb/Resources/Plug-ins-965587f64/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/networking.py”, line 220, in str
self.load()
File “/data/data/com.plexapp.mediaserver.smb/Resources/Plug-ins-965587f64/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/networking.py”, line 158, in load
f = self._opener.open(req, timeout=self._timeout)
File “/data/user/0/com.plexapp.mediaserver.smb/Resources/Python/python27.zip/urllib2.py”, line 435, in open
response = meth(req, response)
File “/data/user/0/com.plexapp.mediaserver.smb/Resources/Python/python27.zip/urllib2.py”, line 548, in http_response
‘http’, request, response, code, msg, hdrs)
File “/data/user/0/com.plexapp.mediaserver.smb/Resources/Python/python27.zip/urllib2.py”, line 473, in error
return self._call_chain(*args)
File “/data/user/0/com.plexapp.mediaserver.smb/Resources/Python/python27.zip/urllib2.py”, line 407, in _call_chain
result = func(*args)
File “/data/user/0/com.plexapp.mediaserver.smb/Resources/Python/python27.zip/urllib2.py”, line 556, in http_error_default
raise HTTPError(req.get_full_url(), code, msg, hdrs, fp)
HTTPError: HTTP Error 404: Not Found

2022-04-19 13:53:34,972 (295b320d00) : DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2022-04-19 13:53:34,973 (26be6174f8) : DEBUG (runtime:1117) - Created a thread named ‘load_all_services’
2022-04-19 13:53:34,975 (26be6174f8) : DEBUG (runtime:1117) - Created a thread named ‘get_server_info’
2022-04-19 13:53:34,978 (295b320d00) : DEBUG (networking:143) - Requesting ‘http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
2022-04-19 13:53:34,978 (295d51ad00) : DEBUG (networking:143) - Requesting ‘http://127.0.0.1:32400
2022-04-19 13:53:34,980 (26be6174f8) : DEBUG (core:150) - Finished starting framework core
2022-04-19 13:53:34,982 (26be6174f8) : DEBUG (core:560) - Loading plug-in code
2022-04-19 13:53:35,099 (295d51ad00) : DEBUG (core:538) - Machine identifier is XXX
2022-04-19 13:53:35,103 (295d51ad00) : DEBUG (core:539) - Server version is 1.25.9.5721-965587f64
2022-04-19 13:53:35,103 (295b320d00) : DEBUG (services:362) - Loaded services
2022-04-19 13:53:35,219 (295c41dd00) : DEBUG (services:438) - No shared code to load
2022-04-19 13:53:35,911 (26be6174f8) : DEBUG (core:566) - Finished loading plug-in code
2022-04-19 13:53:35,912 (26be6174f8) : DEBUG (messaging:69) - Exposing function GetImdbId for remote access
2022-04-19 13:53:35,912 (26be6174f8) : DEBUG (messaging:69) - Exposing function GetTvdbId for remote access
2022-04-19 13:53:35,913 (26be6174f8) : DEBUG (messaging:69) - Exposing function GetTvRageId for remote access
2022-04-19 13:53:35,913 (26be6174f8) : DEBUG (messaging:69) - Exposing function GetTMDbSearchResults for remote access
2022-04-19 13:53:35,913 (26be6174f8) : DEBUG (messaging:69) - Exposing function GetTMDbMetadata for remote access
2022-04-19 13:53:35,914 (26be6174f8) : DEBUG (agentkit:1132) - Creating new agent class called TMDbAgent
2022-04-19 13:53:35,916 (26be6174f8) : DEBUG (agentkit:937) - Updating agent information: [{‘media_types’: [‘Movie’], ‘accepts_from’: [‘com.plexapp.agents.localmedia’], ‘fallback_agent’: None, ‘contributes_to’: None, ‘languages’: [‘en’, ‘cs’, ‘da’, ‘de’, ‘el’, ‘es’, ‘fi’, ‘fr’, ‘he’, ‘hr’, ‘hu’, ‘it’, ‘lv’, ‘lt’, ‘nl’, ‘no’, ‘pl’, ‘pt’, ‘ru’, ‘sk’, ‘sv’, ‘th’, ‘tr’, ‘vi’, ‘zh’, ‘ko’], ‘persist_stored_files’: True, ‘version’: 0, ‘primary_provider’: True, ‘prefs’: True, ‘name’: ‘The Movie Database’}]
2022-04-19 13:53:35,918 (26be6174f8) : DEBUG (networking:143) - Requesting ‘http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo2CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0CjIKczI5CmNvbS5wbGV4YXBwLmFnZW50cy50aGVtb3ZpZWRiczEwCmlkZW50aWZpZXJyMQpzMTAKYWdlbnRfaW5mbzEKcjIKMTAKcjMKczExCm1lZGlhX3R5cGVzcjQKczEyCmFjY2VwdHNfZnJvbW5zMTQKZmFsbGJhY2tfYWdlbnRuczE0CmNvbnRyaWJ1dGVzX3RvcjUKczkKbGFuZ3VhZ2VzYjFzMjAKcGVyc2lzdF9zdG9yZWRfZmlsZXNpMApzNwp2ZXJzaW9uYjFzMTYKcHJpbWFyeV9wcm92aWRlcmIxczUKcHJlZnNzMTgKVGhlIE1vdmllIERhdGFiYXNlczQKbmFtZTEKczUKTW92aWUxCnMyOQpjb20ucGxleGFwcC5hZ2VudHMubG9jYWxtZWRpYTI2CnMyCmVuczIKY3NzMgpkYXMyCmRlczIKZWxzMgplc3MyCmZpczIKZnJzMgpoZXMyCmhyczIKaHVzMgppdHMyCmx2czIKbHRzMgpubHMyCm5vczIKcGxzMgpwdHMyCnJ1czIKc2tzMgpzdnMyCnRoczIKdHJzMgp2aXMyCnpoczIKa29yMAo_
2022-04-19 13:53:54,708 (26be6174f8) : DEBUG (agentkit:1132) - Creating new agent class called TMDbAgent
2022-04-19 13:53:54,715 (26be6174f8) : DEBUG (agentkit:937) - Updating agent information: [{‘media_types’: [‘Movie’], ‘accepts_from’: [‘com.plexapp.agents.localmedia’], ‘fallback_agent’: None, ‘contributes_to’: None, ‘languages’: [‘en’, ‘cs’, ‘da’, ‘de’, ‘el’, ‘es’, ‘fi’, ‘fr’, ‘he’, ‘hr’, ‘hu’, ‘it’, ‘lv’, ‘lt’, ‘nl’, ‘no’, ‘pl’, ‘pt’, ‘ru’, ‘sk’, ‘sv’, ‘th’, ‘tr’, ‘vi’, ‘zh’, ‘ko’], ‘persist_stored_files’: True, ‘version’: 0, ‘primary_provider’: True, ‘prefs’: True, ‘name’: ‘The Movie Database’}, {‘media_types’: [‘TV_Show’], ‘accepts_from’: [‘com.plexapp.agents.localmedia’, ‘com.plexapp.agents.thetvdb’], ‘fallback_agent’: None, ‘contributes_to’: [‘com.plexapp.agents.thetvdb’], ‘languages’: [‘en’, ‘cs’, ‘da’, ‘de’, ‘el’, ‘es’, ‘fi’, ‘fr’, ‘he’, ‘hr’, ‘hu’, ‘it’, ‘lv’, ‘lt’, ‘nl’, ‘no’, ‘pl’, ‘pt’, ‘ru’, ‘sk’, ‘sv’, ‘th’, ‘tr’, ‘vi’, ‘zh’, ‘ko’], ‘persist_stored_files’: True, ‘version’: 0, ‘primary_provider’: True, ‘prefs’: True, ‘name’: ‘The Movie Database’}]
2022-04-19 13:53:54,725 (26be6174f8) : DEBUG (networking:143) - Requesting ‘http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxMApkaWN0Cmxpc3QKZGljdApsaXN0Cmxpc3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0CjIKczI5CmNvbS5wbGV4YXBwLmFnZW50cy50aGVtb3ZpZWRiczEwCmlkZW50aWZpZXJyMQpzMTAKYWdlbnRfaW5mbzIKcjIKcjYKMTAKcjMKczExCm1lZGlhX3R5cGVzcjQKczEyCmFjY2VwdHNfZnJvbW5zMTQKZmFsbGJhY2tfYWdlbnRuczE0CmNvbnRyaWJ1dGVzX3RvcjUKczkKbGFuZ3VhZ2VzYjFzMjAKcGVyc2lzdF9zdG9yZWRfZmlsZXNpMApzNwp2ZXJzaW9uYjFzMTYKcHJpbWFyeV9wcm92aWRlcmIxczUKcHJlZnNzMTgKVGhlIE1vdmllIERhdGFiYXNlczQKbmFtZTEKczUKTW92aWUxCnMyOQpjb20ucGxleGFwcC5hZ2VudHMubG9jYWxtZWRpYTI2CnMyCmVuczIKY3NzMgpkYXMyCmRlczIKZWxzMgplc3MyCmZpczIKZnJzMgpoZXMyCmhyczIKaHVzMgppdHMyCmx2czIKbHRzMgpubHMyCm5vczIKcGxzMgpwdHMyCnJ1czIKc2tzMgpzdnMyCnRoczIKdHJzMgp2aXMyCnpoczIKa28xMApyNwpzMTEKbWVkaWFfdHlwZXNyOApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI5CnMxNApjb250cmlidXRlc190b3I1CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczE4ClRoZSBNb3ZpZSBEYXRhYmFzZXM0Cm5hbWUxCnM3ClRWX1Nob3cyCnMyOQpjb20ucGxleGFwcC5hZ2VudHMubG9jYWxtZWRpYXMyNgpjb20ucGxleGFwcC5hZ2VudHMudGhldHZkYjEKczI2CmNvbS5wbGV4YXBwLmFnZW50cy50aGV0dmRicjAK
2022-04-19 13:53:54,751 (26be6174f8) : INFO (core:611) - Started plug-in
2022-04-19 13:53:54,752 (26be6174f8) : DEBUG (socketinterface:160) - Starting socket server
2022-04-19 13:53:54,753 (26be6174f8) : DEBUG (runtime:1117) - Created a thread named ‘start’
2022-04-19 13:53:54,754 (26be6174f8) : INFO (socketinterface:184) - Socket server started on port 33005
2022-04-19 13:53:54,755 (26be6174f8) : INFO (pipeinterface:25) - Entering run loop
2022-04-19 13:53:54,755 (26be6174f8) : DEBUG (runtime:717) - Handling request GET /:/prefixes
2022-04-19 13:53:54,759 (26be6174f8) : DEBUG (runtime:814) - Found route matching /:/prefixes
2022-04-19 13:53:54,760 (26be6174f8) : DEBUG (runtime:924) - Response: [200] MediaContainer, 154 bytes
2022-04-19 18:49:49,390 (295c51dd00) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.themoviedb/prefs
2022-04-19 18:49:49,431 (295c51dd00) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.themoviedb/prefs
2022-04-19 18:49:49,436 (295c51dd00) : DEBUG (preferences:258) - Loaded preferences from DefaultPrefs.json
2022-04-19 18:49:49,438 (295c51dd00) : INFO (preferences:161) - No user preferences file exists
2022-04-19 18:49:49,446 (295c51dd00) : DEBUG (preferences:198) - Saved the user preferences
2022-04-19 18:49:49,452 (295c51dd00) : DEBUG (runtime:88) - Sending packed state data (110 bytes)
2022-04-19 18:49:49,452 (295c51dd00) : DEBUG (runtime:924) - Response: [200] MediaContainer, 1166 bytes
2022-04-19 18:49:58,389 (295c51dd00) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.themoviedb/prefs/set?country=17
2022-04-19 18:49:58,392 (295c51dd00) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.themoviedb/prefs/set
2022-04-19 18:49:58,394 (295c51dd00) : DEBUG (preferences:198) - Saved the user preferences
2022-04-19 18:49:58,395 (295c51dd00) : CRITICAL (sandbox:298) - Function named ‘ValidatePrefs’ couldn’t be found in the current environment
2022-04-19 18:49:58,396 (295c51dd00) : DEBUG (runtime:88) - Sending packed state data (110 bytes)
2022-04-19 18:49:58,397 (295c51dd00) : DEBUG (runtime:924) - Response: [200] str, 0 bytes
2022-04-19 19:02:40,752 (295c51dd00) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.themoviedb/prefs
2022-04-19 19:02:40,756 (295c51dd00) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.themoviedb/prefs
2022-04-19 19:02:40,768 (295c51dd00) : DEBUG (runtime:88) - Sending packed state data (110 bytes)
2022-04-19 19:02:40,769 (295c51dd00) : DEBUG (runtime:924) - Response: [200] MediaContainer, 1166 bytes
2022-04-19 19:13:35,353 (295c51dd00) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.themoviedb/prefs
2022-04-19 19:13:35,358 (295c51dd00) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.themoviedb/prefs
2022-04-19 19:13:35,364 (295c51dd00) : DEBUG (runtime:88) - Sending packed state data (110 bytes)
2022-04-19 19:13:35,364 (295c51dd00) : DEBUG (runtime:924) - Response: [200] MediaContainer, 1166 bytes
2022-04-19 19:18:33,532 (295c51dd00) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.themoviedb/prefs
2022-04-19 19:18:33,534 (295c51dd00) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.themoviedb/prefs
2022-04-19 19:18:33,550 (295c51dd00) : DEBUG (runtime:88) - Sending packed state data (110 bytes)
2022-04-19 19:18:33,551 (295c51dd00) : DEBUG (runtime:924) - Response: [200] MediaContainer, 1166 bytes

Can you provide the full server logs.

Thanks for jumping in ! Please find the logs attached.

Thanks, I’m trying to track down the cause of this but restarting the server should hopefully resolve the problem for now.

Let me know if that does the trick.

Damned, I feel silly but… after a reset it works now !
I just need to go into each library and > Manage > Refresh all metadata.

Thank you @drzoidberg33 !

Nope, not your fault at all. After some investigation I discovered an issue where a newly signed in session will not work correctly. The issue was on a backend service and a fix has already been deployed so it shouldn’t happen again.

Thanks again for the report!

This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.