I’m having some trouble downloading meta data for two movies in my collection - Fame (1980) and Rent (2005).
In the logs, I noticed there are some errors:
2018-07-08 03:07:04,463 (1160) : INFO (core:349) - Starting framework core - Version: 2.6.3, Build: cd1e0da (Wed Jun 27 06:22:33 UTC 2018)
2018-07-08 03:07:04,463 (1160) : DEBUG (core:361) - Using the elevated policy
2018-07-08 03:07:04,463 (1160) : DEBUG (core:450) - Starting runtime component.
2018-07-08 03:07:04,466 (1160) : DEBUG (core:450) - Starting caching component.
2018-07-08 03:07:04,466 (1160) : DEBUG (core:450) - Starting data component.
2018-07-08 03:07:04,466 (1160) : DEBUG (core:450) - Starting networking component.
2018-07-08 03:07:04,466 (1160) : DEBUG (networking:284) - Loaded HTTP cookies
2018-07-08 03:07:04,555 (1160) : DEBUG (networking:452) - Setting the default network timeout to 20.0
2018-07-08 03:07:04,556 (1160) : DEBUG (core:450) - Starting localization component.
2018-07-08 03:07:04,556 (1160) : INFO (localization:409) - Setting the default locale to en-us
2018-07-08 03:07:04,556 (1160) : DEBUG (core:450) - Starting messaging component.
2018-07-08 03:07:04,558 (1160) : DEBUG (core:450) - Starting debugging component.
2018-07-08 03:07:04,558 (1160) : DEBUG (core:450) - Starting services component.
2018-07-08 03:07:04,558 (1160) : DEBUG (core:450) - Starting myplex component.
2018-07-08 03:07:04,558 (1160) : DEBUG (core:450) - Starting notifications component.
2018-07-08 03:07:04,595 (119c) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.imdb'
2018-07-08 03:07:04,642 (1160) : DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.imdb in namespace 'metadata'
2018-07-08 03:07:04,673 (1160) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2018-07-08 03:07:04,686 (11ac) : DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2018-07-08 03:07:04,686 (1160) : DEBUG (runtime:1117) - Created a thread named 'load_all_services'
2018-07-08 03:07:04,687 (1160) : DEBUG (runtime:1117) - Created a thread named 'get_server_info'
2018-07-08 03:07:04,687 (1160) : DEBUG (core:150) - Finished starting framework core
2018-07-08 03:07:04,687 (1160) : DEBUG (core:560) - Loading plug-in code
2018-07-08 03:07:04,740 (11ac) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2018-07-08 03:07:04,740 (11b4) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400'
2018-07-08 03:07:04,747 (11b4) : DEBUG (core:538) - Machine identifier is 5c40c8466fcdfeba48190908a37417a33cab1cb2
2018-07-08 03:07:04,749 (11b4) : DEBUG (core:539) - Server version is 1.13.3.5223-cd1e0da1b
2018-07-08 03:07:04,986 (11ac) : DEBUG (services:362) - Loaded services
2018-07-08 03:07:04,994 (11b0) : DEBUG (services:438) - No shared code to load
2018-07-08 03:07:05,184 (1160) : DEBUG (core:566) - Finished loading plug-in code
2018-07-08 03:07:05,275 (1160) : DEBUG (agentkit:1115) - Creating new agent class called PlexMovieAgent
2018-07-08 03:07:05,276 (1160) : DEBUG (agentkit:933) - Updating agent information: [{'media_types': ['Movie'], 'accepts_from': ['com.plexapp.agents.localmedia'], 'fallback_agent': None, 'contributes_to': ['com.plexapp.agents.themoviedb'], 'languages': ['en', 'sv', 'fr', 'es', 'nl', 'de', 'it', 'da', 'ar', 'ca', 'zh', 'cs', 'et', 'fi', 'el', 'he', 'hi', 'hu', 'id', 'ja', 'ko', 'lv', 'no', 'fa', 'pl', 'pt', 'ro', 'ru', 'sk', 'th', 'tr', 'uk', 'vi'], 'persist_stored_files': True, 'version': 0, 'primary_provider': True, 'prefs': True, 'name': 'Plex Movie'}]
2018-07-08 03:07:05,298 (1160) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_'
2018-07-08 03:07:05,305 (1160) : INFO (core:611) - Started plug-in
2018-07-08 03:07:05,305 (1160) : DEBUG (socketinterface:160) - Starting socket server
2018-07-08 03:07:05,305 (1160) : DEBUG (runtime:1117) - Created a thread named 'start'
2018-07-08 03:07:05,306 (1160) : INFO (socketinterface:184) - Socket server started on port 49296
2018-07-08 03:07:05,306 (1160) : INFO (pipeinterface:25) - Entering run loop
2018-07-08 03:07:05,306 (1160) : DEBUG (runtime:717) - Handling request GET /:/prefixes
2018-07-08 03:07:05,308 (1160) : DEBUG (runtime:814) - Found route matching /:/prefixes
2018-07-08 03:07:05,308 (1160) : DEBUG (runtime:924) - Response: [200] MediaContainer, 148 bytes
2018-07-08 03:07:05,369 (11cc) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/prefs
2018-07-08 03:07:05,378 (11cc) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/prefs
2018-07-08 03:07:05,378 (11cc) : DEBUG (preferences:258) - Loaded preferences from DefaultPrefs.json
2018-07-08 03:07:05,380 (11cc) : DEBUG (preferences:178) - Loaded the user preferences for com.plexapp.agents.imdb
2018-07-08 03:07:05,387 (11cc) : DEBUG (runtime:88) - Sending packed state data (104 bytes)
2018-07-08 03:07:05,387 (11cc) : DEBUG (runtime:924) - Response: [200] MediaContainer, 2345 bytes
2018-07-08 03:07:24,778 (1334) : DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IxczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU4CnMxNgoyNDZmMGQzY2YwMGM2YzE1czE3Cm9wZW5TdWJ0aXRsZXNIYXNoczQKRmFtZXM0Cm5hbWVzNDAKOGUwOTQ3YTcwY2E3ZjYxMGEzYTY1NjI3ZmQ1ZTg1MGJkMjQyMmFjMHM4CnBsZXhIYXNoczEwMgolNUMlNUNuYXNzZXJ2JTVDdmlkZW8lNUNpbmNvbWluZyU1Q0ZhbWUlMjAoMTk4MCklNUNGYW1lJTJFMTk4MCUyRTEwODBwJTJFQmx1UmF5JTJFeDI2NCUyRURUUy1GR1QlMkVta3ZzOApmaWxlbmFtZXM2CmJsdXJheXM2CnNvdXJjZXM0CjE5ODBzNAp5ZWFyczcKODAxNTYyN3M4CmR1cmF0aW9uczYKNTA3ODk3czIKaWRyMAo_
2018-07-08 03:07:24,779 (1334) : DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IxczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU4CnMxNgoyNDZmMGQzY2YwMGM2YzE1czE3Cm9wZW5TdWJ0aXRsZXNIYXNoczQKRmFtZXM0Cm5hbWVzNDAKOGUwOTQ3YTcwY2E3ZjYxMGEzYTY1NjI3ZmQ1ZTg1MGJkMjQyMmFjMHM4CnBsZXhIYXNoczEwMgolNUMlNUNuYXNzZXJ2JTVDdmlkZW8lNUNpbmNvbWluZyU1Q0ZhbWUlMjAoMTk4MCklNUNGYW1lJTJFMTk4MCUyRTEwODBwJTJFQmx1UmF5JTJFeDI2NCUyRURUUy1GR1QlMkVta3ZzOApmaWxlbmFtZXM2CmJsdXJheXM2CnNvdXJjZXM0CjE5ODBzNAp5ZWFyczcKODAxNTYyN3M4CmR1cmF0aW9uczYKNTA3ODk3czIKaWRyMAo_
2018-07-08 03:07:24,779 (1334) : INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': '246f0d3cf00c6c15', 'name': 'Fame', 'plexHash': '8e0947a70ca7f610a3a65627fd5e850bd2422ac0', 'filename': '%5C%5Cnasserv%5Cvideo%5Cincoming%5CFame%20(1980)%5CFame%2E1980%2E1080p%2EBluRay%2Ex264%2EDTS-FGT%2Emkv', 'source': 'bluray', 'year': '1980', 'duration': '8015627', 'id': '507897'}
2018-07-08 03:07:24,826 (1334) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/507897/tree'
2018-07-08 03:07:24,862 (1334) : INFO (logkit:16) - checking hash search vector: https://meta.plex.tv/movie/hash/8e/8e0947a70ca7f610a3a65627fd5e850bd2422ac0.xml
2018-07-08 03:07:24,875 (1334) : DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/hash/8e/8e0947a70ca7f610a3a65627fd5e850bd2422ac0.xml'
2018-07-08 03:07:35,223 (1334) : ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/movie/hash/8e/8e0947a70ca7f610a3a65627fd5e850bd2422ac0.xml'
2018-07-08 03:07:35,223 (1334) : INFO (logkit:16) - freebase/proxy hash lookup failed: HTTP Error 500: Internal Server Error
2018-07-08 03:07:35,224 (1334) : INFO (logkit:16) - Scoring {}
2018-07-08 03:07:35,224 (1334) : INFO (logkit:16) - ---- HASH RESULTS MAP ----
2018-07-08 03:07:35,224 (1334) : INFO (logkit:16) - {}
2018-07-08 03:07:35,224 (1334) : INFO (logkit:16) - checking title/year search vector: https://meta.plex.tv/movie/guid/93/93632f5045ae1c26adf7c9f5be2df42e1e621ac2.xml
2018-07-08 03:07:35,286 (1334) : DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/guid/93/93632f5045ae1c26adf7c9f5be2df42e1e621ac2.xml'
Other movies seem to be pulling down metadata fine.
If I switch the metadata agent from Plex Movie to “The Movie Database” I’m able to pull metadata down for Fame.
All other movies are left as automatic (Plex Movie Agent).
Any ideas?
I’ve deleted the Plug-In cache and rebooted and that didn’t help.
