Newly installed Ubuntu server not downloading metadata

Plex Media Server Metadata & Adding Files
1

Server Version: 1.21.0.3616
Player Version: Web 4.43.4
OS Version: Ubuntu 20.04 LTS

Hi guys,
I’ve been struggling in updating my library metadata on a new server so, long story short, I’ve cleaned up all and prepared the most basic setup for testing the behaviour: newly clean-installed server, just one movie library, just one file inside (Mulan - 1998).

Here are my observations and all relevant infos:

  • File name is: ‘Mulan (1998).mkv’
  • Library is set to use the new Plex Movie Agent (not the legacy one)
  • Upon scanning, the new file is found but not matched automatically
  • When I try manual matching the movie, the new agent immediately tells “nothing found” (not even a small, realistic search delay)
  • I can manually switch to the old Plex Movie Agent (Legacy) which can match the movie, although it doesn’t download any metadata
  • If I manually force metadata download, nothing happens. Here are the relative debug-level logs section:
Nov 26, 2020 22:54:04.292 [0x7f17edffb700] DEBUG - Request: [192.168.1.221:40446 (Subnet)] PUT /library/metadata/2/refresh (4 live) GZIP
Nov 26, 2020 22:54:04.293 [0x7f17edffb700] DEBUG - Matching 'Mulan' (ID 2) with agent tv.plex.agents.movie
Nov 26, 2020 22:54:04.296 [0x7f17edffb700] DEBUG - Refreshing metadata for 'Mulan' (agent: tv.plex.agents.movie)
Nov 26, 2020 22:54:04.296 [0x7f17edffb700] DEBUG - MetadataAgentPostProcessor: adding id 2 to refresh queue (0 items in queue)
Nov 26, 2020 22:54:04.296 [0x7f188a40c700] DEBUG - Completed: [192.168.1.221:40446] 200 PUT /library/metadata/2/refresh (4 live) GZIP 3ms 596 bytes (pipelined: 2)
Nov 26, 2020 22:54:04.298 [0x7f1852ffd700] DEBUG - Activity: registered new activity 970da6ab-a1c1-4794-a88e-090f59125952 - Refreshing
Nov 26, 2020 22:54:04.298 [0x7f1852ffd700] DEBUG - Activity: updated activity 970da6ab-a1c1-4794-a88e-090f59125952 - completed 10.0% - Refreshing
Nov 26, 2020 22:54:04.298 [0x7f1852ffd700] WARN - MetadataAgentPostProcessor: error refreshing metadata for com.plexapp.agents.imdb://tt0120762?lang=it
Nov 26, 2020 22:54:04.298 [0x7f1852ffd700] DEBUG - Activity: updated activity 970da6ab-a1c1-4794-a88e-090f59125952 - completed 90.0% - Refreshing
Nov 26, 2020 22:54:04.298 [0x7f1852ffd700] DEBUG - Activity: Ended activity 970da6ab-a1c1-4794-a88e-090f59125952.

I’m kind of pointed towards a networking problem, but I don’t know how to test or confirm this hypothesis.

Can some Plex Guru please help me understanding why this happens?

Many thanks!

2

May we have the full log ZIP file please?

Diagnosing from a snippet isn’t possible.

3

For sure!
Just for the sake of keeping things clear and not give you clogged logs (I’ve made a lot o trials with detailed logs enabled yesterday) I started over once again for having just the meaningful entries.

Here the step I took:

  • Clean install of PMS
  • Startup
  • Add a Movie library with the new Plex Movie agent configured (italian language)
  • Try a manual match with the new agent (immediately fails, like I said previously)
  • Try a manual match with the legacy agent (successful, but no metadata was downloaded)
  • Try a forceful metadata update (unsuccessful)
  • Shut down

You can find the whole log of this process in the attachment.

Thank you very much for your help

(File removed)

4

Thanks for the logs (just always keep Verbose logging disabled).

Is PMS signed in with your user?

5

Oh, sorry for that, I was just hoping it gives you some more insight.

By the way no, in this test I didn’t sign-in on purpose.

One more thing, I’ve noticed some other suspicious error in the plugin logs:

PMS Plugin Logs/com.plexapp.system.log:2020-11-27 10:58:35,066 (7fe169ffb700) :  ERROR (networking:196) - Error opening URL 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.fanarttv'
PMS Plugin Logs/com.plexapp.system.log:2020-11-27 10:58:35,131 (7fe14affd700) :  ERROR (networking:196) - Error opening URL 'http://127.0.0.1:32400/:/plugins/tv.plex.agents.movie'
PMS Plugin Logs/com.plexapp.system.log:2020-11-27 10:58:35,148 (7fe149ffb700) :  ERROR (networking:196) - Error opening URL 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.thetvdb'
PMS Plugin Logs/com.plexapp.system.log:2020-11-27 10:58:35,156 (7fe1497fa700) :  ERROR (networking:196) - Error opening URL 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.themoviedb'
PMS Plugin Logs/com.plexapp.system.log:2020-11-27 10:58:35,160 (7fe148ff9700) :  ERROR (networking:196) - Error opening URL 'http://127.0.0.1:32400/:/plugins/org.musicbrainz.agents.music'
PMS Plugin Logs/com.plexapp.system.log:2020-11-27 10:58:35,167 (7fe12bfff700) :  ERROR (networking:196) - Error opening URL 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.localmedia'
PMS Plugin Logs/com.plexapp.system.log:2020-11-27 10:58:35,179 (7fe12b7fe700) :  ERROR (networking:196) - Error opening URL 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.lyricfind'
PMS Plugin Logs/com.plexapp.system.log:2020-11-27 10:58:35,182 (7fe12a7fc700) :  ERROR (networking:196) - Error opening URL 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.opensubtitles'
PMS Plugin Logs/com.plexapp.system.log:2020-11-27 10:58:35,185 (7fe169ffb700) :  ERROR (networking:196) - Error opening URL 'http://127.0.0.1:32400/:/plugins/tv.plex.agents.music'
PMS Plugin Logs/com.plexapp.system.log:2020-11-27 10:58:35,188 (7fe18cff9700) :  ERROR (networking:196) - Error opening URL 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.plexthememusic'
PMS Plugin Logs/com.plexapp.system.log:2020-11-27 10:58:35,191 (7fe14affd700) :  ERROR (networking:196) - Error opening URL 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.htbackdrops'
PMS Plugin Logs/com.plexapp.system.log:2020-11-27 10:58:35,194 (7fe168ff9700) :  ERROR (networking:196) - Error opening URL 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.movieposterdb'
PMS Plugin Logs/com.plexapp.system.log:2020-11-27 10:58:35,197 (7fe1297fa700) :  ERROR (networking:196) - Error opening URL 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.lastfm'
PMS Plugin Logs/com.plexapp.system.log:2020-11-27 10:58:35,200 (7fe1497fa700) :  ERROR (networking:196) - Error opening URL 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.none'
PMS Plugin Logs/com.plexapp.system.log:2020-11-27 10:58:35,202 (7fe149ffb700) :  ERROR (networking:196) - Error opening URL 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb'
PMS Plugin Logs/com.plexapp.system.log:2020-11-27 10:58:40,078 (7fe16a7fc700) :  ERROR (networking:196) - Error opening URL 'http://192.168.1.220:32400/library/sections'
PMS Plugin Logs/com.plexapp.system.log:2020-11-27 10:58:40,078 (7fe16a7fc700) :  ERROR (peerservice:83) - Failed to fetch data from http://192.168.1.220:32400/library/sections: ()

I don’t know if it’s relevant. In any case, I’ve attached the related file to this message.

(File removed)

6

You need to be signed in for the movie agent to work.

1 Like
7

That explains a lot!
It was simple in the end, lol.

To be honest with you it is not very clear that claiming the server is necessary for it to match your media, although I can understand you need to map users to API call/rate/quotas.

Of course if I missed that point in the docs then you have my apologies :wink:

In any case many thanks for your help, as always.
Keep up the good work!

8

No you’re correct, it isn’t very clear. I’ll file a issue so we can make this more apparent when you’re actually setting up a new library.

Glad it’s working now though!

2 Likes
9

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