2019-03-05 00:36:22,604 (2578) :  INFO (core:349) - Starting framework core - Version: 2.6.3, Build: cc260c4 (Fri Dec 14 11:21:01 UTC 2018)
2019-03-05 00:36:22,604 (2578) :  DEBUG (core:361) - Using the elevated policy
2019-03-05 00:36:22,605 (2578) :  DEBUG (core:450) - Starting runtime component.
2019-03-05 00:36:22,605 (2578) :  DEBUG (core:450) - Starting caching component.
2019-03-05 00:36:22,607 (2578) :  DEBUG (core:450) - Starting data component.
2019-03-05 00:36:22,607 (2578) :  DEBUG (core:450) - Starting networking component.
2019-03-05 00:36:22,611 (2578) :  DEBUG (networking:284) - Loaded HTTP cookies
2019-03-05 00:36:22,634 (2578) :  DEBUG (networking:452) - Setting the default network timeout to 20.0
2019-03-05 00:36:22,634 (2578) :  DEBUG (core:450) - Starting localization component.
2019-03-05 00:36:22,634 (2578) :  INFO (localization:409) - Setting the default locale to en-us
2019-03-05 00:36:22,635 (2578) :  DEBUG (core:450) - Starting messaging component.
2019-03-05 00:36:22,635 (2578) :  DEBUG (core:450) - Starting debugging component.
2019-03-05 00:36:22,635 (2578) :  DEBUG (core:450) - Starting services component.
2019-03-05 00:36:22,637 (2904) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/system/messaging/clear_events/com.plexapp.agents.imdb'
2019-03-05 00:36:22,637 (2578) :  DEBUG (core:450) - Starting myplex component.
2019-03-05 00:36:22,637 (2578) :  DEBUG (core:450) - Starting notifications component.
2019-03-05 00:36:22,746 (2578) :  DEBUG (accessor:68) - Creating a new model access point for provider com.plexapp.agents.imdb in namespace 'metadata'
2019-03-05 00:36:22,752 (2578) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/resourceHashes'
2019-03-05 00:36:22,829 (2578) :  DEBUG (runtime:1117) - Created a thread named 'load_all_services'
2019-03-05 00:36:22,829 (2128) :  DEBUG (services:265) - Plug-in is not daemonized - loading services from system
2019-03-05 00:36:22,832 (2578) :  DEBUG (runtime:1117) - Created a thread named 'get_server_info'
2019-03-05 00:36:22,832 (2128) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_'
2019-03-05 00:36:22,832 (2578) :  DEBUG (core:150) - Finished starting framework core
2019-03-05 00:36:22,832 (e70) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400'
2019-03-05 00:36:22,832 (2578) :  DEBUG (core:560) - Loading plug-in code
2019-03-05 00:36:22,838 (e70) :  DEBUG (core:538) - Machine identifier is 4fc6fcbb720dfeaf43f7e287a162df51bc136a6e
2019-03-05 00:36:22,838 (e70) :  DEBUG (core:539) - Server version is 1.14.1.5488-cc260c476
2019-03-05 00:36:23,078 (2128) :  DEBUG (services:362) - Loaded services
2019-03-05 00:36:23,085 (2f34) :  DEBUG (services:438) - No shared code to load
2019-03-05 00:36:23,391 (2578) :  DEBUG (core:566) - Finished loading plug-in code
2019-03-05 00:36:23,526 (2578) :  DEBUG (agentkit:1115) - Creating new agent class called PlexMovieAgent
2019-03-05 00:36:23,528 (2578) :  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-03-05 00:36:23,529 (2578) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_'
2019-03-05 00:36:23,539 (2578) :  INFO (core:611) - Started plug-in
2019-03-05 00:36:23,539 (2578) :  DEBUG (socketinterface:160) - Starting socket server
2019-03-05 00:36:23,540 (2578) :  DEBUG (runtime:1117) - Created a thread named 'start'
2019-03-05 00:36:23,540 (2578) :  INFO (socketinterface:184) - Socket server started on port 50950
2019-03-05 00:36:23,540 (2578) :  INFO (pipeinterface:25) - Entering run loop
2019-03-05 00:36:23,540 (2578) :  DEBUG (runtime:717) - Handling request GET /:/prefixes
2019-03-05 00:36:23,542 (2578) :  DEBUG (runtime:814) - Found route matching /:/prefixes
2019-03-05 00:36:23,542 (2578) :  DEBUG (runtime:924) - Response: [200] MediaContainer, 148 bytes
2019-03-05 00:36:23,568 (19d4) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU4CnMxNgo5MzdlNDcxZjZlMWI2MDUyczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczIxClRoZSBDaWRlciBIb3VzZSBSdWxlc3M0Cm5hbWVzNDAKN2U0ZWMwMjdjZTE3MTE3ZDg5MjA4ZGRiZjBmYmU2ZmFhMWZjM2VkMHM4CnBsZXhIYXNoczEwNwpWJTNBJTVDUmVjb3JkZWQlMjBNb3ZpZXMlNUNUaGUlMjBDaWRlciUyMEhvdXNlJTIwUnVsZXMlMjAoMTk5OSklNUNUaGUlMjBDaWRlciUyMEhvdXNlJTIwUnVsZXMlMjAoMTk5OSklMkV0c3M4CmZpbGVuYW1lczgKdGVsZXN5bmNzNgpzb3VyY2VzNAoxOTk5czQKeWVhcnMyCi0xczgKZHVyYXRpb25zNQoyODM5N3MyCmlkcjAK
2019-03-05 00:36:23,568 (19d4) :  DEBUG (runtime:49) - Received packed state data (556 bytes)
2019-03-05 00:36:23,578 (19d4) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlNlYXJjaA__/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoyCmRpY3QKZGljdAo2CnMyCmVuczQKbGFuZ2IwczYKbWFudWFsYjFzNwpwcmltYXJ5aTAKczcKdmVyc2lvbnIxCnM2Cmt3YXJnc3M1Ck1vdmllczEwCm1lZGlhX3R5cGU4CnMxNgo5MzdlNDcxZjZlMWI2MDUyczE3Cm9wZW5TdWJ0aXRsZXNIYXNoczIxClRoZSBDaWRlciBIb3VzZSBSdWxlc3M0Cm5hbWVzNDAKN2U0ZWMwMjdjZTE3MTE3ZDg5MjA4ZGRiZjBmYmU2ZmFhMWZjM2VkMHM4CnBsZXhIYXNoczEwNwpWJTNBJTVDUmVjb3JkZWQlMjBNb3ZpZXMlNUNUaGUlMjBDaWRlciUyMEhvdXNlJTIwUnVsZXMlMjAoMTk5OSklNUNUaGUlMjBDaWRlciUyMEhvdXNlJTIwUnVsZXMlMjAoMTk5OSklMkV0c3M4CmZpbGVuYW1lczgKdGVsZXN5bmNzNgpzb3VyY2VzNAoxOTk5czQKeWVhcnMyCi0xczgKZHVyYXRpb25zNQoyODM5N3MyCmlkcjAK
2019-03-05 00:36:23,578 (19d4) :  INFO (agentkit:957) - Searching for matches for {'openSubtitlesHash': '937e471f6e1b6052', 'name': 'The Cider House Rules', 'plexHash': '7e4ec027ce17117d89208ddbf0fbe6faa1fc3ed0', 'filename': 'V%3A%5CRecorded%20Movies%5CThe%20Cider%20House%20Rules%20(1999)%5CThe%20Cider%20House%20Rules%20(1999)%2Ets', 'source': 'telesync', 'year': '1999', 'duration': '-1', 'id': '28397'}
2019-03-05 00:36:23,579 (19d4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/28397/tree'
2019-03-05 00:36:23,586 (19d4) :  INFO (logkit:16) - checking hash search vector: https://meta.plex.tv/movie/hash/7e/7e4ec027ce17117d89208ddbf0fbe6faa1fc3ed0.xml
2019-03-05 00:36:23,609 (19d4) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/hash/7e/7e4ec027ce17117d89208ddbf0fbe6faa1fc3ed0.xml'
2019-03-05 00:36:23,951 (19d4) :  ERROR (networking:219) - Error opening URL 'https://meta.plex.tv/movie/hash/7e/7e4ec027ce17117d89208ddbf0fbe6faa1fc3ed0.xml'
2019-03-05 00:36:23,951 (19d4) :  INFO (logkit:16) - freebase/proxy hash lookup failed: HTTP Error 404: Not Found
2019-03-05 00:36:23,953 (19d4) :  INFO (logkit:16) - Scoring {}
2019-03-05 00:36:23,953 (19d4) :  INFO (logkit:16) - ---- HASH RESULTS MAP ----
2019-03-05 00:36:23,953 (19d4) :  INFO (logkit:16) - {}
2019-03-05 00:36:23,953 (19d4) :  INFO (logkit:16) - checking title/year search vector: https://meta.plex.tv/movie/guid/ab/ab760bcdd2963028971f7bc5f2041ac248f5b527.xml
2019-03-05 00:36:23,980 (19d4) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/movie/guid/ab/ab760bcdd2963028971f7bc5f2041ac248f5b527.xml'
2019-03-05 00:36:24,095 (19d4) :  INFO (logkit:16) - Scoring {'tt0124315': [0, u'The Cider House Rules', u'1999', 100, 21154, 0]}
2019-03-05 00:36:24,096 (19d4) :  INFO (logkit:16) - ---- TITLE_YEAR RESULTS MAP ----
2019-03-05 00:36:24,096 (19d4) :  INFO (logkit:16) - {'tt0124315': [0, u'The Cider House Rules', u'1999', 100, 21154, 100]}
2019-03-05 00:36:24,101 (19d4) :  DEBUG (preferences:258) - Loaded preferences from DefaultPrefs.json
2019-03-05 00:36:24,102 (19d4) :  DEBUG (preferences:178) - Loaded the user preferences for com.plexapp.agents.imdb
2019-03-05 00:36:24,124 (19d4) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0124315%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D'
2019-03-05 00:36:26,295 (19d4) :  INFO (logkit:16) - Adding title_year match: The Cider House Rules (1999) score=100, key=tt0124315
2019-03-05 00:36:26,295 (19d4) :  INFO (logkit:16) - Found perfect match with title/year query.
2019-03-05 00:36:26,296 (19d4) :  DEBUG (runtime:88) - Sending packed state data (580 bytes)
2019-03-05 00:36:26,296 (19d4) :  DEBUG (runtime:924) - Response: [200] str, 652 bytes
2019-03-05 00:36:26,344 (1894) :  DEBUG (runtime:717) - Handling request GET /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQoyODM5N3M0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0MDEyNDMxNT9sYW5nPWVuczQKZ3VpZHM5CnR0MDEyNDMxNXMyCmlkcjAK
2019-03-05 00:36:26,345 (1894) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2019-03-05 00:36:26,345 (1894) :  DEBUG (runtime:814) - Found route matching /:/plugins/com.plexapp.agents.imdb/messaging/function/X0FnZW50S2l0OlVwZGF0ZU1ldGFkYXRh/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMTAKczIKZW5zNApsYW5nYjFzNQpmb3JjZWIwczgKcGVyaW9kaWNzNQoyODM5N3M0CmRiaWRpMApzNwp2ZXJzaW9ubnMxMApwYXJlbnRHVUlEbnM4CnBhcmVudElEczUKTW92aWVzMTAKbWVkaWFfdHlwZXM0Mwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjovL3R0MDEyNDMxNT9sYW5nPWVuczQKZ3VpZHM5CnR0MDEyNDMxNXMyCmlkcjAK
2019-03-05 00:36:26,345 (1894) :  DEBUG (model:32) - Loading model with GUID com.plexapp.agents.imdb://tt0124315?lang=en
2019-03-05 00:36:26,346 (1894) :  ERROR (model:205) - Cannot read model from C:\Users\Stephen\AppData\Local\Plex Media Server\Metadata\Movies\0\6852d7687a64c9d971d3c5a778c83c6419718e4.bundle\Contents\com.plexapp.agents.imdb
2019-03-05 00:36:26,348 (1894) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/library/metadata/28397/tree'
2019-03-05 00:36:26,410 (1894) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fconfiguration' from the HTTP cache
2019-03-05 00:36:26,476 (1894) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0124315%3Fappend_to_response%3Dreleases%2Ccredits%26language%3Den'
2019-03-05 00:36:28,663 (1894) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0124315%2Fimages'
2019-03-05 00:36:30,822 (1894) :  DEBUG (networking:166) - Requesting 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2F1715%2Frecommendations'
2019-03-05 00:36:33,009 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/1mE2YGXmAnqZuqIYFAusAlKebaP.jpg'
2019-03-05 00:36:33,072 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/1mE2YGXmAnqZuqIYFAusAlKebaP.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:33,092 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/9siylhSx8gB9hUnatHOdxqsXkjh.jpg'
2019-03-05 00:36:33,128 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/9siylhSx8gB9hUnatHOdxqsXkjh.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:33,160 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/y2zeVqgDEUVg0oUTLKtBvWqPv79.jpg'
2019-03-05 00:36:33,193 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/y2zeVqgDEUVg0oUTLKtBvWqPv79.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:33,214 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/oAN99GKChax969j6Gpt0iAZeMGj.jpg'
2019-03-05 00:36:33,269 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/oAN99GKChax969j6Gpt0iAZeMGj.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:33,292 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/rCwN7Hy75aMPPZhv37mchDy5Ftz.jpg'
2019-03-05 00:36:33,332 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/rCwN7Hy75aMPPZhv37mchDy5Ftz.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:33,355 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/bTyOP3kqN6a8m9lGh7NCruAnz5p.jpg'
2019-03-05 00:36:33,394 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/bTyOP3kqN6a8m9lGh7NCruAnz5p.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:33,420 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/7VZwr0liSPvDfWy7iLnMXu7jxmU.jpg'
2019-03-05 00:36:33,459 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/7VZwr0liSPvDfWy7iLnMXu7jxmU.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:33,480 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/9fh5A56lxAjuFNCqyM2vgLWikyc.jpg'
2019-03-05 00:36:33,523 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/9fh5A56lxAjuFNCqyM2vgLWikyc.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:33,545 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/qZx724n671r7aKaOvHEL2xt660.jpg'
2019-03-05 00:36:33,582 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/qZx724n671r7aKaOvHEL2xt660.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:33,605 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/rVo9dU39uXgV9MHKT3eAdz47s8.jpg'
2019-03-05 00:36:33,651 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/rVo9dU39uXgV9MHKT3eAdz47s8.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:33,673 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/g30w6nYbqEOkzuLuesmnKbGuLr7.jpg'
2019-03-05 00:36:33,726 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/g30w6nYbqEOkzuLuesmnKbGuLr7.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:33,753 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/ePPksw0mYQs5mVp7rIO7MyPwykY.jpg'
2019-03-05 00:36:33,789 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/ePPksw0mYQs5mVp7rIO7MyPwykY.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:33,811 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/cLTvmKDs57wt9ujab7BDuiCCVQF.jpg'
2019-03-05 00:36:33,846 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/cLTvmKDs57wt9ujab7BDuiCCVQF.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:33,868 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/pHYnuMlFXmUMaIuCdYcBNg31FZe.jpg'
2019-03-05 00:36:33,905 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/pHYnuMlFXmUMaIuCdYcBNg31FZe.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:33,927 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/xuP4XGIf9vbJqXJOFsxGDuLF4sE.jpg'
2019-03-05 00:36:33,960 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/xuP4XGIf9vbJqXJOFsxGDuLF4sE.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:34,095 (1894) :  DEBUG (caching:268) - HTTP.system cache trimmed - new size is 1583 KB (1024 items)
2019-03-05 00:36:34,132 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/vAWQenCtBlzICEi9PGY3nz8CWRJ.jpg'
2019-03-05 00:36:34,174 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/vAWQenCtBlzICEi9PGY3nz8CWRJ.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:34,194 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/57efIJGj1GJbejxx96aemuoaYb2.jpg'
2019-03-05 00:36:34,226 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/57efIJGj1GJbejxx96aemuoaYb2.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:34,252 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/tNPcsnfoaK294z1BxamPoYR5hLC.jpg'
2019-03-05 00:36:34,283 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/tNPcsnfoaK294z1BxamPoYR5hLC.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:34,303 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/lgRNj2qa6EWPqJQmtejkGKBZkvm.jpg'
2019-03-05 00:36:34,338 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/lgRNj2qa6EWPqJQmtejkGKBZkvm.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:34,358 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/oqlNwlLHoDgHWssMnBVB1rmykUc.jpg'
2019-03-05 00:36:34,401 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/oqlNwlLHoDgHWssMnBVB1rmykUc.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:34,423 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/iweWVvPLr6HLj6pgLVyTzselPB4.jpg'
2019-03-05 00:36:34,460 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/iweWVvPLr6HLj6pgLVyTzselPB4.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:34,480 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/AFYyLibAkcjq17K4mxojesPwRV.jpg'
2019-03-05 00:36:34,516 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/AFYyLibAkcjq17K4mxojesPwRV.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:34,536 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/lTwX0XBR9BmVivMLNZ0gGx9I6Vi.jpg'
2019-03-05 00:36:34,569 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/lTwX0XBR9BmVivMLNZ0gGx9I6Vi.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:34,589 (1894) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w154/durZ70KuYfuswljC6AfyDQHiZEp.jpg'
2019-03-05 00:36:34,632 (1894) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w154/durZ70KuYfuswljC6AfyDQHiZEp.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)
2019-03-05 00:36:34,638 (1894) :  DEBUG (networking:166) - Requesting 'https://meta.plex.tv/m/0124315?lang=en&ratings=1&reviews=1&extras=1'
2019-03-05 00:36:34,760 (1894) :  DEBUG (networking:161) - Fetching 'http://127.0.0.1:32400/services/tmdb?uri=%2Fmovie%2Ftt0124315%3Fappend_to_response%3Dreleases%2Ccredits%26language%3D' from the HTTP cache
2019-03-05 00:36:34,809 (1894) :  INFO (logkit:16) - Added 1 of 2 extras.
2019-03-05 00:36:34,836 (1894) :  DEBUG (networking:166) - Requesting 'https://chapterdb.plex.tv/chapters/search?title=The%20Cider%20House%20Rules'
2019-03-05 00:36:34,946 (1894) :  ERROR (networking:219) - Error opening URL 'https://chapterdb.plex.tv/chapters/search?title=The%20Cider%20House%20Rules'
2019-03-05 00:36:34,947 (1894) :  INFO (logkit:16) - Error obtaining Plex movie Chapter data for 0124315: HTTP Error 404: Site Not Found
2019-03-05 00:36:35,111 (1894) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(979040)?lang=en&bitrates=80,212,450,750,1500,8000&duration=147&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2019-03-05 00:36:35,111 (1894) :  DEBUG (services:41) - Loading service code for IVA (URLServiceRecord)
2019-03-05 00:36:35,140 (1894) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2019-03-05 00:36:35,140 (1894) :  DEBUG (services:41) - Loading service code for Fallback (URLServiceRecord)
2019-03-05 00:36:35,242 (1894) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2019-03-05 00:36:35,243 (1894) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2019-03-05 00:36:35,244 (1894) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(979040)?lang=en&bitrates=80,212,450,750,1500,8000&duration=147&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2019-03-05 00:36:35,244 (1894) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2019-03-05 00:36:35,246 (1894) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2019-03-05 00:36:35,246 (1894) :  DEBUG (services:616) - Found a service matching 'iva://api.internetvideoarchive.com/2.0/DataService/VideoAssets(979040)?lang=en&bitrates=80,212,450,750,1500,8000&duration=147&adaptive=1&dts=0' - IVA (com.plexapp.agents.imdb)
2019-03-05 00:36:35,247 (1894) :  DEBUG (runtime:49) - Received packed state data (1032 bytes)
2019-03-05 00:36:35,253 (1894) :  DEBUG (model:229) - Serializing to C:\Users\Stephen\AppData\Local\Plex Media Server\Metadata\Movies\0\6852d7687a64c9d971d3c5a778c83c6419718e4.bundle\Contents\com.plexapp.agents.imdb\Info.xml
2019-03-05 00:36:35,255 (1894) :  DEBUG (runtime:88) - Sending packed state data (1056 bytes)
2019-03-05 00:36:35,255 (1894) :  DEBUG (runtime:924) - Response: [200] str, 16 bytes
