Plex Movie agent not working

Plex Media Server Metadata & Adding Files
1

Server Version#: 1.21.2.3939, running on Ubuntu Server 18.04.5
Player Version#: Plex Web

I just added Time Bandits (filename Time Bandits (1981).mkv, per the file naming guidelines) and the automatic agent does not find any matches. This stopped working with the most recent or second most recent update of Plex Media Server. I’ve tried rebuilding the database (per this article: https://support.plex.tv/articles/201100678-repair-a-corrupt-database) and that did not help. Any suggestions? Thank you.

2

May I have your Logs ZIP file please?

Before replying, I checked the scanner & agents

Screenshot from 2021-02-01 19-01-52
Screenshot from 2021-02-01 19-01-52766×653 124 KB

[chuck@lizum usenet.521]$ ls -la /vie/qa/movies/Time*
total 12
drwxr-xr-x  2 chuck chuck 4096 Feb  1 18:58 ./
drwxr-xr-x 56 chuck chuck 4096 Feb  1 18:58 ../
-rw-r--r--  1 chuck chuck  286 Feb  1 18:58 Time Bandits (1981).mkv
[chuck@lizum usenet.522]$
3

Plex Media Server Logs_2021-02-02_09-14-04.zip (4.9 MB)

4

Thanks for the logs.

I am seeing Framework bundle exceptions in your logs which shouldn’t be happening because the movie scanner is in Plex now.

Would you please download and install PMS 1.21.2.3943?

After doing so, would you consider upgrading the library to that new scanner?

5

I updated to 1.21.2.3943 yesterday but it did not resolve the issue.

I’ve been using the new Plex Movie Agent since it was announced.

Screenshot from 2021-02-02 10-10-20
Screenshot from 2021-02-02 10-10-20731×529 35.9 KB

6

Only thing I can think of is timing. Was the file properly named when PMS first scanned it or was it renamed in-situ?

7

I’ve been using FileBot to name the files before adding them to Plex. It’s worth noting that this is not the only movie that isn’t loading metadata and it’s happening with TV shows too. I’m not sure if that makes a difference.

9

First, TheTVDB problems with matching were remedied by Engineering making extra allowances for the changed sorting from TheTVDB. (They no longer sort by best match. They sort by what they think is most relevant/popular)

Anything mismatched would require rematching.
Anything orphaned (not matched) would require you to automatch (click “match”) to make it be scanned again.

I thought we’re discussing Movies and not Television?

10

We are talking about movies, but it’s happening with TV shows too. We can just talk about movies for now though.

11

Whichever you like to talk about is fine with me. It’s your thread :wink:

As a quick test, would you consider:

  1. Create a new library section , give it a “Testing Movies” type name.
  2. Point it at the same folders as your main .
  3. HOWEVER
    a. Turn off preview thumbnails
    b. Turn off all the other non-critical options in the “Advanced” tab.
  4. See how much it matches when doing it “cold”.
12

It matched none of the movies in the new library.

13

Pull those logs please. I need to see that

14

Looking at your logs and Time Bandits is not using the Plex Movie agent. It’s using the Movie Database. What agent is set at the library level (i.e. Edit Section → Advanced → Agent)?

This must be set to Plex Movie in order for things to match inside that library with that agent. A “Referesh Metadata” on the item should fix it.

15

Plex Media Server Logs_2021-02-03_07-02-27.zip (5.6 MB)
Here are the latest logs.

I tried other agents with Time Bandits because the Plex Movie agent wasn’t working. You can see from the previous screenshot that the library is using the Plex Movie Agent.

16

Thanks!

I see a clue in the logs:

Feb 03, 2021 06:25:15.173 [0x7f54deffd700] ERROR - [MetadataAgent/tv.plex.agents.movie] Unable to find media provider

This means it was unable to contact the server that provides the metadata.

Could you restart your server, then wait about 5 minutes and send me fresh logs. You can also check if restarting helps anything.

17

Plex Media Server Logs_2021-02-03_10-53-07.zip (4.3 MB)

18

It looks like all requests to the Plex servers are failing on that machine.

e.g:

Feb 03, 2021 08:27:22.037 [0x7f08235e4700] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Feb 03, 2021 08:27:35.167 [0x7f08235e4700] ERROR - Error issuing curl_easy_perform(handle): 28
Feb 03, 2021 08:27:35.167 [0x7f08235e4700] DEBUG - HTTP simulating 408 after curl timeout
Feb 03, 2021 08:27:35.167 [0x7f08235e4700] ERROR - Error parsing content.
Feb 03, 2021 08:27:35.168 [0x7f08235e4700] ERROR - Error parsing XML: Error parsing file.

This appears to be happening with all web queries.

Check if you can make requests from the command line, e.g. curl https://plex.tv - if requests are failing there then you need to figure out what in your set up is broken.

19

Well, that was the weirdest thing I’ve seen in a while. I recently added a 10Gb ethernet card and when it was plugged in, some (but not all) traffic that should have gone to the internet was getting routed to the 10Gb card, which had no internet access. Unplugging the cable from it made everything work as expected.

I’ve never seen anything like that. Thanks for your help, guys!

20

So I’m having an issue with similar symptoms, but I don’t think it’s the same root cause, since all my interfaces (physical and virtual) have internet access, and a command-line curl shows no difficulty. Here are the logs pertaining to my attempt to do a “Match” on Green Lantern (2011), which is the first of the movies I added in recent weeks that won’t match or get metadata:

Mar 10, 2021 10:28:11.197 [0x7f5e9da4d700] Debug — Auth: authenticated user 1 as ArthurZey
Mar 10, 2021 10:28:11.198 [0x7f5e9d183700] Debug — Request: [10.10.47.10:54376 (Subnet)] GET /library/sections (11 live) TLS GZIP Signed-in Token (ArthurZey)
Mar 10, 2021 10:28:11.202 [0x7f5e9d75f700] Debug — Completed: [10.10.47.10:54376] 200 GET /library/sections (11 live) TLS GZIP 4ms 1084 bytes (pipelined: 1)
Mar 10, 2021 10:28:11.227 [0x7f5e9d75f700] Debug — Auth: authenticated user 1 as ArthurZey
Mar 10, 2021 10:28:11.227 [0x7f5e9d183700] Debug — Request: [10.10.47.10:54376 (Subnet)] GET /system/agents?mediaType=1 (11 live) TLS GZIP Signed-in Token (ArthurZey)
Mar 10, 2021 10:28:11.228 [0x7f5e9d183700] Debug — [com.plexapp.system] Sending command over HTTP (GET): /system/agents?mediaType=1
Mar 10, 2021 10:28:11.228 [0x7f5e9d183700] Debug — HTTP requesting GET http://127.0.0.1:36546/system/agents?mediaType=1
Mar 10, 2021 10:28:11.256 [0x7f5e9d75f700] Debug — Auth: authenticated user 1 as ArthurZey
Mar 10, 2021 10:28:11.257 [0x7f5e57447700] Debug — Request: [10.10.47.10:54377 (Subnet)] GET /library/metadata/59590/matches?manual=1 (12 live) TLS GZIP Signed-in Token (ArthurZey)
Mar 10, 2021 10:28:11.288 [0x7f5e9d183700] Debug — HTTP 200 response from GET http://127.0.0.1:36546/system/agents?mediaType=1
Mar 10, 2021 10:28:11.288 [0x7f5e9d183700] Debug — [com.plexapp.system] HTTP reply status 200, with 2068 bytes of content.
Mar 10, 2021 10:28:11.291 [0x7f5e9d75f700] Debug — Completed: [10.10.47.10:54376] 200 GET /system/agents?mediaType=1 (12 live) TLS GZIP 63ms 1656 bytes (pipelined: 2)
Mar 10, 2021 10:28:14.770 [0x7f5e9d75f700] Debug — Completed: [10.10.47.10:54208] 200 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (12 live) TLS GZIP 20001ms 5 bytes (pipelined: 11)
Mar 10, 2021 10:28:14.784 [0x7f5e9da4d700] Debug — Auth: authenticated user 1 as ArthurZey
Mar 10, 2021 10:28:14.784 [0x7f5e9d183700] Debug — Request: [10.10.47.10:54208 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (12 live) TLS GZIP Signed-in Token (ArthurZey)
Mar 10, 2021 10:28:14.784 [0x7f5e9d183700] Debug — Content-Length is -1 (of total: -1).
Mar 10, 2021 10:28:16.328 [0x7f5e57447700] Error — [MetadataAgent/tv.plex.agents.movie] Error issuing curl_easy_perform(handle): 56
Mar 10, 2021 10:28:16.330 [0x7f5e57447700] Error — [MetadataAgent/tv.plex.agents.movie] MetadataAgent: match request failed, provider returned -56 error (CONTENT OMITTED)
Mar 10, 2021 10:28:16.331 [0x7f5e9d75f700] Debug — Completed: [10.10.47.10:54377] 200 GET /library/metadata/59590/matches?manual=1 (12 live) TLS GZIP 5075ms 562 bytes (pipelined: 1)

You’ll notice a “(CONTENT OMITTED)” in the log. That’s because it made this message too long. Here’s the full set of log entries: 2021-03-10 10.28 MST - Plex Movie Match Error.txt (48.0 KB)

For reference, 10.10.47.10 is my MacBook Pro’s LAN IP.

I’m using the Plex Movie scanner and metadata agent . I get “No matches found.”, and “Search Options” shows “Plex Movie”.

What’s weird is that the log shows that something is matching, but there’s an error. And I’m not sure how to interpret the log entry.

I’m running PMS 1.22.0.4163 on Synology 1813+ (DSM 6.2.3-25426 Update 3), but this has been going on for a handful of recent PMS versions.

21

FWIW, I upgraded PMS to 1.22.2.4256, and whether in conjunction with my static routes (inspired by guidance from this post) or independent of them, the “Match…” functionality now works!

I have service order priority as follows:

  • tun0 (Synology’s connection to my VPN)
  • eth0 (connection to my directly-connected-to-my-ISP router, which forwards port 32400 for Plex to my static LAN IP)
  • eth1 (connection to a different router that creates a LAN that’s behind the router’s VPN connection)

I created static routes for IP addresses I discovered by digging the following hostnames:

  • movieagent.plex.tv
  • mediaverse.plex.tv
  • metadata-static.plex.tv
  • meta.plex.tv

I also added these, not that I was having a problem, and I didn’t expect they would be related, but why the hell not? :slight_smile:

  • notifications.plex.tv
  • tvdb2.plex.tv
  • tvthemes.plexapp.com
  • plexapp.com
  • dl.opensubtitles.org
  • opensubtitles.org