Unable to download metadata

Plex Media Server Desktops & Laptops
1

Hi,
My plex server is having issues downloading metadata and I can’t find the reason for this in the logs:

The error: Error response for update: 5 (Unhandled exception). doesn’t give much clues as to what’s going on…

Jul 28, 2018 11:31:06.198 [0x7fa782ffe700] DEBUG - Refreshing metadata for 'Postman, The' (agent: )
Jul 28, 2018 11:31:06.198 [0x7fa782ffe700] DEBUG - Refreshing GUID: 'tt0119925'
Jul 28, 2018 11:31:06.198 [0x7fa782ffe700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/system/agents/update?mediaType=1&force=1&guid=tt0119925&id=112093
Jul 28, 2018 11:31:06.199 [0x7fa7933fe700] DEBUG - Request: [127.0.0.1:36654 (Loopback)] GET /system/agents/update?mediaType=1&force=1&guid=tt0119925&id=112093 (12 live) GZIP Signed-in
Jul 28, 2018 11:31:06.199 [0x7fa7933fe700] VERBOSE -  * User-Agent => Mozilla/5.0 (Windows NT 10.0; WOW64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/47.0.2526.106 Safari/537.36
Jul 28, 2018 11:31:06.199 [0x7fa7933fe700] VERBOSE -  * Host => 127.0.0.1:32400
Jul 28, 2018 11:31:06.199 [0x7fa7933fe700] VERBOSE -  * Accept => */*
Jul 28, 2018 11:31:06.199 [0x7fa7933fe700] VERBOSE -  * Accept-Encoding => gzip
Jul 28, 2018 11:31:06.199 [0x7fa7933fe700] VERBOSE -  * mediaType => 1
Jul 28, 2018 11:31:06.199 [0x7fa7933fe700] VERBOSE -  * force => 1
Jul 28, 2018 11:31:06.199 [0x7fa7933fe700] VERBOSE -  * guid => tt0119925
Jul 28, 2018 11:31:06.199 [0x7fa7933fe700] VERBOSE -  * id => 112093
Jul 28, 2018 11:31:06.200 [0x7fa7933fe700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/agents/update?mediaType=1&force=1&guid=tt0119925&id=112093
Jul 28, 2018 11:31:06.200 [0x7fa7933fe700] DEBUG - HTTP requesting GET http://127.0.0.1:46345/system/agents/update?mediaType=1&force=1&guid=tt0119925&id=112093
Jul 28, 2018 11:31:06.205 [0x7fa7933fe700] DEBUG - HTTP 200 response from GET http://127.0.0.1:46345/system/agents/update?mediaType=1&force=1&guid=tt0119925&id=112093
Jul 28, 2018 11:31:06.205 [0x7fa7933fe700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 632 bytes of content.
Jul 28, 2018 11:31:06.206 [0x7fa7973ff700] DEBUG - Completed: [127.0.0.1:36654] 200 GET /system/agents/update?mediaType=1&force=1&guid=tt0119925&id=112093 (12 live) GZIP 6ms 695 bytes
Jul 28, 2018 11:31:06.206 [0x7fa782ffe700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/system/agents/update?mediaType=1&force=1&guid=tt0119925&id=112093
Jul 28, 2018 11:31:06.206 [0x7fa782ffe700] ERROR - Error response for update: 5 (Unhandled exception).
Jul 28, 2018 11:31:06.206 [0x7fa782ffe700] VERBOSE - It took 0.0 sec to serialize a list with 0 elements.
Jul 28, 2018 11:31:06.208 [0x7fa7973ff700] DEBUG - Completed: [10.0.0.157:59352] 200 PUT /library/metadata/112093/refresh (12 live) TLS GZIP 13ms 555 bytes (pipelined: 1)

I’ve checked and the server can ping imdb etc
My server version is 1.13.5.5291
I also tried to clear the plugin cache but nothing works, the server still refuse to refresh the metadata…

Any help . would be appreciated thanks.

2

I found a workaround which kind of work.

So you’ll have to click on Fix match… and then select your movie again.
If this option is not available try to click on Analyse first

3

May I see your full logs please?

Manually fixing is not a viable workaround.

Your agent logs, captured after attempting automatic match, will tell me a lot more

4

Hi @ChuckPa, thanks for your help.

==> ./com.plexapp.agents.imdb.log <==
2018-07-28 20:53:41,888 (7fa278ff9700) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZnJzNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDgwNXM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0MTk4MjY5Nz9sYW5nPWZyczQKZ3VpZHM5CnR0MTk4MjY5N3MyCmlkcjAK
2018-07-28 20:53:41,893 (7fa278ff9700) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2018-07-28 20:53:41,895 (7fa278ff9700) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZnJzNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDgwNXM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0MTk4MjY5Nz9sYW5nPWZyczQKZ3VpZHM5CnR0MTk4MjY5N3MyCmlkcjAK
2018-07-28 20:53:41,896 (7fa278ff9700) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.imdb://tt1982697?lang=fr
2018-07-28 20:53:41,897 (7fa278ff9700) :  DEBUG (model:234) - Deserializing from /config/Library/Application Support/Plex Media Server/Metadata/Movies/e/f48237d4a4ed0434698ecc72d7528b73bbfbbea.bundle/Contents/com.plexapp.agents.imdb/Info.xml
2018-07-28 20:53:41,898 (7fa278ff9700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54805/tree'
2018-07-28 20:53:41,916 (7fa278ff9700) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fconfiguration' from the HTTP cache
2018-07-28 20:53:41,930 (7fa278ff9700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt1982697%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Dfr'
2018-07-28 20:53:42,013 (7fa278ff9700) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt1982697%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Dfr'
2018-07-28 20:53:42,014 (7fa278ff9700) :  INFO (__init__:1447) - Error fetching JSON from The Movie Database: http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt1982697%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Dfr
2018-07-28 20:53:42,019 (7fa278ff9700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt1982697%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D'
2018-07-28 20:53:42,101 (7fa278ff9700) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt1982697%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D'
2018-07-28 20:53:42,101 (7fa278ff9700) :  INFO (__init__:1447) - Error fetching JSON from The Movie Database: http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt1982697%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D
2018-07-28 20:53:42,106 (7fa278ff9700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt1982697%2Fimages'
2018-07-28 20:53:42,193 (7fa278ff9700) :  ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt1982697%2Fimages'
2018-07-28 20:53:42,193 (7fa278ff9700) :  INFO (__init__:1447) - Error fetching JSON from The Movie Database: http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt1982697%2Fimages
2018-07-28 20:53:42,194 (7fa278ff9700) :  INFO (__init__:541) - Unable to get TMDb movie data (in Plex Movie) for 1982697: argument of type 'NoneType' is not iterable
2018-07-28 20:53:42,194 (7fa278ff9700) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/m/1982697?lang=fr&ratings=1&reviews=1&extras=1'
2018-07-28 20:53:42,298 (7fa278ff9700) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/m/1982697?lang=fr&ratings=1&reviews=1&extras=1'
2018-07-28 20:53:42,298 (7fa278ff9700) :  INFO (__init__:677) - Plex_Movie_FAILED - Error obtaining Plex movie data for 1982697: HTTP Error 404: Not Found
2018-07-28 20:53:42,299 (7fa278ff9700) :  INFO (__init__:808) - Error obtaining extras for 1982697: 'NoneType' object has no attribute 'xpath'
2018-07-28 20:53:42,300 (7fa278ff9700) :  INFO (__init__:869) - Error obtaining Rotten tomato data for 1982697: 'NoneType' object has no attribute 'xpath'
2018-07-28 20:53:42,308 (7fa278ff9700) :  DEBUG (networking:161) - Fetching 'https://chapterdb.plex.tv/chapters/search?title=Happy%20Ever%20After' from the HTTP cache
2018-07-28 20:53:42,316 (7fa278ff9700) :  DEBUG (chapterdb:197) - Added 0 chapters.
2018-07-28 20:53:42,321 (7fa278ff9700) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/m/1982697?lang=fr&ratings=1&reviews=1&extras=1'
2018-07-28 20:53:42,394 (7fa278ff9700) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/m/1982697?lang=fr&ratings=1&reviews=1&extras=1'
2018-07-28 20:53:42,395 (7fa278ff9700) :  DEBUG (model:229) - Serializing to /config/Library/Application Support/Plex Media Server/Metadata/Movies/e/f48237d4a4ed0434698ecc72d7528b73bbfbbea.bundle/Contents/com.plexapp.agents.imdb/Info.xml
2018-07-28 20:53:42,397 (7fa278ff9700) :  DEBUG (runtime:88) - Sending packed state data (1056 bytes)
2018-07-28 20:53:42,397 (7fa278ff9700) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes

==> ./com.plexapp.agents.fanarttv.log <==
2018-07-28 20:53:43,848 (7f894bfff700) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.fanarttv/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZnJzNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDgwNXM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0MTk4MjY5Nz9sYW5nPWZyczQKZ3VpZHM5CnR0MTk4MjY5N3MyCmlkcjAK
2018-07-28 20:53:43,849 (7f894bfff700) :  DEBUG (runtime:49) - Received packed state data (80 bytes)
2018-07-28 20:53:43,851 (7f894bfff700) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.fanarttv/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZnJzNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQo1NDgwNXM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0MTk4MjY5Nz9sYW5nPWZyczQKZ3VpZHM5CnR0MTk4MjY5N3MyCmlkcjAK
2018-07-28 20:53:43,851 (7f894bfff700) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.imdb://tt1982697?lang=fr
2018-07-28 20:53:43,852 (7f894bfff700) :  DEBUG (model:234) - Deserializing from /config/Library/Application Support/Plex Media Server/Metadata/Movies/e/f48237d4a4ed0434698ecc72d7528b73bbfbbea.bundle/Contents/com.plexapp.agents.fanarttv/Info.xml
2018-07-28 20:53:43,853 (7f894bfff700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/54805/tree'
2018-07-28 20:53:43,864 (7f894bfff700) :  DEBUG (networking:166) - Requesting 'https://webservice.fanart.tv/v3/movies/tt1982697'
2018-07-28 20:53:43,953 (7f894bfff700) :  ERROR (networking:219) - Error opening URL 'https://webservice.fanart.tv/v3/movies/tt1982697'
2018-07-28 20:53:43,955 (7f894bfff700) :  DEBUG (networking:166) - Requesting 'https://webservice.fanart.tv/v3/movies/tt1982697'
2018-07-28 20:53:44,041 (7f894bfff700) :  ERROR (networking:219) - Error opening URL 'https://webservice.fanart.tv/v3/movies/tt1982697'
2018-07-28 20:53:44,043 (7f894bfff700) :  DEBUG (model:229) - Serializing to /config/Library/Application Support/Plex Media Server/Metadata/Movies/e/f48237d4a4ed0434698ecc72d7528b73bbfbbea.bundle/Contents/com.plexapp.agents.fanarttv/Info.xml
2018-07-28 20:53:44,045 (7f894bfff700) :  DEBUG (runtime:88) - Sending packed state data (108 bytes)
2018-07-28 20:53:44,045 (7f894bfff700) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
5

Would you try that again please?

Full logs == “Settings - Server - Help - Download Logs” and attaching the ZIP file it presents you

6

Ok I tried again a couple of times and then downloaded the logs (see attachment here Logs (3.5 MB) )
The strange thing is I can see 2 behaviours depending on the movies I select.

1: On refresh show the tag icon with the spinner for a couple of second then the jacket flips but nothing gets downloaded
2: On refresh, there is nothing shown on the jacket, and I immediately see the popup saying Finished processing…

7

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