2019-01-06 22:10:24,441 (7f81ee453740) :  INFO (core:349) - Starting framework core - Version: 2.6.3, Build: cc260c4 (Fri Dec 14 20:06:30 UTC 2018)
2019-01-06 22:10:24,441 (7f81ee453740) :  DEBUG (core:361) - Using the elevated policy
2019-01-06 22:10:24,442 (7f81ee453740) :  DEBUG (core:450) - Starting runtime component.
2019-01-06 22:10:24,446 (7f81ee453740) :  DEBUG (core:450) - Starting caching component.
2019-01-06 22:10:24,447 (7f81ee453740) :  DEBUG (core:450) - Starting data component.
2019-01-06 22:10:24,447 (7f81ee453740) :  DEBUG (core:450) - Starting networking component.
2019-01-06 22:10:24,449 (7f81ee453740) :  DEBUG (networking:284) - Loaded HTTP cookies
2019-01-06 22:10:24,452 (7f81ee453740) :  DEBUG (networking:452) - Setting the default network timeout to 20.0
2019-01-06 22:10:24,452 (7f81ee453740) :  DEBUG (core:450) - Starting localization component.
2019-01-06 22:10:24,453 (7f81ee453740) :  INFO (localization:409) - Setting the default locale to en-us
2019-01-06 22:10:24,453 (7f81ee453740) :  DEBUG (core:450) - Starting messaging component.
2019-01-06 22:10:24,454 (7f81ee453740) :  DEBUG (core:450) - Starting debugging component.
2019-01-06 22:10:24,454 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.imdb'
2019-01-06 22:10:24,455 (7f81ee453740) :  DEBUG (core:450) - Starting services component.
2019-01-06 22:10:24,456 (7f81ee453740) :  DEBUG (core:450) - Starting myplex component.
2019-01-06 22:10:24,457 (7f81ee453740) :  DEBUG (core:450) - Starting notifications component.
2019-01-06 22:10:24,635 (7f81ee453740) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.imdb in namespace 'metadata'
2019-01-06 22:10:24,643 (7f81ee453740) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2019-01-06 22:10:24,670 (7f81bffff700) :  DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2019-01-06 22:10:24,670 (7f81ee453740) :  DEBUG (runtime:1117) - Created a thread named 'load_all_services'
2019-01-06 22:10:24,671 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2019-01-06 22:10:24,672 (7f81ee453740) :  DEBUG (runtime:1117) - Created a thread named 'get_server_info'
2019-01-06 22:10:24,674 (7f81ee453740) :  DEBUG (core:150) - Finished starting framework core
2019-01-06 22:10:24,674 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400'
2019-01-06 22:10:24,675 (7f81ee453740) :  DEBUG (core:560) - Loading plug-in code
2019-01-06 22:10:24,782 (7f81beffd700) :  DEBUG (core:538) - Machine identifier is 9137ee3c856455d82ce048f60bd6e5b64d0ae2ab
2019-01-06 22:10:24,783 (7f81beffd700) :  DEBUG (core:539) - Server version is 1.14.1.5488-cc260c476
2019-01-06 22:10:25,388 (7f81bffff700) :  DEBUG (services:362) - Loaded services
2019-01-06 22:10:25,402 (7f81bf7fe700) :  DEBUG (services:438) - No shared code to load
2019-01-06 22:10:25,805 (7f81ee453740) :  DEBUG (core:566) - Finished loading plug-in code
2019-01-06 22:10:25,988 (7f81ee453740) :  DEBUG (agentkit:1115) - Creating new agent class called PlexMovieAgent
2019-01-06 22:10:25,989 (7f81ee453740) :  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'}]
2019-01-06 22:10:25,991 (7f81ee453740) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_'
2019-01-06 22:10:26,006 (7f81ee453740) :  INFO (core:611) - Started plug-in
2019-01-06 22:10:26,007 (7f81ee453740) :  DEBUG (socketinterface:160) - Starting socket server
2019-01-06 22:10:26,008 (7f81ee453740) :  DEBUG (runtime:1117) - Created a thread named 'start'
2019-01-06 22:10:26,008 (7f81ee453740) :  INFO (socketinterface:184) - Socket server started on port 35974
2019-01-06 22:10:26,008 (7f81ee453740) :  INFO (pipeinterface:25) - Entering run loop
2019-01-06 22:10:26,009 (7f81ee453740) :  DEBUG (runtime:717) - Handling request GET /:/prefixes
2019-01-06 22:10:26,010 (7f81ee453740) :  DEBUG (runtime:814) - Found route matching /:/prefixes
2019-01-06 22:10:26,011 (7f81ee453740) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 148 bytes
2019-01-06 22:10:26,015 (7f81bffff700) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU3CnMxNgpiYTQzNDBjZjIzZDEwNmMzczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczI3ClRoZSBNYW4gV2l0aCB0aGUgR29sZGVuIEd1bnM0Cm5hbWVzNAoxOTc0czQKeWVhcnM5MwolMkZtbnQlMkZuZnMlMkZtZWRpYSUyRlZpZGVvcyUyRk1vdmllcyUyRlRoZSUyME1hbiUyMFdpdGglMjBUaGUlMjBHb2xkZW4lMjBHdW4lMjAoMTk3NCklMkVta3ZzOApmaWxlbmFtZXM0MAo2OWQxZDg2MWM3MzBjMGViN2ZmN2ZkNWQyZmM1NWNlNzQ5MTNkYTQwczgKcGxleEhhc2hzMgotMXM4CmR1cmF0aW9uczUKMTAyMjFzMgppZHIwCg__
2019-01-06 22:10:26,030 (7f81bffff700) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU3CnMxNgpiYTQzNDBjZjIzZDEwNmMzczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczI3ClRoZSBNYW4gV2l0aCB0aGUgR29sZGVuIEd1bnM0Cm5hbWVzNAoxOTc0czQKeWVhcnM5MwolMkZtbnQlMkZuZnMlMkZtZWRpYSUyRlZpZGVvcyUyRk1vdmllcyUyRlRoZSUyME1hbiUyMFdpdGglMjBUaGUlMjBHb2xkZW4lMjBHdW4lMjAoMTk3NCklMkVta3ZzOApmaWxlbmFtZXM0MAo2OWQxZDg2MWM3MzBjMGViN2ZmN2ZkNWQyZmM1NWNlNzQ5MTNkYTQwczgKcGxleEhhc2hzMgotMXM4CmR1cmF0aW9uczUKMTAyMjFzMgppZHIwCg__
2019-01-06 22:10:26,031 (7f81bffff700) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': 'ba4340cf23d106c3', 'name': 'The Man With the Golden Gun', 'year': '1974', 'filename': '%2Fmnt%2Fnfs%2Fmedia%2FVideos%2FMovies%2FThe%20Man%20With%20The%20Golden%20Gun%20(1974)%2Emkv', 'plexHash': '69d1d861c730c0eb7ff7fd5d2fc55ce74913da40', 'duration': '-1', 'id': '10221'}
2019-01-06 22:10:26,032 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/10221/tree'
2019-01-06 22:10:26,041 (7f81bffff700) :  INFO (__init__:138) - checking hash search vector: https://meta.plex.tv/movie/hash/69/69d1d861c730c0eb7ff7fd5d2fc55ce74913da40.xml
2019-01-06 22:10:26,046 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/hash/69/69d1d861c730c0eb7ff7fd5d2fc55ce74913da40.xml'
2019-01-06 22:10:26,592 (7f81bffff700) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/movie/hash/69/69d1d861c730c0eb7ff7fd5d2fc55ce74913da40.xml'
2019-01-06 22:10:26,592 (7f81bffff700) :  INFO (__init__:165) - freebase/proxy hash lookup failed: HTTP Error 404: Not Found
2019-01-06 22:10:26,592 (7f81bffff700) :  INFO (__init__:169) - Scoring {}
2019-01-06 22:10:26,593 (7f81bffff700) :  INFO (__init__:438) - ---- HASH RESULTS MAP ----
2019-01-06 22:10:26,593 (7f81bffff700) :  INFO (__init__:439) - {}
2019-01-06 22:10:26,594 (7f81bffff700) :  INFO (__init__:138) - checking title/year search vector: https://meta.plex.tv/movie/guid/28/28c6969ab98ac2d021e0e2787c7fff294746df19.xml
2019-01-06 22:10:26,599 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/guid/28/28c6969ab98ac2d021e0e2787c7fff294746df19.xml'
2019-01-06 22:10:26,703 (7f81bffff700) :  INFO (__init__:169) - Scoring {'tt0071807': [0, u'The Man With the Golden Gun', u'1974', 99, 99967, 0]}
2019-01-06 22:10:26,703 (7f81bffff700) :  INFO (__init__:464) - ---- TITLE_YEAR RESULTS MAP ----
2019-01-06 22:10:26,703 (7f81bffff700) :  INFO (__init__:465) - {'tt0071807': [0, u'The Man With the Golden Gun', u'1974', 99, 99967, 99]}
2019-01-06 22:10:26,704 (7f81bffff700) :  DEBUG (preferences:258) - Loaded preferences from DefaultPrefs.json
2019-01-06 22:10:26,706 (7f81bffff700) :  DEBUG (preferences:178) - Loaded the user preferences for com.plexapp.agents.imdb
2019-01-06 22:10:26,710 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0071807%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D'
2019-01-06 22:10:29,051 (7f81bffff700) :  INFO (__init__:473) - Adding title_year match: The Man with the Golden Gun (1974) score=99, key=tt0071807
2019-01-06 22:10:29,051 (7f81bffff700) :  INFO (__init__:481) - Found perfect match with title/year query.
2019-01-06 22:10:29,053 (7f81bffff700) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2019-01-06 22:10:29,054 (7f81bffff700) :  DEBUG (runtime:924) - Response: [200] str, 660 bytes
2019-01-06 22:10:29,111 (7f81bffff700) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQoxMDIyMXM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0MDA3MTgwNz9sYW5nPWVuczQKZ3VpZHM5CnR0MDA3MTgwN3MyCmlkcjAK
2019-01-06 22:10:29,113 (7f81bffff700) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQoxMDIyMXM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0MDA3MTgwNz9sYW5nPWVuczQKZ3VpZHM5CnR0MDA3MTgwN3MyCmlkcjAK
2019-01-06 22:10:29,114 (7f81bffff700) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.imdb://tt0071807?lang=en
2019-01-06 22:10:29,116 (7f81bffff700) :  ERROR (model:205) - Cannot read model from /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Metadata/Movies/4/6c3fb3c291726bc0a9cc9ff4c6a75a4553dc1bb.bundle/Contents/com.plexapp.agents.imdb
2019-01-06 22:10:29,117 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/10221/tree'
2019-01-06 22:10:29,126 (7f81beffd700) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU3CnMxNgpkNWFiNTEwMjhkZmE5ZDViczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczIwClRoZSBTcHkgV2hvIExvdmVkIE1lczQKbmFtZXM0CjE5NzdzNAp5ZWFyczg0CiUyRm1udCUyRm5mcyUyRm1lZGlhJTJGVmlkZW9zJTJGTW92aWVzJTJGVGhlJTIwU3B5JTIwV2hvJTIwTG92ZWQlMjBNZSUyMCgxOTc3KSUyRW1rdnM4CmZpbGVuYW1lczQwCmU5ZDM3MjEyMGI4YTI0OWQ2OThhODM2YjkwMGEzOGIyYzhhMzY1YzhzOApwbGV4SGFzaHMyCi0xczgKZHVyYXRpb25zNQoxMDIyMnMyCmlkcjAK
2019-01-06 22:10:29,127 (7f81beffd700) :  DEBUG (runtime:49) - Received packed state data (556 bytes)
2019-01-06 22:10:29,129 (7f81beffd700) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU3CnMxNgpkNWFiNTEwMjhkZmE5ZDViczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczIwClRoZSBTcHkgV2hvIExvdmVkIE1lczQKbmFtZXM0CjE5NzdzNAp5ZWFyczg0CiUyRm1udCUyRm5mcyUyRm1lZGlhJTJGVmlkZW9zJTJGTW92aWVzJTJGVGhlJTIwU3B5JTIwV2hvJTIwTG92ZWQlMjBNZSUyMCgxOTc3KSUyRW1rdnM4CmZpbGVuYW1lczQwCmU5ZDM3MjEyMGI4YTI0OWQ2OThhODM2YjkwMGEzOGIyYzhhMzY1YzhzOApwbGV4SGFzaHMyCi0xczgKZHVyYXRpb25zNQoxMDIyMnMyCmlkcjAK
2019-01-06 22:10:29,130 (7f81beffd700) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': 'd5ab51028dfa9d5b', 'name': 'The Spy Who Loved Me', 'year': '1977', 'filename': '%2Fmnt%2Fnfs%2Fmedia%2FVideos%2FMovies%2FThe%20Spy%20Who%20Loved%20Me%20(1977)%2Emkv', 'plexHash': 'e9d372120b8a249d698a836b900a38b2c8a365c8', 'duration': '-1', 'id': '10222'}
2019-01-06 22:10:29,134 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/10222/tree'
2019-01-06 22:10:29,137 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fconfiguration'
2019-01-06 22:10:29,143 (7f81beffd700) :  INFO (__init__:138) - checking hash search vector: https://meta.plex.tv/movie/hash/e9/e9d372120b8a249d698a836b900a38b2c8a365c8.xml
2019-01-06 22:10:29,148 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/hash/e9/e9d372120b8a249d698a836b900a38b2c8a365c8.xml'
2019-01-06 22:10:29,586 (7f81beffd700) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/movie/hash/e9/e9d372120b8a249d698a836b900a38b2c8a365c8.xml'
2019-01-06 22:10:29,587 (7f81beffd700) :  INFO (__init__:165) - freebase/proxy hash lookup failed: HTTP Error 404: Not Found
2019-01-06 22:10:29,587 (7f81beffd700) :  INFO (__init__:169) - Scoring {}
2019-01-06 22:10:29,588 (7f81beffd700) :  INFO (__init__:438) - ---- HASH RESULTS MAP ----
2019-01-06 22:10:29,588 (7f81beffd700) :  INFO (__init__:439) - {}
2019-01-06 22:10:29,589 (7f81beffd700) :  INFO (__init__:138) - checking title/year search vector: https://meta.plex.tv/movie/guid/9d/9df620ce8a227da663e64753ced2dcbe14580f52.xml
2019-01-06 22:10:29,595 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/guid/9d/9df620ce8a227da663e64753ced2dcbe14580f52.xml'
2019-01-06 22:10:29,721 (7f81beffd700) :  INFO (__init__:169) - Scoring {'tt0076752': [0, u'The Spy Who Loved Me', u'1977', 99, 101449, 0]}
2019-01-06 22:10:29,721 (7f81beffd700) :  INFO (__init__:464) - ---- TITLE_YEAR RESULTS MAP ----
2019-01-06 22:10:29,721 (7f81beffd700) :  INFO (__init__:465) - {'tt0076752': [0, u'The Spy Who Loved Me', u'1977', 99, 101449, 99]}
2019-01-06 22:10:29,727 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0076752%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D'
2019-01-06 22:10:31,272 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0071807%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Den'
2019-01-06 22:10:31,867 (7f81beffd700) :  INFO (__init__:473) - Adding title_year match: The Spy Who Loved Me (1977) score=99, key=tt0076752
2019-01-06 22:10:31,868 (7f81beffd700) :  INFO (__init__:481) - Found perfect match with title/year query.
2019-01-06 22:10:31,870 (7f81beffd700) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2019-01-06 22:10:31,870 (7f81beffd700) :  DEBUG (runtime:924) - Response: [200] str, 652 bytes
2019-01-06 22:10:31,925 (7f81beffd700) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQoxMDIyMnM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0MDA3Njc1Mj9sYW5nPWVuczQKZ3VpZHM5CnR0MDA3Njc1MnMyCmlkcjAK
2019-01-06 22:10:31,927 (7f81beffd700) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQoxMDIyMnM0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0MDA3Njc1Mj9sYW5nPWVuczQKZ3VpZHM5CnR0MDA3Njc1MnMyCmlkcjAK
2019-01-06 22:10:31,928 (7f81beffd700) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.imdb://tt0076752?lang=en
2019-01-06 22:10:31,928 (7f81beffd700) :  ERROR (model:205) - Cannot read model from /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Metadata/Movies/e/c27485a3a4e41b55a6ff54b1310e2e3a0ae113a.bundle/Contents/com.plexapp.agents.imdb
2019-01-06 22:10:31,929 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/10222/tree'
2019-01-06 22:10:31,943 (7f81beffd700) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fconfiguration' from the HTTP cache
2019-01-06 22:10:31,953 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0076752%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Den'
2019-01-06 22:10:33,424 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0071807%2Fimages'
2019-01-06 22:10:34,127 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0076752%2Fimages'
2019-01-06 22:10:35,573 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2F682%2Frecommendations'
2019-01-06 22:10:36,288 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2F691%2Frecommendations'
2019-01-06 22:10:37,748 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/tfpZyJD6q7N5NMmaICV2DvjToLx.jpg'
2019-01-06 22:10:37,897 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/tfpZyJD6q7N5NMmaICV2DvjToLx.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:37,902 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/aCtdGumQjq58G2VVvsbgVJfniZQ.jpg'
2019-01-06 22:10:37,966 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/aCtdGumQjq58G2VVvsbgVJfniZQ.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:37,971 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/f6HNlsfahFWcYC3VH5sQsMrOL72.jpg'
2019-01-06 22:10:38,047 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/f6HNlsfahFWcYC3VH5sQsMrOL72.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,052 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/wTGUhXPuudh1kAVGL1CNwPJLGIE.jpg'
2019-01-06 22:10:38,110 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/wTGUhXPuudh1kAVGL1CNwPJLGIE.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,114 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/nMeKBOMraW5eG4h3QpksudvWlso.jpg'
2019-01-06 22:10:38,167 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/nMeKBOMraW5eG4h3QpksudvWlso.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,171 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/tSDguOZJ1ZUBORb6p8WyPSvdOTs.jpg'
2019-01-06 22:10:38,230 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/tSDguOZJ1ZUBORb6p8WyPSvdOTs.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,234 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/913sS08HhcTQTwz7X567iR6DLHn.jpg'
2019-01-06 22:10:38,285 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/913sS08HhcTQTwz7X567iR6DLHn.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,305 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/8QO3g9iMAeGQWduEeNwjxoGJqGc.jpg'
2019-01-06 22:10:38,361 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/8QO3g9iMAeGQWduEeNwjxoGJqGc.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,366 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/bNFWmstAAwKrIZBDLbHBobZT8Ve.jpg'
2019-01-06 22:10:38,441 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/a83YQdk8ojglYD3eZ4n2rudyOCo.jpg'
2019-01-06 22:10:38,445 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/bNFWmstAAwKrIZBDLbHBobZT8Ve.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,448 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/r1fzFjFXjilJxkz8rOgIJQ1dxJO.jpg'
2019-01-06 22:10:38,495 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/a83YQdk8ojglYD3eZ4n2rudyOCo.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,500 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/7HgOmNRNGl1pGm4PYhp7FImzT60.jpg'
2019-01-06 22:10:38,505 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/r1fzFjFXjilJxkz8rOgIJQ1dxJO.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,510 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/mariuikhetBWmrEQuW6wmYv5EnX.jpg'
2019-01-06 22:10:38,557 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/7HgOmNRNGl1pGm4PYhp7FImzT60.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,561 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/a3SfPvjanp5WCAoOr4SuHkJv3c5.jpg'
2019-01-06 22:10:38,575 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/mariuikhetBWmrEQuW6wmYv5EnX.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,579 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/snhOfi6hHytfADUSWecZcT3Y0tr.jpg'
2019-01-06 22:10:38,631 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/snhOfi6hHytfADUSWecZcT3Y0tr.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,636 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/omJKCjrAzP2N4vfDJSsERu1BLWa.jpg'
2019-01-06 22:10:38,640 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/a3SfPvjanp5WCAoOr4SuHkJv3c5.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,644 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/t6Ns2pBaV2umEw6YY59B0voa8iN.jpg'
2019-01-06 22:10:38,692 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/omJKCjrAzP2N4vfDJSsERu1BLWa.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,697 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/1pXoJ04Cn8l1E3frewTZJPsxC9G.jpg'
2019-01-06 22:10:38,704 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/t6Ns2pBaV2umEw6YY59B0voa8iN.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,708 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/491RGUn1Csz25mkF1VsBmjRfFv3.jpg'
2019-01-06 22:10:38,759 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/491RGUn1Csz25mkF1VsBmjRfFv3.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,761 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/1pXoJ04Cn8l1E3frewTZJPsxC9G.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,765 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/eBi0PQrdLrOclMgj65gtsQsIIaB.jpg'
2019-01-06 22:10:38,769 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/nB1SIWsUr4Db7nMU7WOWY9VyHkB.jpg'
2019-01-06 22:10:38,822 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/eBi0PQrdLrOclMgj65gtsQsIIaB.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,830 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/6oMeGy3xGN46IUAy4IfKlYvtvIA.jpg'
2019-01-06 22:10:38,845 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/nB1SIWsUr4Db7nMU7WOWY9VyHkB.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,849 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/6zY959JOLzky5HsBZ5RUnviuJpU.jpg'
2019-01-06 22:10:38,886 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/6oMeGy3xGN46IUAy4IfKlYvtvIA.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,890 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/q9GwXfgJDF6fC1HTOWpISnfR2U9.jpg'
2019-01-06 22:10:38,906 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/6zY959JOLzky5HsBZ5RUnviuJpU.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,911 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/pBbVKbkLAcEGksxttzbW0oXZh95.jpg'
2019-01-06 22:10:38,949 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/q9GwXfgJDF6fC1HTOWpISnfR2U9.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,955 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/gJUrdtDKzELYNlcCwDAF02e6rMf.jpg'
2019-01-06 22:10:38,969 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/pBbVKbkLAcEGksxttzbW0oXZh95.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:38,975 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/uZgPBBdKpJgLZZkswddRrIlwlw4.jpg'
2019-01-06 22:10:39,014 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/gJUrdtDKzELYNlcCwDAF02e6rMf.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,019 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/2tCAEhE2rPONBmUOs4W73AlBWYg.jpg'
2019-01-06 22:10:39,027 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/uZgPBBdKpJgLZZkswddRrIlwlw4.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,030 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/5kO76cFDQDsMw1OoUf6ZREohXtP.jpg'
2019-01-06 22:10:39,074 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/2tCAEhE2rPONBmUOs4W73AlBWYg.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,079 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/aAoId3S7rK2dsOCCcXdCUG0VN6P.jpg'
2019-01-06 22:10:39,085 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/5kO76cFDQDsMw1OoUf6ZREohXtP.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,091 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/7VSVgxAvA7IdTf8T6Oon1eaReeJ.jpg'
2019-01-06 22:10:39,138 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/aAoId3S7rK2dsOCCcXdCUG0VN6P.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,146 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/eUEn3OJ2em2OSDZ4TAG1mAgmIJx.jpg'
2019-01-06 22:10:39,151 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/7VSVgxAvA7IdTf8T6Oon1eaReeJ.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,155 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/1gZdpJ9FD7CdCUWycN0FrN8dAK8.jpg'
2019-01-06 22:10:39,206 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/eUEn3OJ2em2OSDZ4TAG1mAgmIJx.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,213 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/fR4avrKFvsSczqAkyuOck6YUSCE.jpg'
2019-01-06 22:10:39,217 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/1gZdpJ9FD7CdCUWycN0FrN8dAK8.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,221 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/nK6YdjM416IhAX5UW6fyWsFQJe0.jpg'
2019-01-06 22:10:39,268 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/fR4avrKFvsSczqAkyuOck6YUSCE.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,272 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/2uBDOtfqUQywWC5xC5egTE0VRYH.jpg'
2019-01-06 22:10:39,278 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/nK6YdjM416IhAX5UW6fyWsFQJe0.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,284 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/xih1wnaslYu8lGBnuNbzHiKV3YJ.jpg'
2019-01-06 22:10:39,342 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/xih1wnaslYu8lGBnuNbzHiKV3YJ.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,349 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/15yHKwmhaRVG21Z3fkv0nSMH3mo.jpg'
2019-01-06 22:10:39,379 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/2uBDOtfqUQywWC5xC5egTE0VRYH.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,385 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/e1kzq9XDILk772837UXqb9w4yX3.jpg'
2019-01-06 22:10:39,406 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/15yHKwmhaRVG21Z3fkv0nSMH3mo.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,412 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/qvuHNTIdg4ZExAQGiRsutZF5SPu.jpg'
2019-01-06 22:10:39,436 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/e1kzq9XDILk772837UXqb9w4yX3.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,443 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/vcsXmcm53WZ3qvR9pwefkvSgrLj.jpg'
2019-01-06 22:10:39,492 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/qvuHNTIdg4ZExAQGiRsutZF5SPu.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,497 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/xbxDWCFV8Vn03RAUNRclDEjBnac.jpg'
2019-01-06 22:10:39,502 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/vcsXmcm53WZ3qvR9pwefkvSgrLj.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,508 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/wgMIIpAh6fbIopA4kCMxW7kNDnm.jpg'
2019-01-06 22:10:39,549 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/xbxDWCFV8Vn03RAUNRclDEjBnac.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,555 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/lugQS86IyozZByojOjnNLi3yvWz.jpg'
2019-01-06 22:10:39,564 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/wgMIIpAh6fbIopA4kCMxW7kNDnm.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,570 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/x5i18vhVMUnUf6Dc3eSAQ4WSglk.jpg'
2019-01-06 22:10:39,634 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/lugQS86IyozZByojOjnNLi3yvWz.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,639 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/x5i18vhVMUnUf6Dc3eSAQ4WSglk.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,640 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/3qlXj3S5wLbItcXbfeFwjYlGXaG.jpg'
2019-01-06 22:10:39,645 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/rVObFFMPzKvmbCJqmJKlQBY5sfE.jpg'
2019-01-06 22:10:39,696 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/3qlXj3S5wLbItcXbfeFwjYlGXaG.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,712 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/zkQREb2bPaCbCMew2pJv1tAAaa2.jpg'
2019-01-06 22:10:39,713 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/rVObFFMPzKvmbCJqmJKlQBY5sfE.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,720 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/lslUBcQ5H8Ub4YFbQKJyFxgR9zX.jpg'
2019-01-06 22:10:39,766 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/zkQREb2bPaCbCMew2pJv1tAAaa2.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,788 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/lslUBcQ5H8Ub4YFbQKJyFxgR9zX.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,891 (7f81bffff700) :  DEBUG (caching:268) - HTTP.system cache trimmed - new size is 2113 KB (1024 items)
2019-01-06 22:10:39,898 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/1ZYQc5XwaUO4JqDNKk2aMW0cJru.jpg'
2019-01-06 22:10:39,904 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/cKXa50UF6tnBTJqfoz7w7cDAlsK.jpg'
2019-01-06 22:10:39,962 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/1ZYQc5XwaUO4JqDNKk2aMW0cJru.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:39,967 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/n7ppys5qeUh0gGor0YLWbKUbIdT.jpg'
2019-01-06 22:10:40,023 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/cKXa50UF6tnBTJqfoz7w7cDAlsK.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:40,028 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/pWs4dTQ9FKeiMigwoR2wdwMqjUa.jpg'
2019-01-06 22:10:40,033 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/n7ppys5qeUh0gGor0YLWbKUbIdT.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:40,038 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/bVzQnVbpqpol4N8d3GzCCSgXtAl.jpg'
2019-01-06 22:10:40,096 (7f81bffff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/bVzQnVbpqpol4N8d3GzCCSgXtAl.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:40,109 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/m/0071807?lang=en&ratings=1&reviews=1&extras=1'
2019-01-06 22:10:40,113 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/pWs4dTQ9FKeiMigwoR2wdwMqjUa.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:40,117 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/rkGTd9XV6gay2OQOsaDATbztKSj.jpg'
2019-01-06 22:10:40,174 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/rkGTd9XV6gay2OQOsaDATbztKSj.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:40,179 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/m0hucUuFwAXtX2jarqnNeQfGo08.jpg'
2019-01-06 22:10:40,217 (7f81bffff700) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0071807%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D' from the HTTP cache
2019-01-06 22:10:40,237 (7f81bffff700) :  INFO (__init__:805) - Added 1 of 2 extras.
2019-01-06 22:10:40,246 (7f81bffff700) :  DEBUG (networking:166) - Requesting 'https://chapterdb.plex.tv/chapters/search?title=The%20Man%20with%20the%20Golden%20Gun'
2019-01-06 22:10:40,249 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/m0hucUuFwAXtX2jarqnNeQfGo08.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:40,253 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/vYMgDqQDpqHDhz3enGkwBmTs7p7.jpg'
2019-01-06 22:10:40,369 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/vYMgDqQDpqHDhz3enGkwBmTs7p7.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:40,373 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/yrPgfJYz5nelM27Bbqh5Ui9rtYT.jpg'
2019-01-06 22:10:40,425 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/yrPgfJYz5nelM27Bbqh5Ui9rtYT.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:40,429 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/mrBhKM6NVdWWQakGxSbs8JWqPDE.jpg'
2019-01-06 22:10:40,482 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/mrBhKM6NVdWWQakGxSbs8JWqPDE.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:40,487 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/zT57Gi2Fe6XrtbTgqNBtKqFlDkO.jpg'
2019-01-06 22:10:40,546 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/zT57Gi2Fe6XrtbTgqNBtKqFlDkO.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:40,551 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/wCtVIs48jA82f66w2OOq36FmFFY.jpg'
2019-01-06 22:10:40,631 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/wCtVIs48jA82f66w2OOq36FmFFY.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:40,637 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/vGc2mTmHQXRvoGHcIXkxHwiT7JD.jpg'
2019-01-06 22:10:40,674 (7f81bffff700) :  ERROR (networking:219) - Error opening URL 'https://chapterdb.plex.tv/chapters/search?title=The%20Man%20with%20the%20Golden%20Gun'
2019-01-06 22:10:40,675 (7f81bffff700) :  INFO (__init__:876) - Error obtaining Plex movie Chapter data for 0071807: HTTP Error 401: Unauthorized
2019-01-06 22:10:40,709 (7f81bffff700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(1920)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=205&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2019-01-06 22:10:40,710 (7f81bffff700) :  DEBUG (services:41) - Loading service code for IVA (URLServiceRecord)
2019-01-06 22:10:40,719 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/vGc2mTmHQXRvoGHcIXkxHwiT7JD.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:40,726 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/t3LzD4cntRTfGJYtD01jXxaMnd9.jpg'
2019-01-06 22:10:40,762 (7f81bffff700) :  DEBUG (services:41) - Loading service code for Fallback (URLServiceRecord)
2019-01-06 22:10:40,829 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/t3LzD4cntRTfGJYtD01jXxaMnd9.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:40,866 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/vNve78JQN1yMOW8FyLq33m6BzFh.jpg'
2019-01-06 22:10:40,982 (7f81beffd700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/vNve78JQN1yMOW8FyLq33m6BzFh.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-01-06 22:10:41,002 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/m/0076752?lang=en&ratings=1&reviews=1&extras=1'
2019-01-06 22:10:41,023 (7f81bffff700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(1920)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=205&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2019-01-06 22:10:41,026 (7f81bffff700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(1920)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=205&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2019-01-06 22:10:41,034 (7f81bffff700) :  DEBUG (model:229) - Serializing to /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Metadata/Movies/4/6c3fb3c291726bc0a9cc9ff4c6a75a4553dc1bb.bundle/Contents/com.plexapp.agents.imdb/Info.xml
2019-01-06 22:10:41,036 (7f81bffff700) :  DEBUG (runtime:88) - Sending packed state data (1056 bytes)
2019-01-06 22:10:41,037 (7f81bffff700) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
2019-01-06 22:10:41,115 (7f81beffd700) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0076752%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D' from the HTTP cache
2019-01-06 22:10:41,126 (7f81beffd700) :  INFO (__init__:805) - Added 1 of 3 extras.
2019-01-06 22:10:41,131 (7f81beffd700) :  DEBUG (networking:166) - Requesting 'https://chapterdb.plex.tv/chapters/search?title=The%20Spy%20Who%20Loved%20Me'
2019-01-06 22:10:41,454 (7f81beffd700) :  ERROR (networking:219) - Error opening URL 'https://chapterdb.plex.tv/chapters/search?title=The%20Spy%20Who%20Loved%20Me'
2019-01-06 22:10:41,454 (7f81beffd700) :  INFO (__init__:876) - Error obtaining Plex movie Chapter data for 0076752: HTTP Error 401: Unauthorized
2019-01-06 22:10:41,479 (7f81beffd700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(1921)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=199&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2019-01-06 22:10:41,483 (7f81beffd700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(1921)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=199&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2019-01-06 22:10:41,486 (7f81beffd700) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(1921)?lang=en&bitrates=80,212,450,750,1500,2500,5000,8000&duration=199&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2019-01-06 22:10:41,490 (7f81beffd700) :  DEBUG (model:229) - Serializing to /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Metadata/Movies/e/c27485a3a4e41b55a6ff54b1310e2e3a0ae113a.bundle/Contents/com.plexapp.agents.imdb/Info.xml
2019-01-06 22:10:41,492 (7f81beffd700) :  DEBUG (runtime:88) - Sending packed state data (1056 bytes)
2019-01-06 22:10:41,492 (7f81beffd700) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
