Server Version#: 1.16.0.1226
Player Version#:
Several days ago, plex stopped getting match data for new movies added. I have restarted, but still not working. Looking at the system logs I am getting a python error related to the imdb just prior to the search for metadata, then there is a message about the imdb agent already existing. The logs for a fix match are below:
2019-06-20 06:58:29,069 (5224) : INFO (logkit:16) - Bundle with guid com.plexapp.agents.imdb://tt0287978?lang=en has been updated - notifying the media server
2019-06-20 06:58:29,069 (341c) : DEBUG (runtime:1117) - Created a thread named 'notify_thread'
2019-06-20 06:58:29,069 (341c) : DEBUG (logkit:13) - Removing com.plexapp.agents.imdb://tt0287978?lang=en from the update list
2019-06-20 06:58:29,071 (5224) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/metadata/notify?guid=com.plexapp.agents.imdb%3A%2F%2Ftt0287978%3Flang%3Den&path=Movies%5C5%5C9290f4432854051a126cd374f09681a2cbdf166.bundle&force=1&queueSize=942&id=89&success=0&async=1'
2019-06-20 06:58:29,071 (2f48) : INFO (logkit:16) - Preparing metadata for Movie in com.plexapp.agents.imdb (tt1648190)
2019-06-20 06:58:29,072 (2f48) : DEBUG (runtime:1117) - Created a thread named 'notify_processing'
2019-06-20 06:58:29,072 (4db0) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/metadata/processing?id=91'
2019-06-20 06:58:29,073 (2f48) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzMgo5MXM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0MTY0ODE5MD9sYW5nPWVuczQKZ3VpZHM5CnR0MTY0ODE5MHMyCmlkcjAK'
2019-06-20 06:58:35,358 (3430) : ERROR (networking:219) - Error opening URL 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzMwo0NTZzNApkYmlkaTAKczcKdmVyc2lvbm5zMTAKcGFyZW50R1VJRG5zOApwYXJlbnRJRHM1Ck1vdmllczEwCm1lZGlhX3R5cGVzNDMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGI6Ly90dDA0Njg1Njk%40bGFuZz1lbnM0Cmd1aWRzOQp0dDA0Njg1NjlzMgppZHIwCg__'
2019-06-20 06:58:35,358 (3430) : CRITICAL (core:579) - Exception in update for com.plexapp.agents.imdb://tt0468569?lang=en (most recent call last):
File "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-7eb2c8f6f\System.bundle\Contents\Code\agentservice.py", line 407, in update_task
self.agent_update_metadata(identifier, media_type, guid, id, lang, dbid, parentGUID, force, version, parentID, periodic)
File "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-7eb2c8f6f\System.bundle\Contents\Code\agentservice.py", line 1236, in agent_update_metadata
kwargs=kwargs
File "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-7eb2c8f6f\Framework.bundle\Contents\Resources\Versions\2\Python\Framework\components\messaging.py", line 86, in call_external_function
packed_result = self._core.networking.http_request(url, cacheTime=0, timeout=None, immediate=True).content
File "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-7eb2c8f6f\Framework.bundle\Contents\Resources\Versions\2\Python\Framework\components\networking.py", line 370, in http_request
return HTTPRequest(self._core, url, data, h, url_cache, encoding, errors, timeout, immediate, sleep, opener, follow_redirects, method)
File "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-7eb2c8f6f\Framework.bundle\Contents\Resources\Versions\2\Python\Framework\components\networking.py", line 141, in __init__
self.load()
File "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-7eb2c8f6f\Framework.bundle\Contents\Resources\Versions\2\Python\Framework\components\networking.py", line 181, in load
f = self._opener.open(req, timeout=self._timeout)
File "C:\Program Files (x86)\Plex\Plex Media Server\python27.zip\urllib2.py", line 435, in open
response = meth(req, response)
File "C:\Program Files (x86)\Plex\Plex Media Server\python27.zip\urllib2.py", line 548, in http_response
'http', request, response, code, msg, hdrs)
File "C:\Program Files (x86)\Plex\Plex Media Server\python27.zip\urllib2.py", line 473, in error
return self._call_chain(*args)
File "C:\Program Files (x86)\Plex\Plex Media Server\python27.zip\urllib2.py", line 407, in _call_chain
result = func(*args)
File "C:\Program Files (x86)\Plex\Plex Media Server\python27.zip\urllib2.py", line 556, in http_error_default
raise HTTPError(req.get_full_url(), code, msg, hdrs, fp)
HTTPError: HTTP Error 500: Internal Server Error
2019-06-20 06:58:35,359 (25a0) : INFO (logkit:16) - Bundle with guid com.plexapp.agents.imdb://tt0468569?lang=en has been updated - notifying the media server
2019-06-20 06:58:35,359 (3430) : DEBUG (runtime:1117) - Created a thread named 'notify_thread'
2019-06-20 06:58:35,361 (3430) : DEBUG (logkit:13) - Removing com.plexapp.agents.imdb://tt0468569?lang=en from the update list
2019-06-20 06:58:35,361 (340c) : INFO (logkit:16) - Preparing metadata for Movie in com.plexapp.agents.imdb (tt4555426)
2019-06-20 06:58:35,361 (25a0) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/metadata/notify?guid=com.plexapp.agents.imdb%3A%2F%2Ftt0468569%3Flang%3Den&path=Movies%5C1%5C5c3ebc654f842202387b9b25177ff74255acf8d.bundle&force=1&queueSize=941&id=456&success=0&async=1'
2019-06-20 06:58:35,361 (340c) : DEBUG (runtime:1117) - Created a thread named 'notify_processing'
2019-06-20 06:58:35,362 (3568) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/metadata/processing?id=90'
2019-06-20 06:58:35,365 (340c) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzMgo5MHM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0NDU1NTQyNj9sYW5nPWVuczQKZ3VpZHM5CnR0NDU1NTQyNnMyCmlkcjAK'
2019-06-20 06:58:54,664 (1708) : DEBUG (runtime:717) - Handling request GET /system/agents/update?mediaType=1&force=1&guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt2334871%3Flang%3Den&id=29764&agent=com.plexapp.agents.localmedia&async=0
2019-06-20 06:58:54,664 (1708) : DEBUG (runtime:814) - Found route matching /system/agents/update
2019-06-20 06:58:54,664 (1708) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins'
2019-06-20 06:58:54,667 (1708) : DEBUG (logkit:13) - Not adding com.plexapp.agents.imdb://tt2334871?lang=en to the update list because it already exists
2019-06-20 06:58:54,667 (1708) : DEBUG (runtime:924) - Response: [200] InitiateUpdateResponse, 159 bytes
2019-06-20 06:59:03,209 (16e0) : DEBUG (runtime:717) - Handling request GET /system/agents?mediaType=1
2019-06-20 06:59:03,210 (16e0) : DEBUG (runtime:49) - Received packed state data (80 bytes)
2019-06-20 06:59:03,210 (16e0) : DEBUG (runtime:814) - Found route matching /system/agents
2019-06-20 06:59:03,211 (16e0) : DEBUG (runtime:88) - Sending packed state data (99 bytes)
2019-06-20 06:59:03,213 (16e0) : DEBUG (runtime:924) - Response: [200] MediaContainer, 1992 bytes
2019-06-20 06:59:03,234 (6f0) : DEBUG (runtime:717) - Handling request GET /system/agents/search?mediaType=1&id=29764&identifier=com.plexapp.agents.imdb&duration=5430955&filename=G%253A%255CVideo%255CSnatched%2520(2017)%255CSnatched%2520(2017)%252Em4v&lang=en&openSubtitlesHash=860667d742d25ca0&plexHash=9fc921221ff36348939799e98e8d891f8e954ff7&name=Snatched&year=2017&manual=1
2019-06-20 06:59:03,236 (6f0) : DEBUG (runtime:814) - Found route matching /system/agents/search
2019-06-20 06:59:03,236 (6f0) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins'
2019-06-20 06:59:03,239 (6f0) : INFO (logkit:16) - Searching for Movie in com.plexapp.agents.imdb ({'openSubtitlesHash': '860667d742d25ca0', 'name': 'Snatched', 'year': '2017', 'filename': 'G%3A%5CVideo%5CSnatched%20(2017)%5CSnatched%20(2017)%2Em4v', 'plexHash': '9fc921221ff36348939799e98e8d891f8e954ff7', 'duration': '5430955', 'id': '29764'})
2019-06-20 06:59:03,239 (6f0) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IxczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU3CnMxNgo4NjA2NjdkNzQyZDI1Y2EwczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczgKU25hdGNoZWRzNApuYW1lczQKMjAxN3M0CnllYXJzNTgKRyUzQSU1Q1ZpZGVvJTVDU25hdGNoZWQlMjAoMjAxNyklNUNTbmF0Y2hlZCUyMCgyMDE3KSUyRW00dnM4CmZpbGVuYW1lczQwCjlmYzkyMTIyMWZmMzYzNDg5Mzk3OTllOThlOGQ4OTFmOGU5NTRmZjdzOApwbGV4SGFzaHM3CjU0MzA5NTVzOApkdXJhdGlvbnM1CjI5NzY0czIKaWRyMAo_'
2019-06-20 06:59:08,213 (2510) : INFO (datakit:131) - Saved the dictionary file
2019-06-20 06:59:28,920 (1f94) : DEBUG (runtime:717) - Handling request GET /system/agents/update?mediaType=1&force=1&guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt2334871%3Flang%3Den&id=29764&agent=com.plexapp.agents.localmedia&async=0
2019-06-20 06:59:28,920 (1f94) : DEBUG (runtime:814) - Found route matching /system/agents/update
2019-06-20 06:59:28,921 (1f94) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins'
2019-06-20 06:59:28,924 (1f94) : DEBUG (logkit:13) - Not adding com.plexapp.agents.imdb://tt2334871?lang=en to the update list because it already exists
2019-06-20 06:59:28,924 (1f94) : DEBUG (runtime:924) - Response: [200] InitiateUpdateResponse, 159 bytes
2019-06-20 06:59:41,865 (4590) : DEBUG (runtime:717) - Handling request GET /system/agents/update?mediaType=1&force=1&guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt2334871%3Flang%3Den&id=29764&agent=com.plexapp.agents.localmedia&async=0
2019-06-20 06:59:41,865 (4590) : DEBUG (runtime:814) - Found route matching /system/agents/update
2019-06-20 06:59:41,865 (4590) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins'
2019-06-20 06:59:41,868 (4590) : DEBUG (logkit:13) - Not adding com.plexapp.agents.imdb://tt2334871?lang=en to the update list because it already exists
2019-06-20 06:59:41,869 (4590) : DEBUG (runtime:924) - Response: [200] InitiateUpdateResponse, 159 bytes
2019-06-20 06:59:50,440 (2f18) : DEBUG (runtime:717) - Handling request GET /system/agents/update?mediaType=1&force=1&guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt2334871%3Flang%3Den&id=29764&agent=com.plexapp.agents.localmedia&async=0
2019-06-20 06:59:50,440 (2f18) : DEBUG (runtime:814) - Found route matching /system/agents/update
2019-06-20 06:59:50,441 (2f18) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins'
2019-06-20 06:59:50,444 (2f18) : DEBUG (logkit:13) - Not adding com.plexapp.agents.imdb://tt2334871?lang=en to the update list because it already exists
2019-06-20 06:59:50,446 (2f18) : DEBUG (runtime:924) - Response: [200] InitiateUpdateResponse, 159 bytes
2019-06-20 07:00:32,950 (6f0) : DEBUG (runtime:924) - Response: [200] MediaContainer, 1882 bytes
2019-06-20 07:00:43,772 (57f8) : DEBUG (runtime:717) - Handling request GET /system/agents/update?mediaType=1&force=1&guid=com%2Eplexapp%2Eagents%2Eimdb%3A%2F%2Ftt2334871%3Flang%3Den&id=29764
2019-06-20 07:00:43,773 (57f8) : DEBUG (runtime:814) - Found route matching /system/agents/update
2019-06-20 07:00:43,773 (57f8) : DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins'
2019-06-20 07:00:43,775 (57f8) : DEBUG (logkit:13) - Not adding com.plexapp.agents.imdb://tt2334871?lang=en to the update list because it already exists
2019-06-20 07:00:43,776 (57f8) : DEBUG (runtime:924) - Response: [200] InitiateUpdateResponse, 159 bytes
2019-06-20 07:00:58,085 (3ac8) : DEBUG (runtime:717) - Handling request GET /system/:/prefs
2019-06-20 07:00:58,085 (3ac8) : DEBUG (runtime:49) - Received packed state data (80 bytes)
2019-06-20 07:00:58,085 (3ac8) : DEBUG (runtime:814) - Found route matching /system/:/prefs
2019-06-20 07:00:58,086 (3ac8) : DEBUG (runtime:88) - Sending packed state data (99 bytes)
2019-06-20 07:00:58,088 (3ac8) : DEBUG (runtime:924) - Response: [200] MediaContainer, 418 bytes
ANy help?
