Plex media server on Linux just won't re-scan for new files

Plex Media Server Desktops & Laptops
1

Server Version#: newest
Player Version#: newest

Ubuntu 24.04. There’s definitely a problem.

2

Need a lot more info than " Problem " ( like your mechanic “it’s broke” :wink: )

  1. Confirm server DEBUG logging is enabled
  2. Restart the server
  3. Scan files so we can see it
  4. Download the server logs ZIP file (under Server - Troubleshooting - Download Logs)
  5. Attach the ZIP here

99.99% of the time, this is a permissions problem. If it were Ubuntu, everyone would be screaming.

3

I don’t feel comfortable publically sending all the files, but here are the interesting things from logs:

from com.plexapp.agents.imdb.log:

2025-03-07 00:12:02,578 (70a910b47808) :  ERROR (networking:197) - Error opening URL 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2025-03-07 00:12:02,581 (70a910b47808) :  CRITICAL (runtime:1299) - Exception getting hosted resource hashes (most recent call last):
  File "/usr/lib/plexmediaserver/Resources/Plug-ins-630c9f557/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 "/usr/lib/plexmediaserver/Resources/Plug-ins-630c9f557/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/networking.py", line 243, in content
    return self.__str__()
  File "/usr/lib/plexmediaserver/Resources/Plug-ins-630c9f557/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/networking.py", line 221, in __str__
    self.load()
  File "/usr/lib/plexmediaserver/Resources/Plug-ins-630c9f557/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/networking.py", line 159, in load
    f = self._opener.open(req, timeout=self._timeout)
  File "/usr/lib/plexmediaserver/Resources/Python/python27.zip/urllib2.py", line 435, in open
    response = meth(req, response)
  File "/usr/lib/plexmediaserver/Resources/Python/python27.zip/urllib2.py", line 548, in http_response
    'http', request, response, code, msg, hdrs)
  File "/usr/lib/plexmediaserver/Resources/Python/python27.zip/urllib2.py", line 473, in error
    return self._call_chain(*args)
  File "/usr/lib/plexmediaserver/Resources/Python/python27.zip/urllib2.py", line 407, in _call_chain
    result = func(*args)
  File "/usr/lib/plexmediaserver/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

from com.plexapp.system.log:

2025-03-07 00:11:58,615 (704e799e8b38) :  ERROR (networking:197) - Error opening URL 'http://127.0.0.1:32400'
2025-03-07 00:11:58,615 (704e79a6eb38) :  ERROR (networking:197) - Error opening URL 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2025-03-07 00:11:58,616 (704e799e8b38) :  WARNING (core:541) - Unable to retrieve the machine identifier or server version.
2025-03-07 00:11:58,616 (704e79a6eb38) :  ERROR (services:272) - Unable to load services from system. Loading from the current bundle only.
2025-03-07 00:11:58,622 (704e79a6eb38) :  DEBUG (services:362) - Loaded services
2025-03-07 00:11:58,644 (704e79a0bb38) :  DEBUG (services:438) - No shared code to load
2025-03-07 00:11:59,534 (704e7d00c808) :  INFO (__init__:35) - Starting System 630c9f5 (Thu Feb 06 17:40:12 UTC 2025)
2025-03-07 00:11:59,534 (704e7d00c808) :  DEBUG (runtime:640) - Adding a prefix handler for 'Player' ('/player')
2025-03-07 00:11:59,542 (704e7d00c808) :  INFO (datakit:93) - Loaded the dictionary file
2025-03-07 00:11:59,543 (704e7d00c808) :  DEBUG (agentservice:52) - Starting the agent service
2025-03-07 00:12:00,051 (704e7d00c808) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.system in namespace 'metadata'
2025-03-07 00:12:00,089 (704e7d00c808) :  DEBUG (bundleservice:65) - Starting the bundle service
2025-03-07 00:12:00,091 (704e7d00c808) :  DEBUG (runtime:1117) - Created a thread named 'start'
2025-03-07 00:12:00,092 (704e7d00c808) :  DEBUG (playerservice:12) - Starting the player service
2025-03-07 00:12:00,094 (704e7d00c808) :  DEBUG (flagservice:13) - Starting the media flag service
2025-03-07 00:12:00,096 (704e7d00c808) :  DEBUG (runtime:1117) - Created a thread named 'start'
2025-03-07 00:12:00,097 (704e7d00c808) :  DEBUG (peerservice:141) - Starting the peer service
2025-03-07 00:12:00,103 (704e7d00c808) :  DEBUG (runtime:1117) - Created a thread named 'refresh_servers'
2025-03-07 00:12:00,104 (704e797bcb38) :  DEBUG (peerservice:164) - Fetching the current list of servers
2025-03-07 00:12:00,106 (704e7d00c808) :  DEBUG (runtime:1150) - Scheduled a timed thread named 'refresh_servers'
2025-03-07 00:12:00,107 (704e797bcb38) :  DEBUG (networking:144) - Requesting 'http://127.0.0.1:32400/servers'
2025-03-07 00:12:00,109 (704e7d00c808) :  DEBUG (streamservice:18) - Starting the stream service
2025-03-07 00:12:00,112 (704e7d00c808) :  CRITICAL (runtime:709) - Private handlers are no longer supported; couldn't register <bound method StreamService.token_handler of <streamservice.StreamService object at 0x704e79561950>>
2025-03-07 00:12:00,114 (704e7d00c808) :  DEBUG (runtime:1150) - Scheduled a timed thread named 'invalidation_timer'
2025-03-07 00:12:00,119 (704e79802b38) :  DEBUG (runtime:1117) - Created a thread named 'ensure_agent_info_exists_inner'
2025-03-07 00:12:00,121 (704e7d00c808) :  DEBUG (proxyservice:12) - Starting the proxy service
2025-03-07 00:12:00,122 (704e797bcb38) :  ERROR (networking:197) - Error opening URL 'http://127.0.0.1:32400/servers'
2025-03-07 00:12:00,136 (704e7d00c808) :  DEBUG (runtime:1117) - Created a thread named 'update_attribution_flags_inner'
2025-03-07 00:12:00,138 (704e7d00c808) :  INFO (core:611) - Started plug-in
2025-03-07 00:12:00,139 (704e7d00c808) :  DEBUG (socketinterface:160) - Starting socket server
2025-03-07 00:12:00,148 (704e7d00c808) :  DEBUG (runtime:1117) - Created a thread named 'start'
2025-03-07 00:12:00,150 (704e7d00c808) :  INFO (socketinterface:184) - Socket server started on port 34377
2025-03-07 00:12:00,151 (704e7d00c808) :  INFO (pipeinterface:25) - Entering run loop
2025-03-07 00:12:00,153 (704e7d00c808) :  DEBUG (runtime:717) - Handling request GET /:/prefixes
2025-03-07 00:12:00,159 (704e79802b38) :  DEBUG (runtime:1117) - Created a thread named 'ensure_agent_info_exists_inner'
2025-03-07 00:12:00,164 (704e7d00c808) :  DEBUG (runtime:814) - Found route matching /:/prefixes
2025-03-07 00:12:00,168 (704e797bcb38) :  CRITICAL (runtime:1128) - Exception in thread named 'refresh_servers' (most recent call last):
  File "/usr/lib/plexmediaserver/Resources/Plug-ins-630c9f557/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/runtime.py", line 1126, in _start_thread
    f(*args, **kwargs)
  File "/usr/lib/plexmediaserver/Resources/Plug-ins-630c9f557/System.bundle/Contents/Code/peerservice.py", line 169, in refresh_servers
    servers_el = self.get_servers_el()
  File "/usr/lib/plexmediaserver/Resources/Plug-ins-630c9f557/System.bundle/Contents/Code/peerservice.py", line 165, in get_servers_el
    return XML.ElementFromURL('http://127.0.0.1:32400/servers', cacheTime = 0)
  File "/usr/lib/plexmediaserver/Resources/Plug-ins-630c9f557/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/api/parsekit.py", line 344, in ElementFromURL
    method=method,
  File "/usr/lib/plexmediaserver/Resources/Plug-ins-630c9f557/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/api/networkkit.py", line 67, in _http_request
    req = self._core.networking.http_request(url, *args, **kwargs)
  File "/usr/lib/plexmediaserver/Resources/Plug-ins-630c9f557/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/networking.py", line 352, in http_request
    return HTTPRequest(self._core, url, data, h, url_cache, encoding, errors, timeout, immediate, sleep, opener, follow_redirects, method)
  File "/usr/lib/plexmediaserver/Resources/Plug-ins-630c9f557/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/networking.py", line 119, in __init__
    self.load()
  File "/usr/lib/plexmediaserver/Resources/Plug-ins-630c9f557/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/networking.py", line 159, in load
    f = self._opener.open(req, timeout=self._timeout)
  File "/usr/lib/plexmediaserver/Resources/Python/python27.zip/urllib2.py", line 435, in open
    response = meth(req, response)
  File "/usr/lib/plexmediaserver/Resources/Python/python27.zip/urllib2.py", line 548, in http_response
    'http', request, response, code, msg, hdrs)
  File "/usr/lib/plexmediaserver/Resources/Python/python27.zip/urllib2.py", line 473, in error
    return self._call_chain(*args)
  File "/usr/lib/plexmediaserver/Resources/Python/python27.zip/urllib2.py", line 407, in _call_chain
    result = func(*args)
  File "/usr/lib/plexmediaserver/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 503: Service Unavailable
2025-03-07 00:12:02,572 (704e797cfb38) :  ERROR (networking:197) - Error opening URL 'http://resources-cdn.plexapp.com/hashes.json'
2025-03-07 00:12:02,573 (704e797cfb38) :  CRITICAL (runtime:1299) - Exception getting hosted resource hashes (most recent call last):
  File "/usr/lib/plexmediaserver/Resources/Plug-ins-630c9f557/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/runtime.py", line 1291, in get_resource_hashes
    json = self._core.networking.http_request("http://resources-cdn.plexapp.com/hashes.json", timeout=5).content
  File "/usr/lib/plexmediaserver/Resources/Plug-ins-630c9f557/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/networking.py", line 243, in content
    return self.__str__()
  File "/usr/lib/plexmediaserver/Resources/Plug-ins-630c9f557/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/networking.py", line 221, in __str__
    self.load()
  File "/usr/lib/plexmediaserver/Resources/Plug-ins-630c9f557/Framework.bundle/Contents/Resources/Versions/2/Python/Framework/components/networking.py", line 159, in load
    f = self._opener.open(req, timeout=self._timeout)
  File "/usr/lib/plexmediaserver/Resources/Python/python27.zip/urllib2.py", line 435, in open
    response = meth(req, response)
  File "/usr/lib/plexmediaserver/Resources/Python/python27.zip/urllib2.py", line 548, in http_response
    'http', request, response, code, msg, hdrs)
  File "/usr/lib/plexmediaserver/Resources/Python/python27.zip/urllib2.py", line 473, in error
    return self._call_chain(*args)
  File "/usr/lib/plexmediaserver/Resources/Python/python27.zip/urllib2.py", line 407, in _call_chain
    result = func(*args)
  File "/usr/lib/plexmediaserver/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

from Plex Media Server.log:

Mar 07, 2025 00:12:00.331 [125976124074808] WARN - [DatabaseFixups] Held transaction for too long (/home/runner/actions-runner/_work/plex-media-server/plex-media-server/Library/DatabaseFixups.cpp:369): 0.180000 seconds

Mar 07, 2025 00:12:01.115 [125976207960888] WARN - [EventSourceClient/pubsub/192.46.236.7:443] MyPlex: attempted a reachability check but we're not yet mapped.

Mar 07, 2025 00:12:03.781 [125976149240632] ERROR - downloadContainer: expected MediaContainer element, found Error
Mar 07, 2025 00:12:03.781 [125976115686200] ERROR - IVA: Error downloading trailers for source 1.
Mar 07, 2025 00:12:03.872 [125976149240632] ERROR - downloadContainer: expected MediaContainer element, found Error
Mar 07, 2025 00:12:03.872 [125976115686200] ERROR - IVA: Error downloading trailers for source 2.
Mar 07, 2025 00:12:03.977 [125976216349496] DEBUG - NAT: UPnP, found device <http://192.168.x.x:5351/rootDesc.xml> with private address <192.168.x.x>
Mar 07, 2025 00:12:03.980 [125976216349496] DEBUG - NAT: UPnP, usable device <http://192.168.x.x:5351/rootDesc.xml> with private address <192.168.x.x>.
Mar 07, 2025 00:12:03.981 [125976216349496] DEBUG - NAT: UPnP, public address is x.x.x.x
Mar 07, 2025 00:12:03.982 [125976216349496] DEBUG - [HCl#2a] HTTP requesting GET https://x.x.x.x.cad3e409a7094f72919e7a0e227cfd41.plex.direct:29539/identity
Mar 07, 2025 00:12:03.982 [125976186989368] WARN - [HttpClient/HCl#2a] HTTP error requesting GET https://x.x.x.x.cad3e409a7094f72919e7a0e227cfd41.plex.direct:29539/identity (7, Couldn't connect to server) (Failed to connect to x.x.x.x.cad3e409a7094f72919e7a0e227cfd41.plex.direct port 29539 after 0 ms: Couldn't connect to server)

Thank you!

4

I have sent you a PM.

You may attach your full logs ZIP file there.

5

@ChuckPa I am having similar issues. For me plex crashes constantly and is no longer stable, while it used to be. Happy to dm any logs

6

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